2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:829 sofia/default/+233278910821 ending bridge by request from read function
2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:915 BRIDGE THREAD DONE [sofia/default/+233278910821]
2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:654 (sofia/default/+233278910821) State EXCHANGE_MEDIA going to sleep
2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_HANGUP (Cur 22 Tot 4674736)
2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:848 (sofia/default/+233278910821) Callstate Change EARLY -> HANGUP
2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233278910821) State HANGUP
2020-03-16 07:59:12.014649 [DEBUG] mod_sofia.c:453 Channel sofia/default/+233278910821 hanging up, cause: NO_USER_RESPONSE
2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:823 sofia/default/+233278910821 ending bridge by request from write function
2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:60 sofia/default/+233278910821 Standard HANGUP, cause: NO_USER_RESPONSE
2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233278910821) State HANGUP going to sleep
2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:620 (sofia/default/+233278910821) State Change CS_HANGUP -> CS_REPORTING
2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_REPORTING (Cur 22 Tot 4674736)
2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233278910821) State REPORTING
2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:915 BRIDGE THREAD DONE [sofia/default/+233242431111@196.44.101.68:5060]
2020-03-16 07:59:12.014649 [INFO] mod_json_cdr.c:271 Process [bf638467-cfba-408c-812b-67515ac8a216.cdr.json]
2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:1895 sofia/default/+233278910821 skip receive message [UNBRIDGE] (channel is hungup already)
2020-03-16 07:59:12.014649 [NOTICE] switch_core_state_machine.c:386 sofia/default/+233242431111@196.44.101.68:5060 has executed the last dialplan instruction, hanging up.
2020-03-16 07:59:12.014649 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/+233242431111@196.44.101.68:5060 [CS_EXECUTE] [NORMAL_CLEARING]
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:651 (sofia/default/+233242431111@196.44.101.68:5060) State EXECUTE going to sleep
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_HANGUP (Cur 22 Tot 4674736)
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:848 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change EARLY -> HANGUP
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233242431111@196.44.101.68:5060) State HANGUP
2020-03-16 07:59:12.034597 [DEBUG] mod_sofia.c:447 sofia/default/+233242431111@196.44.101.68:5060 Overriding SIP cause 480 with 480 from the other leg
2020-03-16 07:59:12.034597 [DEBUG] mod_sofia.c:453 Channel sofia/default/+233242431111@196.44.101.68:5060 hanging up, cause: NORMAL_CLEARING
2020-03-16 07:59:12.034597 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:60 sofia/default/+233242431111@196.44.101.68:5060 Standard HANGUP, cause: NORMAL_CLEARING
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233242431111@196.44.101.68:5060) State HANGUP going to sleep
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:620 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_HANGUP -> CS_REPORTING
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_REPORTING (Cur 22 Tot 4674736)
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233242431111@196.44.101.68:5060) State REPORTING
2020-03-16 07:59:12.034597 [INFO] mod_json_cdr.c:271 Process [8fc5b222-56b8-4eda-8417-b85cc663451f.cdr.json]
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:174 sofia/default/+233278910821 Standard REPORTING, cause: NO_USER_RESPONSE
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233278910821) State REPORTING going to sleep
2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:611 (sofia/default/+233278910821) State Change CS_REPORTING -> CS_DESTROY
2020-03-16 07:59:12.034597 [DEBUG] switch_core_session.c:1726 Session 4674727 (sofia/default/+233278910821) Locked, Waiting on external entities
2020-03-16 07:59:12.034597 [NOTICE] switch_core_session.c:1744 Session 4674727 (sofia/default/+233278910821) Ended
2020-03-16 07:59:12.034597 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/+233278910821 [CS_DESTROY]
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:739 (sofia/default/+233278910821) Running State Change CS_DESTROY (Cur 21 Tot 4674736)
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233278910821) State DESTROY
2020-03-16 07:59:12.054601 [DEBUG] mod_sofia.c:364 sofia/default/+233278910821 SOFIA DESTROY
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:181 sofia/default/+233278910821 Standard DESTROY
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233278910821) State DESTROY going to sleep
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:174 sofia/default/+233242431111@196.44.101.68:5060 Standard REPORTING, cause: NORMAL_CLEARING
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233242431111@196.44.101.68:5060) State REPORTING going to sleep
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:611 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_REPORTING -> CS_DESTROY
2020-03-16 07:59:12.054601 [DEBUG] switch_core_session.c:1726 Session 4674726 (sofia/default/+233242431111@196.44.101.68:5060) Locked, Waiting on external entities
2020-03-16 07:59:12.054601 [NOTICE] switch_core_session.c:1744 Session 4674726 (sofia/default/+233242431111@196.44.101.68:5060) Ended
2020-03-16 07:59:12.054601 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/+233242431111@196.44.101.68:5060 [CS_DESTROY]
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:739 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_DESTROY (Cur 20 Tot 4674736)
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233242431111@196.44.101.68:5060) State DESTROY
2020-03-16 07:59:12.054601 [DEBUG] mod_sofia.c:364 sofia/default/+233242431111@196.44.101.68:5060 SOFIA DESTROY
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:181 sofia/default/+233242431111@196.44.101.68:5060 Standard DESTROY
2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233242431111@196.44.101.68:5060) State DESTROY going to sleep
2020-03-16 07:59:12.334581 [NOTICE] switch_channel.c:1118 New Channel sofia/default/+233242431111@196.44.101.68:5060 [ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_NEW (Cur 21 Tot 4674737)
2020-03-16 07:59:12.334581 [DEBUG] sofia.c:10255 sofia/default/+233242431111@196.44.101.68:5060 receiving invite from 197.251.229.242:1025 version: 1.10.2 -release-13-f7bdd3845a 64bit
2020-03-16 07:59:12.334581 [DEBUG] sofia.c:10349 verifying acl "default" for ip/port 197.251.229.242:0.
2020-03-16 07:59:12.334581 [DEBUG] sofia.c:10378 IP 197.251.229.242 Approved by acl "default[]". Access Granted.
2020-03-16 07:59:12.334581 [DEBUG] sofia.c:11452 Setting NAT mode based on via port
2020-03-16 07:59:12.334581 [DEBUG] sofia.c:7301 Channel sofia/default/+233242431111@196.44.101.68:5060 entering state [received][100]
2020-03-16 07:59:12.334581 [DEBUG] sofia.c:7311 Remote SDP:
v=0
o=3cxPS 405404647424 207215394817 IN IP4 197.251.229.242
s=3cxPS Audio call
c=IN IP4 197.251.229.242
t=0 0
m=audio 9030 RTP/SAVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:Ci72RZdgckx3BvzPUpU+8LBFxvR/c5cBM5Q4w4X0
m=audio 9030 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000

2020-03-16 07:59:12.334581 [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:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
2020-03-16 07:59:12.334581 [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:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
2020-03-16 07:59:12.334581 [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:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
2020-03-16 07:59:12.334581 [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:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
2020-03-16 07:59:12.334581 [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:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1822 Found suite AES_CM_128_HMAC_SHA1_80
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1892 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1215 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:6YMmvJXhb18M+MflfSXZ+IUKx34vSMtS1bkUQ+wn]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/+233242431111@196.44.101.68:5060 G729/8000 20 ms 160 samples 8000 bits 1 channels
2020-03-16 07:59:12.334581 [DEBUG] switch_core_codec.c:111 sofia/default/+233242431111@196.44.101.68:5060 Original read codec set to G729:18
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5909 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2020-03-16 07:59:12.334581 [DEBUG] sofia.c:7735 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_NEW -> CS_INIT
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:604 (sofia/default/+233242431111@196.44.101.68:5060) State NEW
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_INIT (Cur 21 Tot 4674737)
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233242431111@196.44.101.68:5060) State INIT
2020-03-16 07:59:12.334581 [DEBUG] mod_sofia.c:93 sofia/default/+233242431111@196.44.101.68:5060 SOFIA INIT
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:40 sofia/default/+233242431111@196.44.101.68:5060 Standard INIT
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_INIT -> CS_ROUTING
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233242431111@196.44.101.68:5060) State INIT going to sleep
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_ROUTING (Cur 21 Tot 4674737)
2020-03-16 07:59:12.334581 [DEBUG] switch_channel.c:2332 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change DOWN -> RINGING
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233242431111@196.44.101.68:5060) State ROUTING
2020-03-16 07:59:12.334581 [DEBUG] mod_sofia.c:154 sofia/default/+233242431111@196.44.101.68:5060 SOFIA ROUTING
2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:236 sofia/default/+233242431111@196.44.101.68:5060 Standard ROUTING
2020-03-16 07:59:12.334581 [INFO] mod_dialplan_xml.c:637 Processing +233242431111 <+233242431111>->233278910821 in context default
2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2020-03-16 07:59:12.354642 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: 021f676c-1b86-4c5d-ab5d-83652fc892b0
FreeSWITCH-Hostname: rack-astpp
FreeSWITCH-Switchname: rack-astpp
FreeSWITCH-IPv4: 196.44.101.68
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2020-03-16%2007%3A59%3A12
Event-Date-GMT: Mon,%2016%20Mar%202020%2007%3A59%3A12%20GMT
Event-Date-Timestamp: 1584345552334581
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 281836657
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
Unique-ID: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: %2B233242431111%40196.44.101.68
Channel-Call-UUID: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
Answer-State: ringing
Channel-Read-Codec-Name: G729
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 8000
Channel-Write-Codec-Name: G729
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 8000
Caller-Direction: inbound
Caller-Logical-Direction: inbound
Caller-Username: %2B233242431111
Caller-Dialplan: XML
Caller-Caller-ID-Name: %2B233242431111
Caller-Caller-ID-Number: %2B233242431111
Caller-Orig-Caller-ID-Name: %2B233242431111
Caller-Orig-Caller-ID-Number: %2B233242431111
Caller-Network-Addr: 197.251.229.242
Caller-ANI: %2B233242431111
Caller-Destination-Number: 233278910821
Caller-Unique-ID: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1584345552334581
Caller-Channel-Created-Time: 1584345552334581
Caller-Channel-Answered-Time: 0
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 0
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Channel-Resurrect-Time: 0
Caller-Channel-Bridged-Time: 0
Caller-Channel-Last-Hold: 0
Caller-Channel-Hold-Accum: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
variable_direction: inbound
variable_uuid: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
variable_session_id: 4674737
variable_sip_from_user: %2B233242431111
variable_sip_from_port: 5060
variable_sip_from_uri: %2B233242431111%40196.44.101.68%3A5060
variable_sip_from_host: 196.44.101.68
variable_video_media_flow: disabled
variable_text_media_flow: disabled
variable_channel_name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
variable_sip_call_id: wP2JVVhYg-Wt4ibzjESbzg..
variable_sip_local_network_addr: 196.44.101.68
variable_sip_network_ip: 197.251.229.242
variable_sip_network_port: 1025
variable_sip_invite_stamp: 1584345552334581
variable_sip_received_ip: 197.251.229.242
variable_sip_received_port: 1025
variable_sip_via_protocol: udp
variable_sip_authorized: true
variable_sip_acl_authed_by: default
variable_sip_from_user_stripped: 233242431111
variable_sip_from_tag: 9730ec1a
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%40196.44.101.68%3A5060
variable_recovery_profile_name: default
variable_sip_Remote-Party-ID: %22%2B233242431111%22%20%3Csip%3A%2B233242431111%40196.44.101.68%3A5060%3E%3Bparty%3Dcalling
variable_sip_cid_type: rpid
variable_sip_full_via: SIP/2.0/UDP%20197.251.229.242%3A5060%3Bbranch%3Dz9hG4bK-524287-1---291d2253dc300f75%3Brport%3D1025
variable_sip_from_display: %2B233242431111
variable_sip_full_from: %22%2B233242431111%22%20%3Csip%3A%2B233242431111%40196.44.101.68%3A5060%3E%3Btag%3D9730ec1a
variable_sip_full_to: %3Csip%3A233278910821%40196.44.101.68%3A5060%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REGISTER,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20INFO,%20MESSAGE,%20UPDATE
variable_sip_req_user: 233278910821
variable_sip_req_port: 5060
variable_sip_req_uri: 233278910821%40196.44.101.68%3A5060
variable_sip_req_host: 196.44.101.68
variable_sip_to_user: 233278910821
variable_sip_to_port: 5060
variable_sip_to_uri: 233278910821%40196.44.101.68%3A5060
variable_sip_to_host: 196.44.101.68
variable_sip_contact_user: %2B233242431111
variable_sip_contact_port: 5060
variable_sip_contact_uri: %2B233242431111%40197.251.229.242%3A5060
variable_sip_contact_host: 197.251.229.242
variable_sip_user_agent: 3CXPhoneSystem%2015.5.15502.6%20(15502)
variable_sip_via_host: 197.251.229.242
variable_sip_via_port: 5060
variable_sip_via_rport: 1025
variable_max_forwards: 70
variable_presence_id: %2B233242431111%40196.44.101.68
variable_sip_nat_detected: true
variable_switch_r_sdp: v%3D0%0D%0Ao%3D3cxPS%20405404647424%20207215394817%20IN%20IP4%20197.251.229.242%0D%0As%3D3cxPS%20Audio%20call%0D%0Ac%3DIN%20IP4%20197.251.229.242%0D%0At%3D0%200%0D%0Am%3Daudio%209030%20RTP/SAVP%200%208%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dcrypto%3A1%20AES_CM_128_HMAC_SHA1_80%20inline%3ACupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1%0D%0Aa%3Dcrypto%3A2%20AES_CM_128_HMAC_SHA1_32%20inline%3ACi72RZdgckx3BvzPUpU%2B8LBFxvR/c5cBM5Q4w4X0%0D%0Am%3Daudio%209030%20RTP/AVP%200%208%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_bcg729.G729%408000h%4020i%408000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_bcg729.G729%408000h%4020i%408000b
variable_rtp_use_codec_string: G729,PCMA,PCMU
variable_remote_video_media_flow: inactive
variable_remote_text_media_flow: inactive
variable_remote_audio_media_flow: sendrecv
variable_audio_media_flow: sendrecv
variable_rtp_secure_media_negotiated: AES_CM_128_HMAC_SHA1_80
variable_srtp_remote_audio_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3ACupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1
variable_srtp_remote_audio_crypto_type: AES_CM_128_HMAC_SHA1_80
variable_rtp_has_crypto: AES_CM_128_HMAC_SHA1_80
variable_rtp_last_audio_local_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3A6YMmvJXhb18M%2BMflfSXZ%2BIUKx34vSMtS1bkUQ%2Bwn
variable_remote_media_ip: 197.251.229.242
variable_remote_media_port: 9030
variable_rtp_audio_recv_pt: 18
variable_rtp_use_codec_name: G729
variable_rtp_use_codec_fmtp: annexb%3Dno
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: G729%408000h%4020i%401c
variable_read_codec: G729
variable_original_read_codec: G729
variable_read_rate: 8000
variable_original_read_rate: 8000
variable_write_codec: G729
variable_write_rate: 8000
variable_dtmf_type: rfc2833
variable_endpoint_disposition: RECEIVED
variable_call_uuid: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: %2B233242431111
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: %2B233242431111
Hunt-Caller-ID-Number: %2B233242431111
Hunt-Orig-Caller-ID-Name: %2B233242431111
Hunt-Orig-Caller-ID-Number: %2B233242431111
Hunt-Network-Addr: 197.251.229.242
Hunt-ANI: %2B233242431111
Hunt-Destination-Number: 233278910821
Hunt-Unique-ID: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
Hunt-Source: mod_sofia
Hunt-Context: default
Hunt-Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
Hunt-Profile-Index: 1
Hunt-Profile-Created-Time: 1584345552334581
Hunt-Channel-Created-Time: 1584345552334581
Hunt-Channel-Answered-Time: 0
Hunt-Channel-Progress-Time: 0
Hunt-Channel-Progress-Media-Time: 0
Hunt-Channel-Hangup-Time: 0
Hunt-Channel-Transfer-Time: 0
Hunt-Channel-Resurrect-Time: 0
Hunt-Channel-Bridged-Time: 0
Hunt-Channel-Last-Hold: 0
Hunt-Channel-Hold-Accum: 0
Hunt-Screen-Bit: true
Hunt-Privacy-Hide-Name: false
Hunt-Privacy-Hide-Number: false


2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_name: 
2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_value: 
2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] tag_name: 
2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] section: dialplan
2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 233278910821
2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '233278910821' AND status=0 limit 1
2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number  : +233242431111 / +233242431111
2020-03-16 07:59:12.354642 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1 
2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="233278910821" LIMIT 1
2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="233278910821" limit 1
2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : outbound
2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("197.251.229.242") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "233278910821"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
2020-03-16 07:59:12.374642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 6425492662]
2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "6425492662" AND deleted = 0 limit 1
2020-03-16 07:59:12.374642 [WARNING] switch_core_state_machine.c:688 74301954-09ed-408c-8322-28f2adfa25b2 sofia/default/100@196.44.101.68 Abandoned
2020-03-16 07:59:12.374642 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/100@196.44.101.68 [CS_NEW] [WRONG_CALL_STATE]
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:585 (sofia/default/100@196.44.101.68) Running State Change CS_HANGUP (Cur 21 Tot 4674737)
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:848 (sofia/default/100@196.44.101.68) Callstate Change DOWN -> HANGUP
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@196.44.101.68) State HANGUP
2020-03-16 07:59:12.374642 [DEBUG] mod_sofia.c:453 Channel sofia/default/100@196.44.101.68 hanging up, cause: WRONG_CALL_STATE
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@196.44.101.68 Standard HANGUP, cause: WRONG_CALL_STATE
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@196.44.101.68) State HANGUP going to sleep
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:620 (sofia/default/100@196.44.101.68) State Change CS_HANGUP -> CS_REPORTING
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:585 (sofia/default/100@196.44.101.68) Running State Change CS_REPORTING (Cur 21 Tot 4674737)
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:936 (sofia/default/100@196.44.101.68) State REPORTING
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@196.44.101.68 Standard REPORTING, cause: WRONG_CALL_STATE
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:936 (sofia/default/100@196.44.101.68) State REPORTING going to sleep
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:611 (sofia/default/100@196.44.101.68) State Change CS_REPORTING -> CS_DESTROY
2020-03-16 07:59:12.374642 [DEBUG] switch_core_session.c:1726 Session 4674723 (sofia/default/100@196.44.101.68) Locked, Waiting on external entities
2020-03-16 07:59:12.374642 [NOTICE] switch_core_session.c:1744 Session 4674723 (sofia/default/100@196.44.101.68) Ended
2020-03-16 07:59:12.374642 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/100@196.44.101.68 [CS_DESTROY]
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:739 (sofia/default/100@196.44.101.68) Running State Change CS_DESTROY (Cur 20 Tot 4674737)
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:749 (sofia/default/100@196.44.101.68) State DESTROY
2020-03-16 07:59:12.374642 [DEBUG] mod_sofia.c:364 sofia/default/100@196.44.101.68 SOFIA DESTROY
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@196.44.101.68 Standard DESTROY
2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:749 (sofia/default/100@196.44.101.68) State DESTROY going to sleep
2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^233278910821.*' OR blocked_patterns = '^23327891082.*' OR blocked_patterns = '^2332789108.*' OR blocked_patterns = '^233278910.*' OR blocked_patterns = '^23327891.*' OR blocked_patterns = '^2332789.*' OR blocked_patterns = '^233278.*' OR blocked_patterns = '^23327.*' OR blocked_patterns = '^2332.*' OR blocked_patterns = '^233.*' OR blocked_patterns = '^23.*' OR blocked_patterns = '^2.*' OR blocked_patterns ='--') AND accountid = 1367 limit 1 
2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="233278910821" AND A.accountid = '1367' limit 1
2020-03-16 07:59:12.374642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 233278910821
2020-03-16 07:59:12.374642 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1 
2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="233278910821" LIMIT 1
2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="233278910821" limit 1
2020-03-16 07:59:12.374642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] New Call direction : outbound
2020-03-16 07:59:12.374642 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1 
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="233278910821" AND (accountid = 0 OR status = 1) LIMIT 1
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^233278910821.*' OR patterns = '^23327891082.*' OR patterns = '^2332789108.*' OR patterns = '^233278910.*' OR patterns = '^23327891.*' OR patterns = '^2332789.*' OR patterns = '^233278.*' OR patterns = '^23327.*' OR patterns = '^2332.*' OR patterns = '^233.*' OR patterns = '^23.*' OR patterns = '^2.*' OR patterns ='--') AND accountid = 1367 ORDER BY LENGTH(PKGPTR.patterns) DESC
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '233278910821' OR number = '23327891082' OR number = '2332789108' OR number = '233278910' OR number = '23327891' OR number = '2332789' OR number = '233278' OR number = '23327' OR number = '2332' OR number = '233' OR number = '23' OR number = '2' OR number ='--') OR (number = '233278910821*' OR number = '23327891082*' OR number = '2332789108*' OR number = '233278910*' OR number = '23327891*' OR number = '2332789*' OR number = '233278*' OR number = '23327*' OR number = '2332*' OR number = '233*' OR number = '23*' OR number = '2*' OR number ='--') OR (number = '+233242431111' OR number = '+23324243111' OR number = '+2332424311' OR number = '+233242431' OR number = '+23324243' OR number = '+2332424' OR number = '+233242' OR number = '+23324' OR number = '+2332' OR number = '+233' OR number = '+23' OR number = '+2' OR number = '+' OR number ='--') OR (number = '+233242431111*' OR number = '+23324243111*' OR number = '+2332424311*' OR number = '+233242431*' OR number = '+23324243*' OR number = '+2332424*' OR number = '+233242*' OR number = '+23324*' OR number = '+2332*' OR number = '+233*' OR number = '+23*' OR number = '+2*' OR number = '+*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call Barring Status : -1
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] Caller Id : +233242431111
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] CLI Pool Id : 1
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] Query :Select count(*) as count from dids where number='+233242431111' and status=0
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_COUNT] COUNT: 0
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CIDPOOL] Query :Select number from dids where status=0 and accountid='1367' order by RAND() limit 1
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '233278910821' OR routing_prefix = '23327891082' OR routing_prefix = '2332789108' OR routing_prefix = '233278910' OR routing_prefix = '23327891' OR routing_prefix = '2332789' OR routing_prefix = '233278' OR routing_prefix = '23327' OR routing_prefix = '2332' OR routing_prefix = '233' OR routing_prefix = '23' OR routing_prefix = '2' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information ===================
2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 1367
2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 6425492662
2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 1347.2088
2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 40
2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 1
2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================================================
2020-03-16 07:59:12.394601 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH1: 2
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 2 AND status=0 limit 1 
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 40 AND status = 0
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :outbound
2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^233278910821.*' OR pattern = '^23327891082.*' OR pattern = '^2332789108.*' OR pattern = '^233278910.*' OR pattern = '^23327891.*' OR pattern = '^2332789.*' OR pattern = '^233278.*' OR pattern = '^23327.*' OR pattern = '^2332.*' OR pattern = '^233.*' OR pattern = '^23.*' OR pattern = '^2.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 40 OR accountid=1367)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: outbound
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Rates Information ===================
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 1144850
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Includedseconds : 0
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Cost : 0.16000
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] comment : GH-MOB-TIGO
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Country Id : 0
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Accid : 1367
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk ID: 0
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Routing type: 
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ================================================================
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Your1347.2088 balance Accountid 1367 !!!
2020-03-16 07:59:12.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 24 mins!
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Call Max length duration : 24 minutes
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] [DIALPLAN] User Rate ID : 1144850
2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id	:0
2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=40 ORDER by id asc
2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^233278910821.*' OR pattern = '^23327891082.*' OR pattern = '^2332789108.*' OR pattern = '^233278910.*' OR pattern = '^23327891.*' OR pattern = '^2332789.*' OR pattern = '^233278.*' OR pattern = '^23327.*' OR pattern = '^2332.*' OR pattern = '^233.*' OR pattern = '^23.*' OR pattern = '^2.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (7) ORDER by TR.cost ASC,TR.precedence ASC, TK.precedence
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Termination Rates Information ===================
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 548686
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Code : ^233.*
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Destination : Ghana Mob
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Free Seconds : 0
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Prefix : ^233.*
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Strip : 
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Prepend : +
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Carrier id : 7
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] carrier_name : MTN_LOCAL
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] dialplan_variable : 
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Failover gateway : 
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Vendor id : 17
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Max channels : 0
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk Name : MTN_LOCAL
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================END OF TERMINATION RATES=======================
2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1367 AND status=0 LIMIT 1
2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] maxlength::::::::: 24
2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 40 AND status = 0
2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate FROM localization WHERE id=(SELECT localization_id from accounts where id = 17) AND status=0 limit 1 
2020-03-16 07:59:12.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 233278910821
2020-03-16 07:59:12.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : +233278910821
2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Generated XML:
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<document type="freeswitch/xml">
<section name="dialplan" description="ASTPP Dialplan">
<context name="default">
<extension name="233278910821">
<condition field="destination_number" expression="233278910821">
<action application="set" data="effective_destination_number=233278910821"/>
<action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
<action application="set" data="bridge_pre_execute_bleg_data=+1440 normal_clearing"/>
<action application="set" data="callstart=2020-03-16 07:59:12"/>
<action application="set" data="hangup_after_bridge=true"/>
<action application="set" data="continue_on_fail=TRUE"/>
<action application="set" data="account_id=1367"/>
<action application="set" data="parent_id=0"/>
<action application="set" data="entity_id=0"/>
<action application="set" data="call_processed=internal"/>
<action application="set" data="call_direction=outbound"/>
<action application="set" data="accountname=PEG IP"/>
<action application="export" data="t38_passthru=true"/>
<action application="set" data="fax_enable_t38=true"/>
<action application="set" data="fax_enable_t38_request=true"/>
<action application="set" data="origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367"/>
<action application="set" data="original_caller_id_name=+233242431111"/>
<action application="set" data="original_caller_id_number=0242436881"/>
<action application="limit" data="db 6425492662 user_6425492662 90 !SWITCH_CONGESTION"/>

