I have been trying different phones and soft phones with SRTP optional but I have issue getting them to work.
Hardphone that has problem are Fanvil phones. Softphone will be MicroSIP. Both of them when setting SRTP has optional, they will use a=crypto in SDP with RTP/AVP. When calling from the phone/softphone to a conference center I created, the call will just drop after connected.
I have set NDLB-allow-crypto-in-avp in SIP profile as well as in vars. I have even tried auto-rtp-bugs=clear in SIP profile but none of these helps. It also has problem calling other phones/softphone using the rfc3711 like linphone.
One way is to set the phone using SRTP compulsory but there is another problem. If we are using the Call Center module, calls go into the Call Center queue will be transferred out in RTP only (unless we can configure it to use SRTP?) and it will not reach the phone with SRTP compulsory.
I have the log below (call from phone 135 to 777 conference room). Anyone has any clue?
Hardphone that has problem are Fanvil phones. Softphone will be MicroSIP. Both of them when setting SRTP has optional, they will use a=crypto in SDP with RTP/AVP. When calling from the phone/softphone to a conference center I created, the call will just drop after connected.
I have set NDLB-allow-crypto-in-avp in SIP profile as well as in vars. I have even tried auto-rtp-bugs=clear in SIP profile but none of these helps. It also has problem calling other phones/softphone using the rfc3711 like linphone.
One way is to set the phone using SRTP compulsory but there is another problem. If we are using the Call Center module, calls go into the Call Center queue will be transferred out in RTP only (unless we can configure it to use SRTP?) and it will not reach the phone with SRTP compulsory.
I have the log below (call from phone 135 to 777 conference room). Anyone has any clue?
2020-06-11 10:53:05.115572 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [23d19c87-4086-460a-8478-b1cb34c17c42]
2020-06-11 10:53:05.115572 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Running State Change CS_NEW (Cur 1 Tot 24)
2020-06-11 10:53:05.115572 [DEBUG] sofia.c:10279 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 receiving invite from 115.66.245.196:1762 version: 1.10.3 -release-15-129de34d84 64bit
2020-06-11 10:53:05.115572 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 115.66.245.196:0.
2020-06-11 10:53:05.115572 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'internal' for [777@ibratro.talk.cloudplay.cloud] from ip 115.66.245.196
2020-06-11 10:53:05.115572 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State NEW
2020-06-11 10:53:05.115572 [DEBUG] sofia.c:2434 detaching session 23d19c87-4086-460a-8478-b1cb34c17c42
2020-06-11 10:53:05.215573 [DEBUG] sofia.c:2544 Re-attaching to session 23d19c87-4086-460a-8478-b1cb34c17c42
2020-06-11 10:53:05.235567 [DEBUG] sofia.c:10279 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 receiving invite from 115.66.245.196:1762 version: 1.10.3 -release-15-129de34d84 64bit
2020-06-11 10:53:05.235567 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 115.66.245.196:0.
2020-06-11 10:53:05.235567 [DEBUG] sofia.c:11476 Setting NAT mode based on via received
2020-06-11 10:53:05.235567 [DEBUG] sofia.c:7325 Channel sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 entering state [received][100]
2020-06-11 10:53:05.235567 [DEBUG] sofia.c:7335 Remote SDP:
v=0
o=sdp_admin 2659321466 1464618255 IN IP4 192.168.1.89
s=A conversation
c=IN IP4 192.168.1.89
t=0 0
m=audio 16516 RTP/AVP 9 18 8 0 2 4 101
a=rtpmap:9 G722/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=yes
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:4 G723/8000
a=fmtp:4 bitrate=6.3
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:5ngAAO55AABjegAAZGcAALYXAACBTwAAxhgAADBv
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AEAD_AES_256_GCM_8]alias=[] in [1 AES_CM_128_HMAC_SHA1_80 inline:5ngAAO55AABjegAAZGcAALYXAACBTwAAxhgAADBv]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AEAD_AES_128_GCM_8]alias=[] in [1 AES_CM_128_HMAC_SHA1_80 inline:5ngAAO55AABjegAAZGcAALYXAACBTwAAxhgAADBv]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AES_256_CM_HMAC_SHA1_80]alias=[AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:5ngAAO55AABjegAAZGcAALYXAACBTwAAxhgAADBv]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AES_192_CM_HMAC_SHA1_80]alias=[AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:5ngAAO55AABjegAAZGcAALYXAACBTwAAxhgAADBv]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AES_CM_128_HMAC_SHA1_80]alias=[] in [1 AES_CM_128_HMAC_SHA1_80 inline:5ngAAO55AABjegAAZGcAALYXAACBTwAAxhgAADBv]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:1822 Found suite AES_CM_128_HMAC_SHA1_80
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:1892 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:5ngAAO55AABjegAAZGcAALYXAACBTwAAxhgAADBv]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:1215 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:0MbBnnrt/RaMMuhHvFR+0WO8XrY0KpZFmUgKYJ9h]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G726-32:2:8000:20:0:1]/[G729:18:8000:20:8000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G726-32:2:8000:20:0:1]/[G722:9:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G723:4:8000:30:6300:1]/[G729:18:8000:20:8000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G723:4:8000:30:6300:1]/[G722:9:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G723:4:8000:30:6300:1]/[PCMA:8:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G723:4:8000:30:6300:1]/[PCMU:0:8000:20:64000:1]
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 G722/8000 20 ms 160 samples 64000 bits 1 channels
2020-06-11 10:53:05.235567 [DEBUG] switch_core_codec.c:111 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Original read codec set to G722:9
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
2020-06-11 10:53:05.235567 [DEBUG] switch_core_media.c:5909 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Set 2833 dtmf send payload to 101 recv payload to 101
2020-06-11 10:53:05.235567 [DEBUG] sofia.c:7759 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State Change CS_NEW -> CS_INIT
2020-06-11 10:53:05.235567 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Running State Change CS_INIT (Cur 1 Tot 24)
2020-06-11 10:53:05.235567 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State INIT
2020-06-11 10:53:05.235567 [DEBUG] mod_sofia.c:93 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 SOFIA INIT
2020-06-11 10:53:05.235567 [DEBUG] switch_core_state_machine.c:40 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Standard INIT
2020-06-11 10:53:05.235567 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State Change CS_INIT -> CS_ROUTING
2020-06-11 10:53:05.235567 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State INIT going to sleep
2020-06-11 10:53:05.235567 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Running State Change CS_ROUTING (Cur 1 Tot 24)
2020-06-11 10:53:05.235567 [DEBUG] switch_channel.c:2332 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Callstate Change DOWN -> RINGING
2020-06-11 10:53:05.235567 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State ROUTING
2020-06-11 10:53:05.235567 [DEBUG] mod_verto.c:5580 EVENT BROADCAST presence.135@ibratro.talk.cloudplay.cloud {
"data": {
"channelCallState": "RINGING",
"originalChannelCallState": "DOWN",
"channelState": "CS_ROUTING",
"callerUserName": "135",
"callerIDName": "CP 135",
"callerIDNumber": "135",
"channelUUID": "23d19c87-4086-460a-8478-b1cb34c17c42",
"presenceCallDirection": "inbound",
"channelPresenceID": "135@ibratro.talk.cloudplay.cloud"
},
"eventChannel": "presence.135@ibratro.talk.cloudplay.cloud"
}
2020-06-11 10:53:05.235567 [DEBUG] mod_sofia.c:154 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 SOFIA ROUTING
2020-06-11 10:53:05.235567 [DEBUG] switch_core_state_machine.c:236 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Standard ROUTING
2020-06-11 10:53:05.235567 [INFO] mod_dialplan_xml.c:637 Processing CP 135 <135>->777 in context ibratro.talk.cloudplay.cloud
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->user_exists] continue=true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(user_exists=false)
2020-06-11 10:53:05.255554 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [user_exists]=[false]
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(from_user_exists=true)
2020-06-11 10:53:05.255554 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [from_user_exists]=[true]
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->caller-details] continue=true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(caller_destination=${destination_number}) INLINE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(caller_destination=777)
2020-06-11 10:53:05.255554 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [caller_destination]=[777]
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(caller_id_name=135)
2020-06-11 10:53:05.255554 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [caller_id_name]=[135]
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(caller_id_number=${caller_id_number})
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->call-direction] continue=true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 ANTI-Action export(call_direction=local) INLINE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 export(call_direction=local)
2020-06-11 10:53:05.255554 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[local]
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->global-variables] continue=true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [global-variables] () =~ // break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(timezone=Asia/Singapore) INLINE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(timezone=Asia/Singapore)
2020-06-11 10:53:05.255554 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [timezone]=[Asia/Singapore]
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->domain-variables] continue=true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [domain-variables] () =~ // break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->is_loopback] continue=true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->user_record] continue=true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(from_user_record=)
2020-06-11 10:53:05.255554 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [from_user_record]=[UNDEF]
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->redial] continue=true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [redial] destination_number(777) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->speed_dial] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [speed_dial] destination_number(777) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->default_caller_id] continue=true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(emergency_caller_id_name=)
2020-06-11 10:53:05.255554 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [emergency_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(emergency_caller_id_number=)
2020-06-11 10:53:05.255554 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [emergency_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [default_caller_id] ${outbound_caller_id_number}(+6563728162) =~ /^$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->jessica.3d3] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [jessica.3d3] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [jessica.3d3] destination_number(777) =~ /^3(\d{3})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->twilio.800] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [twilio.800] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [twilio.800] destination_number(777) =~ /^1?(8(00|55|66|77|88)\d{7})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->twilio.+65d] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [twilio.+65d] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [twilio.+65d] destination_number(777) =~ /^\+65(\d*)$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->transfer.4d3] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [transfer.4d3] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [transfer.4d3] destination_number(777) =~ /^4(\d{3})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->murphy.9d8] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [murphy.9d8] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [murphy.9d8] destination_number(777) =~ /^9(\d{8})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->twilio.8d] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [twilio.8d] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [twilio.8d] destination_number(777) =~ /^(\d{8})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->twilio.+d] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [twilio.+d] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [twilio.+d] ${toll_allow}() =~ /international/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->murphy.2d3] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [murphy.2d3] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [murphy.2d3] destination_number(777) =~ /^2(\d{3})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->agent_status] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [agent_status] destination_number(777) =~ /^\*22$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [agent_status] destination_number(777) =~ /^(agent\+)(.*)$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->agent_status_id] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [agent_status_id] destination_number(777) =~ /^\*23$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->Q1] continue=
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [Q1] destination_number(777) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [Q1] destination_number(777) =~ /^111$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->group-intercept] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [group-intercept] destination_number(777) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->Main CC] continue=
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [Main CC] destination_number(777) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [Main CC] destination_number(777) =~ /^168$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->page-extension] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [page-extension] destination_number(777) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->eavesdrop] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [eavesdrop] destination_number(777) =~ /^\*33(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->call_privacy] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [call_privacy] destination_number(777) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->call_return] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [call_return] destination_number(777) =~ /^\*69$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->intercept-ext] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [intercept-ext] destination_number(777) =~ /^\*\*(\d+)$/ break=on-true
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [intercept-ext] destination_number(777) =~ /^\*\*$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->intercept-ext-polycom] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [intercept-ext-polycom] destination_number(777) =~ /^\*97(\d+)$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->extension_queue] continue=
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [extension_queue] destination_number(777) =~ /^\*800(.*)$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->dx] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [dx] destination_number(777) =~ /^dx$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->att_xfer] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [att_xfer] destination_number(777) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->extension-to-voicemail] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->send_to_voicemail] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [send_to_voicemail] destination_number(777) =~ /^\*99(\d{2,10})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->xfer_vm] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [xfer_vm] destination_number(777) =~ /^xfer_vm$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->vmain] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [vmain] destination_number(777) =~ /^vmain$|^\*4000$|^\*98$/ break=never
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [vmain] destination_number(777) =~ /^(vmain$|^\*4000$|^\*98)(\d{2,12})$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->vmain_user] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [vmain_user] destination_number(777) =~ /^\*97$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->is_transfer] continue=false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (FAIL) [is_transfer] destination_number(777) =~ /^is_transfer$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 parsing [ibratro.talk.cloudplay.cloud->Conference 1] continue=
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Regex (PASS) [Conference 1] destination_number(777) =~ /^777$/ break=on-false
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action answer()
Dialplan: sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Action conference(db0869dc-17ca-4841-9b7e-042a39dc85ae@ibratro.talk.cloudplay.cloud@default+flags{''})
2020-06-11 10:53:05.275555 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State Change CS_ROUTING -> CS_EXECUTE
2020-06-11 10:53:05.275555 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State ROUTING going to sleep
2020-06-11 10:53:05.275555 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Running State Change CS_EXECUTE (Cur 1 Tot 24)
2020-06-11 10:53:05.275555 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State EXECUTE
2020-06-11 10:53:05.275555 [DEBUG] mod_sofia.c:209 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 SOFIA EXECUTE
2020-06-11 10:53:05.275555 [DEBUG] switch_core_state_machine.c:329 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Standard EXECUTE
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(caller_id_number=135)
2020-06-11 10:53:05.275555 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [caller_id_number]=[135]
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 set(RFC2822_DATE=Thu, 11 Jun 2020 10:53:05 +0800)
2020-06-11 10:53:05.275555 [DEBUG] mod_dptools.c:1672 SET sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [RFC2822_DATE]=[Thu, 11 Jun 2020 10:53:05 +0800]
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 export(origination_callee_id_name=777)
2020-06-11 10:53:05.275555 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [origination_callee_id_name]=[777]
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 hash(insert/ibratro.talk.cloudplay.cloud-last_dial/135/777)
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 answer()
2020-06-11 10:53:05.275555 [DEBUG] switch_core_media.c:8661 AUDIO RTP [sofia/internal/135@ibratro.talk.cloudplay.cloud:5061] 165.22.48.55 port 32090 -> 192.168.1.89 port 16516 codec: 9 ms: 20
2020-06-11 10:53:05.275555 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 160 bytes per 20ms
2020-06-11 10:53:05.275555 [DEBUG] switch_core_media.c:8975 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Set 2833 dtmf send payload to 101
2020-06-11 10:53:05.275555 [DEBUG] switch_core_media.c:8982 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Set 2833 dtmf receive payload to 101
2020-06-11 10:53:05.275555 [DEBUG] switch_core_media.c:9005 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Set rtp dtmf delay to 40
2020-06-11 10:53:05.275555 [INFO] switch_rtp.c:4217 Activating audio Secure RTP SEND
2020-06-11 10:53:05.275555 [INFO] switch_rtp.c:4195 Activating audio Secure RTP RECV
2020-06-11 10:53:05.275555 [DEBUG] switch_core_sqldb.c:2836 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
2020-06-11 10:53:05.275555 [DEBUG] mod_sofia.c:898 Local SDP sofia/internal/135@ibratro.talk.cloudplay.cloud:5061:
v=0
o=FreeSWITCH 1591811895 1591811896 IN IP4 165.22.48.55
s=FreeSWITCH
c=IN IP4 165.22.48.55
t=0 0
m=audio 32090 RTP/SAVP 9 101
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:0MbBnnrt/RaMMuhHvFR+0WO8XrY0KpZFmUgKYJ9h
2020-06-11 10:53:05.275555 [DEBUG] switch_core_sqldb.c:2836 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
2020-06-11 10:53:05.275555 [NOTICE] mod_dptools.c:1406 Channel [sofia/internal/135@ibratro.talk.cloudplay.cloud:5061] has been answered
2020-06-11 10:53:05.275555 [DEBUG] switch_channel.c:3865 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Callstate Change RINGING -> ACTIVE
2020-06-11 10:53:05.275555 [DEBUG] sofia.c:7325 Channel sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 entering state [completed][200]
EXECUTE [depth=0] sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 conference(db0869dc-17ca-4841-9b7e-042a39dc85ae@ibratro.talk.cloudplay.cloud@default+flags{''})
2020-06-11 10:53:05.275555 [DEBUG] mod_verto.c:5580 EVENT BROADCAST presence.135@ibratro.talk.cloudplay.cloud {
"data": {
"channelCallState": "ACTIVE",
"originalChannelCallState": "RINGING",
"channelState": "CS_EXECUTE",
"callerUserName": "135",
"callerIDName": "CP 135",
"callerIDNumber": "135",
"channelUUID": "23d19c87-4086-460a-8478-b1cb34c17c42",
"presenceCallDirection": "inbound",
"channelPresenceID": "135@ibratro.talk.cloudplay.cloud"
},
"eventChannel": "presence.135@ibratro.talk.cloudplay.cloud"
}
2020-06-11 10:53:05.275555 [DEBUG] mod_conference.c:3414 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie
2020-06-11 10:53:05.275555 [DEBUG] conference_member.c:1764 Raw Codec Activation Success L16@16000hz 1 channel 20ms
2020-06-11 10:53:05.275555 [DEBUG] conference_member.c:1811 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2020-06-11 10:53:05.275555 [DEBUG] switch_core_codec.c:223 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Push codec L16:100
2020-06-11 10:53:05.275555 [DEBUG] mod_conference.c:228 Setup timer success interval: 20 samples: 160
2020-06-11 10:53:05.295573 [DEBUG] conference_member.c:106 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 binding '9' to 'energy up'
2020-06-11 10:53:05.295573 [INFO] switch_ivr_async.c:219 Digit parser mod_conference: Setting realm to 'conf'
2020-06-11 10:53:05.295573 [DEBUG] switch_ivr_async.c:344 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f951b9c8380 data: 0x7f954402fed0
2020-06-11 10:53:05.295573 [DEBUG] conference_member.c:106 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 binding '8' to 'energy equ'
2020-06-11 10:53:05.295573 [DEBUG] switch_ivr_async.c:344 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f951b9c8380 data: 0x7f954402ff00
2020-06-11 10:53:05.295573 [DEBUG] conference_member.c:106 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 binding '7' to 'energy dn'
2020-06-11 10:53:05.295573 [DEBUG] switch_ivr_async.c:344 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f951b9c8380 data: 0x7f954402ff30
2020-06-11 10:53:05.295573 [DEBUG] conference_member.c:106 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 binding '3' to 'vol talk up'
2020-06-11 10:53:05.295573 [DEBUG] switch_ivr_async.c:344 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f951b9c8380 data: 0x7f954402ff60
2020-06-11 10:53:05.295573 [DEBUG] conference_member.c:106 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 binding '2' to 'vol talk zero'
2020-06-11 10:53:05.295573 [DEBUG] switch_ivr_async.c:344 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f951b9c8380 data: 0x7f954402ff90
2020-06-11 10:53:05.295573 [DEBUG] conference_member.c:106 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 binding '1' to 'vol talk dn'
2020-06-11 10:53:05.295573 [DEBUG] switch_ivr_async.c:344 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f951b9c8380 data: 0x7f954402ffc0
2020-06-11 10:53:05.295573 [DEBUG] conference_member.c:106 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 binding '6' to 'vol listen up'
2020-06-11 10:53:05.295573 [DEBUG] switch_ivr_async.c:344 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f951b9c8380 data: 0x7f954402fff0
2020-06-11 10:53:05.295573 [DEBUG] conference_member.c:106 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 binding '5' to 'vol listen zero'
2020-06-11 10:53:05.295573 [DEBUG] switch_ivr_async.c:344 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f951b9c8380 data: 0x7f9544030020
2020-06-11 10:53:05.295573 [DEBUG] conference_member.c:106 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 binding '4' to 'vol listen dn'
2020-06-11 10:53:05.295573 [DEBUG] switch_ivr_async.c:344 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f951b9c8380 data: 0x7f9544030050
2020-06-11 10:53:05.295573 [DEBUG] conference_loop.c:1338 Setup timer soft success interval: 20 samples: 160 from codec G722
2020-06-11 10:53:05.315555 [NOTICE] switch_core_media.c:15843 Activating write resampler
2020-06-11 10:53:05.335560 [DEBUG] sofia.c:7325 Channel sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 entering state [ready][200]
2020-06-11 10:53:05.415558 [NOTICE] sofia.c:1089 Hangup sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [CS_EXECUTE] [NORMAL_CLEARING]
2020-06-11 10:53:05.435560 [INFO] conference_loop.c:1665 Channel leaving conference, cause: NORMAL_CLEARING
2020-06-11 10:53:05.435560 [DEBUG] mod_conference.c:2501 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 skip receive message [UNBRIDGE] (channel is hungup already)
2020-06-11 10:53:05.435560 [DEBUG] switch_core_media.c:12370 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 skip receive message [HARD_MUTE] (channel is hungup already)
2020-06-11 10:53:05.435560 [DEBUG] switch_core_codec.c:248 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Restore previous codec G722:9.
2020-06-11 10:53:05.435560 [DEBUG] switch_core_session.c:2905 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2020-06-11 10:53:05.435560 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State EXECUTE going to sleep
2020-06-11 10:53:05.435560 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Running State Change CS_HANGUP (Cur 1 Tot 24)
2020-06-11 10:53:05.435560 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Callstate Change ACTIVE -> HANGUP
2020-06-11 10:53:05.435560 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State HANGUP
2020-06-11 10:53:05.435560 [DEBUG] mod_sofia.c:453 Channel sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 hanging up, cause: NORMAL_CLEARING
2020-06-11 10:53:05.435560 [DEBUG] mod_verto.c:5580 EVENT BROADCAST presence.135@ibratro.talk.cloudplay.cloud {
"data": {
"channelCallState": "HANGUP",
"originalChannelCallState": "ACTIVE",
"channelState": "CS_HANGUP",
"callerUserName": "135",
"callerIDName": "CP 135",
"callerIDNumber": "135",
"channelUUID": "23d19c87-4086-460a-8478-b1cb34c17c42",
"presenceCallDirection": "inbound",
"channelPresenceID": "135@ibratro.talk.cloudplay.cloud"
},
"eventChannel": "presence.135@ibratro.talk.cloudplay.cloud"
}
2020-06-11 10:53:05.435560 [DEBUG] switch_core_state_machine.c:60 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Standard HANGUP, cause: NORMAL_CLEARING
2020-06-11 10:53:05.435560 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State HANGUP going to sleep
2020-06-11 10:53:05.435560 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State Change CS_HANGUP -> CS_REPORTING
2020-06-11 10:53:05.435560 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Running State Change CS_REPORTING (Cur 1 Tot 24)
2020-06-11 10:53:05.435560 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State REPORTING
2020-06-11 10:53:05.435560 [DEBUG] mod_conference.c:835 Write Lock ON
2020-06-11 10:53:05.435560 [DEBUG] mod_conference.c:838 Write Lock OFF
2020-06-11 10:53:05.475556 [DEBUG] switch_core_state_machine.c:174 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Standard REPORTING, cause: NORMAL_CLEARING
2020-06-11 10:53:05.475556 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State REPORTING going to sleep
2020-06-11 10:53:05.475556 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State Change CS_REPORTING -> CS_DESTROY
2020-06-11 10:53:05.475556 [DEBUG] switch_core_session.c:1726 Session 24 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Locked, Waiting on external entities
2020-06-11 10:53:05.475556 [NOTICE] switch_core_session.c:1744 Session 24 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Ended
2020-06-11 10:53:05.475556 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 [CS_DESTROY]
2020-06-11 10:53:05.475556 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) Running State Change CS_DESTROY (Cur 0 Tot 24)
2020-06-11 10:53:05.475556 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State DESTROY
2020-06-11 10:53:05.475556 [DEBUG] mod_sofia.c:364 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 SOFIA DESTROY
2020-06-11 10:53:05.475556 [DEBUG] switch_core_state_machine.c:181 sofia/internal/135@ibratro.talk.cloudplay.cloud:5061 Standard DESTROY
2020-06-11 10:53:05.475556 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/135@ibratro.talk.cloudplay.cloud:5061) State DESTROY going to sleep