<action application="set" data="effective_caller_id_name=+233242431111"/>
<action application="set" data="effective_caller_id_number=+233242431111"/>
<action application="set" data="calltype=STANDARD"/>
<action application="set" data="termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17"/>
<action application="set" data="trunk_id=7"/>
<action application="set" data="provider_id=17"/>
<action application="set" data="rate_flag=1"/>
<action application="set" data="force_trunk_flag=1144850"/>
<action application="export" data="presence_data=trunk_id=7"/>
<action application="set" data="intcall=0"/>
<action application="export" data="presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242"/>
<action application="bridge" data="[leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821"/>
</condition>
</extension>
</context>
</section>
</document>
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 parsing [default->233278910821] continue=false
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Regex (PASS) [233278910821] destination_number(233278910821) =~ /233278910821/ break=on-false
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_destination_number=233278910821) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(bridge_pre_execute_bleg_app=sched_hangup) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(bridge_pre_execute_bleg_data=+1440 normal_clearing) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(callstart=2020-03-16 07:59:12) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(hangup_after_bridge=true) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(continue_on_fail=TRUE) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(account_id=1367) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(parent_id=0) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(entity_id=0) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(call_processed=internal) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(call_direction=outbound) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(accountname=PEG IP) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(t38_passthru=true) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(fax_enable_t38=true) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(fax_enable_t38_request=true) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(original_caller_id_name=+233242431111) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(original_caller_id_number=0242436881) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action limit(db 6425492662 user_6425492662 90 !SWITCH_CONGESTION) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_caller_id_name=+233242431111) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_caller_id_number=+233242431111) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(calltype=STANDARD) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(trunk_id=7) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(provider_id=17) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(rate_flag=1) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(force_trunk_flag=1144850) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(presence_data=trunk_id=7) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(intcall=0) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821) 
2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:287 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_ROUTING -> CS_EXECUTE
2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233242431111@196.44.101.68:5060) State ROUTING going to sleep
2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_EXECUTE (Cur 20 Tot 4674737)
2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:651 (sofia/default/+233242431111@196.44.101.68:5060) State EXECUTE
2020-03-16 07:59:12.414599 [DEBUG] mod_sofia.c:209 sofia/default/+233242431111@196.44.101.68:5060 SOFIA EXECUTE
2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:329 sofia/default/+233242431111@196.44.101.68:5060 Standard EXECUTE
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_destination_number=233278910821)
2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_destination_number]=[233278910821]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(bridge_pre_execute_bleg_app=sched_hangup)
2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [bridge_pre_execute_bleg_app]=[sched_hangup]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(bridge_pre_execute_bleg_data=+1440 normal_clearing)
2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [bridge_pre_execute_bleg_data]=[+1440 normal_clearing]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(callstart=2020-03-16 07:59:12)
2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [callstart]=[2020-03-16 07:59:12]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(hangup_after_bridge=true)
2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(continue_on_fail=TRUE)
2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [continue_on_fail]=[TRUE]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(account_id=1367)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [account_id]=[1367]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(parent_id=0)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [parent_id]=[0]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(entity_id=0)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [entity_id]=[0]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(call_processed=internal)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [call_processed]=[internal]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(call_direction=outbound)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [call_direction]=[outbound]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(accountname=PEG IP)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [accountname]=[PEG IP]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(t38_passthru=true)
2020-03-16 07:59:12.434587 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [t38_passthru]=[true]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(fax_enable_t38=true)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [fax_enable_t38]=[true]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(fax_enable_t38_request=true)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [fax_enable_t38_request]=[true]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [origination_rates]=[ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(original_caller_id_name=+233242431111)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [original_caller_id_name]=[+233242431111]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(original_caller_id_number=0242436881)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [original_caller_id_number]=[0242436881]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 limit(db 6425492662 user_6425492662 90 !SWITCH_CONGESTION)
2020-03-16 07:59:12.434587 [DEBUG] switch_limit.c:124 incr called: 6425492662_user_6425492662 max:90, interval:0
2020-03-16 07:59:12.434587 [DEBUG] mod_db.c:194 Usage for 6425492662_user_6425492662 is now 1/90
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_caller_id_name=+233242431111)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_caller_id_name]=[+233242431111]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_caller_id_number=+233242431111)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_caller_id_number]=[+233242431111]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(calltype=STANDARD)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [calltype]=[STANDARD]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [termination_rates]=[ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(trunk_id=7)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [trunk_id]=[7]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(provider_id=17)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [provider_id]=[17]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(rate_flag=1)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [rate_flag]=[1]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(force_trunk_flag=1144850)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [force_trunk_flag]=[1144850]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(presence_data=trunk_id=7)
2020-03-16 07:59:12.434587 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=7]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(intcall=0)
2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [intcall]=[0]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242)
2020-03-16 07:59:12.434587 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821)
2020-03-16 07:59:12.454586 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [t38_passthru]=[true] to event
2020-03-16 07:59:12.454586 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242] to event
2020-03-16 07:59:12.454586 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242] to event
2020-03-16 07:59:12.454586 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables
2020-03-16 07:59:12.454586 [DEBUG] switch_ivr_originate.c:2760 Parsing session specific variables
2020-03-16 07:59:12.454586 [NOTICE] switch_channel.c:1118 New Channel sofia/default/+233278910821 [e11f0fab-6f98-4aec-8a48-d077ef91c8af]
2020-03-16 07:59:12.454586 [DEBUG] mod_sofia.c:5089 (sofia/default/+233278910821) State Change CS_NEW -> CS_INIT
2020-03-16 07:59:12.454586 [DEBUG] switch_ivr_originate.c:3067 sofia/default/+233278910821 Setting leg timeout to 30
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_INIT (Cur 21 Tot 4674738)
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233278910821) State INIT
2020-03-16 07:59:12.454586 [DEBUG] mod_sofia.c:93 sofia/default/+233278910821 SOFIA INIT
2020-03-16 07:59:12.454586 [DEBUG] sofia_glue.c:1618 sofia/default/+233278910821 sending invite version: 1.10.2 -release-13-f7bdd3845a 64bit
Local SDP:
v=0
o=FreeSWITCH 1584314652 1584314653 IN IP4 196.44.101.68
s=FreeSWITCH
c=IN IP4 196.44.101.68
t=0 0
m=audio 30900 RTP/AVP 18 8 0 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:40 sofia/default/+233278910821 Standard INIT
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+233278910821) State Change CS_INIT -> CS_ROUTING
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233278910821) State INIT going to sleep
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_ROUTING (Cur 21 Tot 4674738)
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233278910821) State ROUTING
2020-03-16 07:59:12.454586 [DEBUG] mod_sofia.c:154 sofia/default/+233278910821 SOFIA ROUTING
2020-03-16 07:59:12.454586 [DEBUG] switch_ivr_originate.c:67 (sofia/default/+233278910821) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233278910821) State ROUTING going to sleep
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_CONSUME_MEDIA (Cur 21 Tot 4674738)
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:663 (sofia/default/+233278910821) State CONSUME_MEDIA
2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:663 (sofia/default/+233278910821) State CONSUME_MEDIA going to sleep
2020-03-16 07:59:12.454586 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [calling][0]
2020-03-16 07:59:12.494637 [DEBUG] switch_scheduler.c:147 Deleting task 1409251 switch_ivr_schedule_hangup (bf638467-cfba-408c-812b-67515ac8a216)
2020-03-16 07:59:12.634655 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [proceeding][183]
2020-03-16 07:59:12.634655 [DEBUG] sofia.c:7311 Remote SDP:
v=0
o=- 10025417 10025417 IN IP4 196.201.34.20
s=-
c=IN IP4 196.201.34.70
t=0 0
a=sendrecv
m=audio 27302 RTP/AVP 18 101
c=IN IP4 196.201.34.70
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=maxptime:40

2020-03-16 07:59:12.634655 [NOTICE] sofia.c:7314 Pre-Answer sofia/default/+233278910821!
2020-03-16 07:59:12.634655 [DEBUG] switch_channel.c:3565 (sofia/default/+233278910821) Callstate Change DOWN -> EARLY
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[G729:18:8000:20:8000:1]
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[PCMA:8:8000:20:64000:1]
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[PCMU:0:8000:20:64000:1]
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/+233278910821 G729/8000 20 ms 160 samples 8000 bits 1 channels
2020-03-16 07:59:12.634655 [DEBUG] switch_core_codec.c:111 sofia/default/+233278910821 Original read codec set to G729:18
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5909 sofia/default/+233278910821 Set 2833 dtmf send payload to 101 recv payload to 101
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+233278910821] 196.44.101.68 port 30900 -> 196.201.34.70 port 27302 codec: 18 ms: 20
2020-03-16 07:59:12.634655 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:8973 sofia/default/+233278910821 Set 2833 dtmf send payload to 101
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:8980 sofia/default/+233278910821 Set 2833 dtmf receive payload to 101
2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:9003 sofia/default/+233278910821 Set rtp dtmf delay to 40
2020-03-16 07:59:12.654640 [INFO] switch_ivr_originate.c:3758 Sending early media
2020-03-16 07:59:12.654640 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+233242431111@196.44.101.68:5060] 196.44.101.68 port 27810 -> 197.251.229.242 port 9030 codec: 18 ms: 20
2020-03-16 07:59:12.654640 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
2020-03-16 07:59:12.654640 [DEBUG] switch_core_media.c:8973 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf send payload to 101
2020-03-16 07:59:12.654640 [DEBUG] switch_core_media.c:8980 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf receive payload to 101
2020-03-16 07:59:12.654640 [DEBUG] switch_core_media.c:9003 sofia/default/+233242431111@196.44.101.68:5060 Set rtp dtmf delay to 40
2020-03-16 07:59:12.654640 [INFO] switch_rtp.c:4212 Activating audio Secure RTP SEND
2020-03-16 07:59:12.654640 [DEBUG] switch_core_sqldb.c:2827 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
2020-03-16 07:59:12.654640 [INFO] switch_rtp.c:4190 Activating audio Secure RTP RECV
2020-03-16 07:59:12.654640 [DEBUG] mod_sofia.c:2593 Ring SDP:
v=0
o=FreeSWITCH 1584317742 1584317743 IN IP4 196.44.101.68
s=FreeSWITCH
c=IN IP4 196.44.101.68
t=0 0
m=audio 27810 RTP/SAVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
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:6YMmvJXhb18M+MflfSXZ+IUKx34vSMtS1bkUQ+wn
m=audio 0 RTP/AVP 19

2020-03-16 07:59:12.654640 [DEBUG] switch_core_sqldb.c:2827 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
2020-03-16 07:59:12.654640 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/+233242431111@196.44.101.68:5060!
2020-03-16 07:59:12.654640 [DEBUG] switch_channel.c:3565 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change RINGING -> EARLY
2020-03-16 07:59:12.654640 [DEBUG] switch_ivr_originate.c:3809 Originate Resulted in Success: [sofia/default/+233278910821]
2020-03-16 07:59:12.654640 [DEBUG] switch_ivr_bridge.c:1796 (sofia/default/+233278910821) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2020-03-16 07:59:12.654640 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_EXCHANGE_MEDIA (Cur 21 Tot 4674738)
2020-03-16 07:59:12.654640 [DEBUG] switch_core_state_machine.c:654 (sofia/default/+233278910821) State EXCHANGE_MEDIA
2020-03-16 07:59:12.654640 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
2020-03-16 07:59:12.694653 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
2020-03-16 07:59:12.834640 [DEBUG] switch_ivr_bridge.c:658 sofia/default/+233278910821 Bridge execute app sched_hangup(+1440 normal_clearing)
2020-03-16 07:59:12.854643 [DEBUG] switch_ivr.c:632 sofia/default/+233278910821 Command Execute [depth=0] sched_hangup(+1440 normal_clearing)
EXECUTE [depth=0] sofia/default/+233278910821 sched_hangup(+1440 normal_clearing)
2020-03-16 07:59:12.854643 [DEBUG] switch_scheduler.c:252 Added task 1409255 switch_ivr_schedule_hangup (e11f0fab-6f98-4aec-8a48-d077ef91c8af) to run at 1584346992
2020-03-16 07:59:13.274646 [DEBUG] sofia.c:7301 Channel sofia/default/+233242431111@196.44.101.68:5060 entering state [early][183]
2020-03-16 07:59:13.654590 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [terminated][500]
2020-03-16 07:59:13.654590 [NOTICE] sofia.c:8534 Hangup sofia/default/+233278910821 [CS_EXCHANGE_MEDIA] [NORMAL_TEMPORARY_FAILURE]
2020-03-16 07:59:13.654590 [DEBUG] switch_ivr_bridge.c:829 sofia/default/+233278910821 ending bridge by request from read function
2020-03-16 07:59:13.654590 [DEBUG] switch_ivr_bridge.c:915 BRIDGE THREAD DONE [sofia/default/+233278910821]
2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:654 (sofia/default/+233278910821) State EXCHANGE_MEDIA going to sleep
2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_HANGUP (Cur 21 Tot 4674738)
2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:848 (sofia/default/+233278910821) Callstate Change EARLY -> HANGUP
2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233278910821) State HANGUP
2020-03-16 07:59:13.654590 [DEBUG] mod_sofia.c:453 Channel sofia/default/+233278910821 hanging up, cause: NORMAL_TEMPORARY_FAILURE
2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:60 sofia/default/+233278910821 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233278910821) State HANGUP going to sleep
2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:620 (sofia/default/+233278910821) State Change CS_HANGUP -> CS_REPORTING
2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_REPORTING (Cur 21 Tot 4674738)
2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233278910821) State REPORTING
2020-03-16 07:59:13.654590 [INFO] mod_json_cdr.c:271 Process [e11f0fab-6f98-4aec-8a48-d077ef91c8af.cdr.json]
2020-03-16 07:59:13.674599 [DEBUG] switch_ivr_bridge.c:915 BRIDGE THREAD DONE [sofia/default/+233242431111@196.44.101.68:5060]
2020-03-16 07:59:13.674599 [DEBUG] switch_ivr_bridge.c:1895 sofia/default/+233278910821 skip receive message [UNBRIDGE] (channel is hungup already)
2020-03-16 07:59:13.674599 [NOTICE] switch_core_state_machine.c:386 sofia/default/+233242431111@196.44.101.68:5060 has executed the last dialplan instruction, hanging up.
2020-03-16 07:59:13.674599 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/+233242431111@196.44.101.68:5060 [CS_EXECUTE] [NORMAL_CLEARING]
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:651 (sofia/default/+233242431111@196.44.101.68:5060) State EXECUTE going to sleep
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_HANGUP (Cur 21 Tot 4674738)
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:848 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change EARLY -> HANGUP
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233242431111@196.44.101.68:5060) State HANGUP
2020-03-16 07:59:13.674599 [DEBUG] mod_sofia.c:447 sofia/default/+233242431111@196.44.101.68:5060 Overriding SIP cause 480 with 500 from the other leg
2020-03-16 07:59:13.674599 [DEBUG] mod_sofia.c:453 Channel sofia/default/+233242431111@196.44.101.68:5060 hanging up, cause: NORMAL_CLEARING
2020-03-16 07:59:13.674599 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 500
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:60 sofia/default/+233242431111@196.44.101.68:5060 Standard HANGUP, cause: NORMAL_CLEARING
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233242431111@196.44.101.68:5060) State HANGUP going to sleep
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:620 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_HANGUP -> CS_REPORTING
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_REPORTING (Cur 21 Tot 4674738)
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233242431111@196.44.101.68:5060) State REPORTING
2020-03-16 07:59:13.674599 [INFO] mod_json_cdr.c:271 Process [ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2.cdr.json]
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:174 sofia/default/+233278910821 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233278910821) State REPORTING going to sleep
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:611 (sofia/default/+233278910821) State Change CS_REPORTING -> CS_DESTROY
2020-03-16 07:59:13.674599 [DEBUG] switch_core_session.c:1726 Session 4674738 (sofia/default/+233278910821) Locked, Waiting on external entities
2020-03-16 07:59:13.674599 [NOTICE] switch_core_session.c:1744 Session 4674738 (sofia/default/+233278910821) Ended
2020-03-16 07:59:13.674599 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/+233278910821 [CS_DESTROY]
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:739 (sofia/default/+233278910821) Running State Change CS_DESTROY (Cur 20 Tot 4674738)
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233278910821) State DESTROY
2020-03-16 07:59:13.674599 [DEBUG] mod_sofia.c:364 sofia/default/+233278910821 SOFIA DESTROY
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:181 sofia/default/+233278910821 Standard DESTROY
2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233278910821) State DESTROY going to sleep
2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:174 sofia/default/+233242431111@196.44.101.68:5060 Standard REPORTING, cause: NORMAL_CLEARING
2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233242431111@196.44.101.68:5060) State REPORTING going to sleep
2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:611 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_REPORTING -> CS_DESTROY
2020-03-16 07:59:13.714581 [DEBUG] switch_core_session.c:1726 Session 4674737 (sofia/default/+233242431111@196.44.101.68:5060) Locked, Waiting on external entities
2020-03-16 07:59:13.714581 [NOTICE] switch_core_session.c:1744 Session 4674737 (sofia/default/+233242431111@196.44.101.68:5060) Ended
2020-03-16 07:59:13.714581 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/+233242431111@196.44.101.68:5060 [CS_DESTROY]
2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:739 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_DESTROY (Cur 19 Tot 4674738)
2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233242431111@196.44.101.68:5060) State DESTROY
2020-03-16 07:59:13.714581 [DEBUG] mod_sofia.c:364 sofia/default/+233242431111@196.44.101.68:5060 SOFIA DESTROY
2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:181 sofia/default/+233242431111@196.44.101.68:5060 Standard DESTROY
2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233242431111@196.44.101.68:5060) State DESTROY going to sleep
2020-03-16 07:59:13.854648 [DEBUG] switch_scheduler.c:147 Deleting task 1409255 switch_ivr_schedule_hangup (e11f0fab-6f98-4aec-8a48-d077ef91c8af)
2020-03-16 07:59:14.334640 [NOTICE] switch_channel.c:1118 New Channel sofia/default/+233242431111@196.44.101.68:5060 [d16e1c65-0a8f-4734-8997-4a50023e54ca]
2020-03-16 07:59:14.334640 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_NEW (Cur 20 Tot 4674739)
2020-03-16 07:59:14.334640 [DEBUG] sofia.c:10255 sofia/default/+233242431111@196.44.101.68:5060 receiving invite from 197.251.229.242:1025 version: 1.10.2 -release-13-f7bdd3845a 64bit
2020-03-16 07:59:14.334640 [DEBUG] sofia.c:10349 verifying acl "default" for ip/port 197.251.229.242:0.
2020-03-16 07:59:14.334640 [DEBUG] sofia.c:10378 IP 197.251.229.242 Approved by acl "default[]". Access Granted.
2020-03-16 07:59:14.354597 [DEBUG] sofia.c:11452 Setting NAT mode based on via port
2020-03-16 07:59:14.354597 [DEBUG] sofia.c:7301 Channel sofia/default/+233242431111@196.44.101.68:5060 entering state [received][100]
2020-03-16 07:59:14.354597 [DEBUG] sofia.c:7311 Remote SDP:
v=0
o=3cxPS 514641100800 373242724353 IN IP4 197.251.229.242
s=3cxPS Audio call
c=IN IP4 197.251.229.242
t=0 0
m=audio 9032 RTP/SAVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:px4YQ2itc3UT2Q6e8Qn6JDg5MLG/Z1Ke1agBXQdo
m=audio 9032 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000

2020-03-16 07:59:14.354597 [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:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
2020-03-16 07:59:14.354597 [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:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
2020-03-16 07:59:14.354597 [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:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
2020-03-16 07:59:14.354597 [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:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
2020-03-16 07:59:14.354597 [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:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1822 Found suite AES_CM_128_HMAC_SHA1_80
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1892 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1215 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:RC0PS2qV/Se+E00kDVgysculRHVz0vEYkYMkPwhG]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/+233242431111@196.44.101.68:5060 G729/8000 20 ms 160 samples 8000 bits 1 channels
2020-03-16 07:59:14.354597 [DEBUG] switch_core_codec.c:111 sofia/default/+233242431111@196.44.101.68:5060 Original read codec set to G729:18
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5909 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2020-03-16 07:59:14.354597 [DEBUG] sofia.c:7735 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_NEW -> CS_INIT
2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:604 (sofia/default/+233242431111@196.44.101.68:5060) State NEW
2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_INIT (Cur 20 Tot 4674739)
2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233242431111@196.44.101.68:5060) State INIT
2020-03-16 07:59:14.354597 [DEBUG] mod_sofia.c:93 sofia/default/+233242431111@196.44.101.68:5060 SOFIA INIT
2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:40 sofia/default/+233242431111@196.44.101.68:5060 Standard INIT
2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_INIT -> CS_ROUTING
2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233242431111@196.44.101.68:5060) State INIT going to sleep
2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_ROUTING (Cur 20 Tot 4674739)
2020-03-16 07:59:14.354597 [DEBUG] switch_channel.c:2332 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change DOWN -> RINGING
2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233242431111@196.44.101.68:5060) State ROUTING
2020-03-16 07:59:14.354597 [DEBUG] mod_sofia.c:154 sofia/default/+233242431111@196.44.101.68:5060 SOFIA ROUTING
2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:236 sofia/default/+233242431111@196.44.101.68:5060 Standard ROUTING
2020-03-16 07:59:14.354597 [INFO] mod_dialplan_xml.c:637 Processing +233242431111 <+233242431111>->233278910821 in context default
2020-03-16 07:59:14.354597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2020-03-16 07:59:14.354597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2020-03-16 07:59:14.374586 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: 021f676c-1b86-4c5d-ab5d-83652fc892b0
FreeSWITCH-Hostname: rack-astpp
FreeSWITCH-Switchname: rack-astpp
FreeSWITCH-IPv4: 196.44.101.68
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2020-03-16%2007%3A59%3A14
Event-Date-GMT: Mon,%2016%20Mar%202020%2007%3A59%3A14%20GMT
Event-Date-Timestamp: 1584345554354597
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 281836777
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
Unique-ID: d16e1c65-0a8f-4734-8997-4a50023e54ca
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: %2B233242431111%40196.44.101.68
Channel-Call-UUID: d16e1c65-0a8f-4734-8997-4a50023e54ca
Answer-State: ringing
Channel-Read-Codec-Name: G729
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 8000
Channel-Write-Codec-Name: G729
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 8000
Caller-Direction: inbound
Caller-Logical-Direction: inbound
Caller-Username: %2B233242431111
Caller-Dialplan: XML
Caller-Caller-ID-Name: %2B233242431111
Caller-Caller-ID-Number: %2B233242431111
Caller-Orig-Caller-ID-Name: %2B233242431111
Caller-Orig-Caller-ID-Number: %2B233242431111
Caller-Network-Addr: 197.251.229.242
Caller-ANI: %2B233242431111
Caller-Destination-Number: 233278910821
Caller-Unique-ID: d16e1c65-0a8f-4734-8997-4a50023e54ca
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1584345554334640
Caller-Channel-Created-Time: 1584345554334640
Caller-Channel-Answered-Time: 0
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 0
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Channel-Resurrect-Time: 0
Caller-Channel-Bridged-Time: 0
Caller-Channel-Last-Hold: 0
Caller-Channel-Hold-Accum: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
variable_direction: inbound
variable_uuid: d16e1c65-0a8f-4734-8997-4a50023e54ca
variable_session_id: 4674739
variable_sip_from_user: %2B233242431111
variable_sip_from_port: 5060
variable_sip_from_uri: %2B233242431111%40196.44.101.68%3A5060
variable_sip_from_host: 196.44.101.68
variable_video_media_flow: disabled
variable_text_media_flow: disabled
variable_channel_name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
variable_sip_call_id: BDA7u_G6SBWCFmJboszX2w..
variable_sip_local_network_addr: 196.44.101.68
variable_sip_network_ip: 197.251.229.242
variable_sip_network_port: 1025
variable_sip_invite_stamp: 1584345554334640
variable_sip_received_ip: 197.251.229.242
variable_sip_received_port: 1025
variable_sip_via_protocol: udp
variable_sip_authorized: true
variable_sip_acl_authed_by: default
variable_sip_from_user_stripped: 233242431111
variable_sip_from_tag: ed6d3d79
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%40196.44.101.68%3A5060
variable_recovery_profile_name: default
variable_sip_Remote-Party-ID: %22%2B233242431111%22%20%3Csip%3A%2B233242431111%40196.44.101.68%3A5060%3E%3Bparty%3Dcalling
variable_sip_cid_type: rpid
variable_sip_full_via: SIP/2.0/UDP%20197.251.229.242%3A5060%3Bbranch%3Dz9hG4bK-524287-1---285e821d9b37170b%3Brport%3D1025
variable_sip_from_display: %2B233242431111
variable_sip_full_from: %22%2B233242431111%22%20%3Csip%3A%2B233242431111%40196.44.101.68%3A5060%3E%3Btag%3Ded6d3d79
variable_sip_full_to: %3Csip%3A233278910821%40196.44.101.68%3A5060%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REGISTER,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20INFO,%20MESSAGE,%20UPDATE
variable_sip_req_user: 233278910821
variable_sip_req_port: 5060
variable_sip_req_uri: 233278910821%40196.44.101.68%3A5060
variable_sip_req_host: 196.44.101.68
variable_sip_to_user: 233278910821
variable_sip_to_port: 5060
variable_sip_to_uri: 233278910821%40196.44.101.68%3A5060
variable_sip_to_host: 196.44.101.68
variable_sip_contact_user: %2B233242431111
variable_sip_contact_port: 5060
variable_sip_contact_uri: %2B233242431111%40197.251.229.242%3A5060
variable_sip_contact_host: 197.251.229.242
variable_sip_user_agent: 3CXPhoneSystem%2015.5.15502.6%20(15502)
variable_sip_via_host: 197.251.229.242
variable_sip_via_port: 5060
variable_sip_via_rport: 1025
variable_max_forwards: 70
variable_presence_id: %2B233242431111%40196.44.101.68
variable_sip_nat_detected: true
variable_switch_r_sdp: v%3D0%0D%0Ao%3D3cxPS%20514641100800%20373242724353%20IN%20IP4%20197.251.229.242%0D%0As%3D3cxPS%20Audio%20call%0D%0Ac%3DIN%20IP4%20197.251.229.242%0D%0At%3D0%200%0D%0Am%3Daudio%209032%20RTP/SAVP%200%208%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dcrypto%3A1%20AES_CM_128_HMAC_SHA1_80%20inline%3A6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08%0D%0Aa%3Dcrypto%3A2%20AES_CM_128_HMAC_SHA1_32%20inline%3Apx4YQ2itc3UT2Q6e8Qn6JDg5MLG/Z1Ke1agBXQdo%0D%0Am%3Daudio%209032%20RTP/AVP%200%208%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_bcg729.G729%408000h%4020i%408000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_bcg729.G729%408000h%4020i%408000b
variable_rtp_use_codec_string: G729,PCMA,PCMU
variable_remote_video_media_flow: inactive
variable_remote_text_media_flow: inactive
variable_remote_audio_media_flow: sendrecv
variable_audio_media_flow: sendrecv
variable_rtp_secure_media_negotiated: AES_CM_128_HMAC_SHA1_80
variable_srtp_remote_audio_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3A6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08
variable_srtp_remote_audio_crypto_type: AES_CM_128_HMAC_SHA1_80
variable_rtp_has_crypto: AES_CM_128_HMAC_SHA1_80
variable_rtp_last_audio_local_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3ARC0PS2qV/Se%2BE00kDVgysculRHVz0vEYkYMkPwhG
variable_remote_media_ip: 197.251.229.242
variable_remote_media_port: 9032
variable_rtp_audio_recv_pt: 18
variable_rtp_use_codec_name: G729
variable_rtp_use_codec_fmtp: annexb%3Dno
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: G729%408000h%4020i%401c
variable_read_codec: G729
variable_original_read_codec: G729
variable_read_rate: 8000
variable_original_read_rate: 8000
variable_write_codec: G729
variable_write_rate: 8000
variable_dtmf_type: rfc2833
variable_endpoint_disposition: RECEIVED
variable_call_uuid: d16e1c65-0a8f-4734-8997-4a50023e54ca
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: %2B233242431111
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: %2B233242431111
Hunt-Caller-ID-Number: %2B233242431111
Hunt-Orig-Caller-ID-Name: %2B233242431111
Hunt-Orig-Caller-ID-Number: %2B233242431111
Hunt-Network-Addr: 197.251.229.242
Hunt-ANI: %2B233242431111
Hunt-Destination-Number: 233278910821
Hunt-Unique-ID: d16e1c65-0a8f-4734-8997-4a50023e54ca
Hunt-Source: mod_sofia
Hunt-Context: default
Hunt-Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
Hunt-Profile-Index: 1
Hunt-Profile-Created-Time: 1584345554334640
Hunt-Channel-Created-Time: 1584345554334640
Hunt-Channel-Answered-Time: 0
Hunt-Channel-Progress-Time: 0
Hunt-Channel-Progress-Media-Time: 0
Hunt-Channel-Hangup-Time: 0
Hunt-Channel-Transfer-Time: 0
Hunt-Channel-Resurrect-Time: 0
Hunt-Channel-Bridged-Time: 0
Hunt-Channel-Last-Hold: 0
Hunt-Channel-Hold-Accum: 0
Hunt-Screen-Bit: true
Hunt-Privacy-Hide-Name: false
Hunt-Privacy-Hide-Number: false


2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_name: 
2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] section: dialplan
2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] tag_name: 
2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_value: 
2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 233278910821
2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '233278910821' AND status=0 limit 1
2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number  : +233242431111 / +233242431111
2020-03-16 07:59:14.374586 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1 
2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="233278910821" LIMIT 1
2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="233278910821" limit 1
2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : outbound
2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("197.251.229.242") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "233278910821"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 6425492662]
2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "6425492662" AND deleted = 0 limit 1
2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^233278910821.*' OR blocked_patterns = '^23327891082.*' OR blocked_patterns = '^2332789108.*' OR blocked_patterns = '^233278910.*' OR blocked_patterns = '^23327891.*' OR blocked_patterns = '^2332789.*' OR blocked_patterns = '^233278.*' OR blocked_patterns = '^23327.*' OR blocked_patterns = '^2332.*' OR blocked_patterns = '^233.*' OR blocked_patterns = '^23.*' OR blocked_patterns = '^2.*' OR blocked_patterns ='--') AND accountid = 1367 limit 1 
2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="233278910821" AND A.accountid = '1367' limit 1
2020-03-16 07:59:14.394597 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 233278910821
2020-03-16 07:59:14.394597 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1 
2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="233278910821" LIMIT 1
2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="233278910821" limit 1
2020-03-16 07:59:14.394597 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] New Call direction : outbound
2020-03-16 07:59:14.394597 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1 
2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="233278910821" AND (accountid = 0 OR status = 1) LIMIT 1
2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^233278910821.*' OR patterns = '^23327891082.*' OR patterns = '^2332789108.*' OR patterns = '^233278910.*' OR patterns = '^23327891.*' OR patterns = '^2332789.*' OR patterns = '^233278.*' OR patterns = '^23327.*' OR patterns = '^2332.*' OR patterns = '^233.*' OR patterns = '^23.*' OR patterns = '^2.*' OR patterns ='--') AND accountid = 1367 ORDER BY LENGTH(PKGPTR.patterns) DESC
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '233278910821' OR number = '23327891082' OR number = '2332789108' OR number = '233278910' OR number = '23327891' OR number = '2332789' OR number = '233278' OR number = '23327' OR number = '2332' OR number = '233' OR number = '23' OR number = '2' OR number ='--') OR (number = '233278910821*' OR number = '23327891082*' OR number = '2332789108*' OR number = '233278910*' OR number = '23327891*' OR number = '2332789*' OR number = '233278*' OR number = '23327*' OR number = '2332*' OR number = '233*' OR number = '23*' OR number = '2*' OR number ='--') OR (number = '+233242431111' OR number = '+23324243111' OR number = '+2332424311' OR number = '+233242431' OR number = '+23324243' OR number = '+2332424' OR number = '+233242' OR number = '+23324' OR number = '+2332' OR number = '+233' OR number = '+23' OR number = '+2' OR number = '+' OR number ='--') OR (number = '+233242431111*' OR number = '+23324243111*' OR number = '+2332424311*' OR number = '+233242431*' OR number = '+23324243*' OR number = '+2332424*' OR number = '+233242*' OR number = '+23324*' OR number = '+2332*' OR number = '+233*' OR number = '+23*' OR number = '+2*' OR number = '+*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call Barring Status : -1
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] Caller Id : +233242431111
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] CLI Pool Id : 1
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] Query :Select count(*) as count from dids where number='+233242431111' and status=0
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_COUNT] COUNT: 0
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CIDPOOL] Query :Select number from dids where status=0 and accountid='1367' order by RAND() limit 1
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '233278910821' OR routing_prefix = '23327891082' OR routing_prefix = '2332789108' OR routing_prefix = '233278910' OR routing_prefix = '23327891' OR routing_prefix = '2332789' OR routing_prefix = '233278' OR routing_prefix = '23327' OR routing_prefix = '2332' OR routing_prefix = '233' OR routing_prefix = '23' OR routing_prefix = '2' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information ===================
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 1367
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 6425492662
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 1347.2088
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 40
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 1
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================================================
2020-03-16 07:59:14.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH1: 2
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 2 AND status=0 limit 1 
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 40 AND status = 0
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :outbound
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^233278910821.*' OR pattern = '^23327891082.*' OR pattern = '^2332789108.*' OR pattern = '^233278910.*' OR pattern = '^23327891.*' OR pattern = '^2332789.*' OR pattern = '^233278.*' OR pattern = '^23327.*' OR pattern = '^2332.*' OR pattern = '^233.*' OR pattern = '^23.*' OR pattern = '^2.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 40 OR accountid=1367)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: outbound
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Rates Information ===================
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 1144850
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Includedseconds : 0
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Cost : 0.16000
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] comment : GH-MOB-TIGO
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Country Id : 0
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Accid : 1367
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk ID: 0
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Routing type: 
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ================================================================
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Your1347.2088 balance Accountid 1367 !!!
2020-03-16 07:59:14.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 24 mins!
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Call Max length duration : 24 minutes
2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] [DIALPLAN] User Rate ID : 1144850
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id	:0
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=40 ORDER by id asc
2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^233278910821.*' OR pattern = '^23327891082.*' OR pattern = '^2332789108.*' OR pattern = '^233278910.*' OR pattern = '^23327891.*' OR pattern = '^2332789.*' OR pattern = '^233278.*' OR pattern = '^23327.*' OR pattern = '^2332.*' OR pattern = '^233.*' OR pattern = '^23.*' OR pattern = '^2.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (7) ORDER by TR.cost ASC,TR.precedence ASC, TK.precedence
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Termination Rates Information ===================
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 548686
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Code : ^233.*
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Destination : Ghana Mob
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Free Seconds : 0
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Prefix : ^233.*
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Strip : 
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Prepend : +
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Carrier id : 7
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] carrier_name : MTN_LOCAL
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] dialplan_variable : 
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Failover gateway : 
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Vendor id : 17
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Max channels : 0
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk Name : MTN_LOCAL
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================END OF TERMINATION RATES=======================
2020-03-16 07:59:14.434643 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1367 AND status=0 LIMIT 1
2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] maxlength::::::::: 24
2020-03-16 07:59:14.434643 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 40 AND status = 0
2020-03-16 07:59:14.434643 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate FROM localization WHERE id=(SELECT localization_id from accounts where id = 17) AND status=0 limit 1 
2020-03-16 07:59:14.434643 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 233278910821
2020-03-16 07:59:14.434643 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : +233278910821
2020-03-16 07:59:14.434643 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Generated XML:
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<document type="freeswitch/xml">
<section name="dialplan" description="ASTPP Dialplan">
<context name="default">
<extension name="233278910821">
<condition field="destination_number" expression="233278910821">
<action application="set" data="effective_destination_number=233278910821"/>
<action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
<action application="set" data="bridge_pre_execute_bleg_data=+1440 normal_clearing"/>
<action application="set" data="callstart=2020-03-16 07:59:14"/>
<action application="set" data="hangup_after_bridge=true"/>
<action application="set" data="continue_on_fail=TRUE"/>
<action application="set" data="account_id=1367"/>
<action application="set" data="parent_id=0"/>
<action application="set" data="entity_id=0"/>
<action application="set" data="call_processed=internal"/>
<action application="set" data="call_direction=outbound"/>
<action application="set" data="accountname=PEG IP"/>
<action application="export" data="t38_passthru=true"/>
<action application="set" data="fax_enable_t38=true"/>
<action application="set" data="fax_enable_t38_request=true"/>
<action application="set" data="origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367"/>
<action application="set" data="original_caller_id_name=+233242431111"/>
<action application="set" data="original_caller_id_number=0242436863"/>
<action application="limit" data="db 6425492662 user_6425492662 90 !SWITCH_CONGESTION"/>

<action application="set" data="effective_caller_id_name=+233242431111"/>
<action application="set" data="effective_caller_id_number=+233242431111"/>
<action application="set" data="calltype=STANDARD"/>
<action application="set" data="termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17"/>
<action application="set" data="trunk_id=7"/>
<action application="set" data="provider_id=17"/>
<action application="set" data="rate_flag=1"/>
<action application="set" data="force_trunk_flag=1144850"/>
<action application="export" data="presence_data=trunk_id=7"/>
<action application="set" data="intcall=0"/>
<action application="export" data="presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242"/>
<action application="bridge" data="[leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821"/>
</condition>
</extension>
</context>
</section>
</document>
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 parsing [default->233278910821] continue=false
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Regex (PASS) [233278910821] destination_number(233278910821) =~ /233278910821/ break=on-false
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_destination_number=233278910821) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(bridge_pre_execute_bleg_app=sched_hangup) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(bridge_pre_execute_bleg_data=+1440 normal_clearing) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(callstart=2020-03-16 07:59:14) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(hangup_after_bridge=true) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(continue_on_fail=TRUE) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(account_id=1367) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(parent_id=0) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(entity_id=0) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(call_processed=internal) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(call_direction=outbound) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(accountname=PEG IP) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(t38_passthru=true) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(fax_enable_t38=true) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(fax_enable_t38_request=true) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(original_caller_id_name=+233242431111) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(original_caller_id_number=0242436863) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action limit(db 6425492662 user_6425492662 90 !SWITCH_CONGESTION) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_caller_id_name=+233242431111) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_caller_id_number=+233242431111) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(calltype=STANDARD) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(trunk_id=7) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(provider_id=17) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(rate_flag=1) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(force_trunk_flag=1144850) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(presence_data=trunk_id=7) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(intcall=0) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242) 
Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821) 
2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:287 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_ROUTING -> CS_EXECUTE
2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233242431111@196.44.101.68:5060) State ROUTING going to sleep
2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_EXECUTE (Cur 20 Tot 4674739)
2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:651 (sofia/default/+233242431111@196.44.101.68:5060) State EXECUTE
2020-03-16 07:59:14.434643 [DEBUG] mod_sofia.c:209 sofia/default/+233242431111@196.44.101.68:5060 SOFIA EXECUTE
2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:329 sofia/default/+233242431111@196.44.101.68:5060 Standard EXECUTE
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_destination_number=233278910821)
2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_destination_number]=[233278910821]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(bridge_pre_execute_bleg_app=sched_hangup)
2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [bridge_pre_execute_bleg_app]=[sched_hangup]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(bridge_pre_execute_bleg_data=+1440 normal_clearing)
2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [bridge_pre_execute_bleg_data]=[+1440 normal_clearing]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(callstart=2020-03-16 07:59:14)
2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [callstart]=[2020-03-16 07:59:14]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(hangup_after_bridge=true)
2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(continue_on_fail=TRUE)
2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [continue_on_fail]=[TRUE]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(account_id=1367)
2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [account_id]=[1367]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(parent_id=0)
2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [parent_id]=[0]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(entity_id=0)
2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [entity_id]=[0]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(call_processed=internal)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [call_processed]=[internal]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(call_direction=outbound)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [call_direction]=[outbound]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(accountname=PEG IP)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [accountname]=[PEG IP]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(t38_passthru=true)
2020-03-16 07:59:14.454644 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [t38_passthru]=[true]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(fax_enable_t38=true)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [fax_enable_t38]=[true]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(fax_enable_t38_request=true)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [fax_enable_t38_request]=[true]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [origination_rates]=[ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(original_caller_id_name=+233242431111)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [original_caller_id_name]=[+233242431111]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(original_caller_id_number=0242436863)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [original_caller_id_number]=[0242436863]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 limit(db 6425492662 user_6425492662 90 !SWITCH_CONGESTION)
2020-03-16 07:59:14.454644 [DEBUG] switch_limit.c:124 incr called: 6425492662_user_6425492662 max:90, interval:0
2020-03-16 07:59:14.454644 [DEBUG] mod_db.c:194 Usage for 6425492662_user_6425492662 is now 1/90
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_caller_id_name=+233242431111)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_caller_id_name]=[+233242431111]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_caller_id_number=+233242431111)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_caller_id_number]=[+233242431111]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(calltype=STANDARD)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [calltype]=[STANDARD]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [termination_rates]=[ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(trunk_id=7)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [trunk_id]=[7]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(provider_id=17)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [provider_id]=[17]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(rate_flag=1)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [rate_flag]=[1]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(force_trunk_flag=1144850)
2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [force_trunk_flag]=[1144850]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(presence_data=trunk_id=7)
2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=7]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(intcall=0)
2020-03-16 07:59:14.474641 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [intcall]=[0]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242)
2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242]
EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821)
2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [t38_passthru]=[true] to event
2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242] to event
2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242] to event
2020-03-16 07:59:14.474641 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables
2020-03-16 07:59:14.474641 [DEBUG] switch_ivr_originate.c:2760 Parsing session specific variables
2020-03-16 07:59:14.474641 [NOTICE] switch_channel.c:1118 New Channel sofia/default/+233278910821 [f9621b72-aa88-489b-a428-c72f1da5d0f5]
2020-03-16 07:59:14.474641 [DEBUG] mod_sofia.c:5089 (sofia/default/+233278910821) State Change CS_NEW -> CS_INIT
2020-03-16 07:59:14.474641 [DEBUG] switch_ivr_originate.c:3067 sofia/default/+233278910821 Setting leg timeout to 30
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_INIT (Cur 21 Tot 4674740)
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233278910821) State INIT
2020-03-16 07:59:14.474641 [DEBUG] mod_sofia.c:93 sofia/default/+233278910821 SOFIA INIT
2020-03-16 07:59:14.474641 [DEBUG] sofia_glue.c:1618 sofia/default/+233278910821 sending invite version: 1.10.2 -release-13-f7bdd3845a 64bit
Local SDP:
v=0
o=FreeSWITCH 1584318802 1584318803 IN IP4 196.44.101.68
s=FreeSWITCH
c=IN IP4 196.44.101.68
t=0 0
m=audio 26752 RTP/AVP 18 8 0 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:40 sofia/default/+233278910821 Standard INIT
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+233278910821) State Change CS_INIT -> CS_ROUTING
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233278910821) State INIT going to sleep
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_ROUTING (Cur 21 Tot 4674740)
2020-03-16 07:59:14.474641 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [calling][0]
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233278910821) State ROUTING
2020-03-16 07:59:14.474641 [DEBUG] mod_sofia.c:154 sofia/default/+233278910821 SOFIA ROUTING
2020-03-16 07:59:14.474641 [DEBUG] switch_ivr_originate.c:67 (sofia/default/+233278910821) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233278910821) State ROUTING going to sleep
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_CONSUME_MEDIA (Cur 21 Tot 4674740)
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:663 (sofia/default/+233278910821) State CONSUME_MEDIA
2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:663 (sofia/default/+233278910821) State CONSUME_MEDIA going to sleep
2020-03-16 07:59:14.794648 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [proceeding][183]
2020-03-16 07:59:14.794648 [DEBUG] sofia.c:7311 Remote SDP:
v=0
o=- 6982403 6982403 IN IP4 196.201.34.20
s=-
c=IN IP4 196.201.34.70
t=0 0
a=sendrecv
m=audio 27444 RTP/AVP 18 101
c=IN IP4 196.201.34.70
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=maxptime:40

2020-03-16 07:59:14.794648 [NOTICE] sofia.c:7314 Pre-Answer sofia/default/+233278910821!
2020-03-16 07:59:14.794648 [DEBUG] switch_channel.c:3565 (sofia/default/+233278910821) Callstate Change DOWN -> EARLY
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[G729:18:8000:20:8000:1]
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[PCMA:8:8000:20:64000:1]
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[PCMU:0:8000:20:64000:1]
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/+233278910821 G729/8000 20 ms 160 samples 8000 bits 1 channels
2020-03-16 07:59:14.794648 [DEBUG] switch_core_codec.c:111 sofia/default/+233278910821 Original read codec set to G729:18
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5909 sofia/default/+233278910821 Set 2833 dtmf send payload to 101 recv payload to 101
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+233278910821] 196.44.101.68 port 26752 -> 196.201.34.70 port 27444 codec: 18 ms: 20
2020-03-16 07:59:14.794648 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
2020-03-16 07:59:14.794648 [INFO] switch_ivr_originate.c:3758 Sending early media
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8973 sofia/default/+233278910821 Set 2833 dtmf send payload to 101
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+233242431111@196.44.101.68:5060] 196.44.101.68 port 16428 -> 197.251.229.242 port 9032 codec: 18 ms: 20
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8980 sofia/default/+233278910821 Set 2833 dtmf receive payload to 101
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:9003 sofia/default/+233278910821 Set rtp dtmf delay to 40
2020-03-16 07:59:14.794648 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8973 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf send payload to 101
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8980 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf receive payload to 101
2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:9003 sofia/default/+233242431111@196.44.101.68:5060 Set rtp dtmf delay to 40
2020-03-16 07:59:14.794648 [INFO] switch_rtp.c:4212 Activating audio Secure RTP SEND
2020-03-16 07:59:14.794648 [INFO] switch_rtp.c:4190 Activating audio Secure RTP RECV
2020-03-16 07:59:14.794648 [DEBUG] switch_core_sqldb.c:2827 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
2020-03-16 07:59:14.794648 [DEBUG] switch_core_sqldb.c:2827 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
2020-03-16 07:59:14.794648 [DEBUG] mod_sofia.c:2593 Ring SDP:
v=0
o=FreeSWITCH 1584329126 1584329127 IN IP4 196.44.101.68
s=FreeSWITCH
c=IN IP4 196.44.101.68
t=0 0
m=audio 16428 RTP/SAVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
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:RC0PS2qV/Se+E00kDVgysculRHVz0vEYkYMkPwhG
m=audio 0 RTP/AVP 19

2020-03-16 07:59:14.794648 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/+233242431111@196.44.101.68:5060!
2020-03-16 07:59:14.794648 [DEBUG] switch_channel.c:3565 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change RINGING -> EARLY
2020-03-16 07:59:14.794648 [DEBUG] switch_ivr_originate.c:3809 Originate Resulted in Success: [sofia/default/+233278910821]
2020-03-16 07:59:14.814657 [DEBUG] switch_ivr_bridge.c:1796 (sofia/default/+233278910821) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2020-03-16 07:59:14.814657 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_EXCHANGE_MEDIA (Cur 21 Tot 4674740)
2020-03-16 07:59:14.814657 [DEBUG] switch_core_state_machine.c:654 (sofia/default/+233278910821) State EXCHANGE_MEDIA
2020-03-16 07:59:14.814657 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
2020-03-16 07:59:14.854641 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
2020-03-16 07:59:14.994641 [DEBUG] switch_ivr_bridge.c:658 sofia/default/+233278910821 Bridge execute app sched_hangup(+1440 normal_clearing)
2020-03-16 07:59:15.014649 [DEBUG] switch_ivr.c:632 sofia/default/+233278910821 Command Execute [depth=0] sched_hangup(+1440 normal_clearing)
EXECUTE [depth=0] sofia/default/+233278910821 sched_hangup(+1440 normal_clearing)