2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [INFO] mod_dialplan_xml.c:637 Processing 27104760600 <27104760600>->0823103007 in context public 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->unloop] continue=false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->call_debug] continue=true 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Freeswitch] continue=true 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_Freeswitch] ${network_addr}(154.118.252.35) =~ /^(154\.118\.252\.36|154\.118\.252\.37)$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_FusionPBX] continue=true 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_FusionPBX] ${sip_h_X-AUTH-IP}(197.184.81.134) =~ /^154\.118\.252\.39$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Telkom] continue=false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_Telkom] destination_number(0823103007) =~ /^D8990(.*)$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Vodacom] continue=false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_Vodacom] destination_number(0823103007) =~ /^\+?27D899?(.*)$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_MNP] continue=false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_MNP] destination_number(0823103007) =~ /^\+?27?(.*)$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Vodacom] continue=false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_Vodacom] destination_number(0823103007) =~ /^0027D899(.*)$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Vodacom] continue=false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_Vodacom] destination_number(0823103007) =~ /^27D899(.*)$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Bitco_and_others] continue=false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (PASS) [From_Bitco_and_others] destination_number(0823103007) =~ /^0(.*)$/ break=on-false 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action set(destination_number=27823103007) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action set(inside_call=true) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action export(fax_enable_t38_request=true) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action export(fax_enable_t38=true) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action lua(/usr/local/freeswitch/scripts/cgrtbilling/cgrtbilling_did_v2.luac) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State Change CS_ROUTING -> CS_EXECUTE 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State ROUTING going to sleep 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) Running State Change CS_EXECUTE (Cur 711 Tot 780256) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State EXECUTE 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] mod_sofia.c:198 sofia/internal/27104760600@sbc.brightnetworks.co.za. SOFIA EXECUTE 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:328 sofia/internal/27104760600@sbc.brightnetworks.co.za. Standard EXECUTE 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. set(destination_number=27823103007) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] mod_dptools.c:1548 SET sofia/internal/27104760600@sbc.brightnetworks.co.za. [destination_number]=[27823103007] 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. set(inside_call=true) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] mod_dptools.c:1548 SET sofia/internal/27104760600@sbc.brightnetworks.co.za. [inside_call]=[true] 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. export(fax_enable_t38_request=true) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [fax_enable_t38_request]=[true] 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. export(fax_enable_t38=true) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [fax_enable_t38]=[true] 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. lua(/usr/local/freeswitch/scripts/cgrtbilling/cgrtbilling_did_v2.luac) 2018-10-01 10:44:07.773592 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0fefd9e020 Connected. 2018-10-01 10:44:07.773592 [NOTICE] switch_cpp.cpp:1365 SELECT management_did.did_number, management_did.status, management_tenant.name, management_tenant.id as tenant_id FROM management_did INNER JOIN management_tenant ON management_did.tenant_id = management_tenant.id WHERE management_did.did_number = '27823103007' 2018-10-01 10:44:07.773592 [ALERT] switch_cpp.cpp:1365 <------------ The DID 27823103007 has not been found ------------> 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(proto_specific_hangup_cause, sip:404) 2018-10-01 10:44:07.773592 [WARNING] switch_cpp.cpp:1365 ------->2515b661-970d-43c3-81b1-dc3edf6f2cc0 - WS CALL Set value :.....: proto_specific_hangup_cause sip:404<------------- 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_cpp.cpp:721 CoreSession::hangup 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [NOTICE] switch_cpp.cpp:723 Hangup sofia/internal/27104760600@sbc.brightnetworks.co.za. [CS_EXECUTE] [UNALLOCATED_NUMBER] 2018-10-01 10:44:07.773592 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0fefd9e020 released. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_cpp.cpp:1112 sofia/internal/27104760600@sbc.brightnetworks.co.za. destroy/unlink session from object 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_session.c:2815 sofia/internal/27104760600@sbc.brightnetworks.co.za. skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State EXECUTE going to sleep 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) Running State Change CS_HANGUP (Cur 711 Tot 780256) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) Callstate Change RINGING -> HANGUP 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State HANGUP 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] mod_sofia.c:438 Channel sofia/internal/27104760600@sbc.brightnetworks.co.za. hanging up, cause: UNALLOCATED_NUMBER 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 404 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:60 sofia/internal/27104760600@sbc.brightnetworks.co.za. Standard HANGUP, cause: UNALLOCATED_NUMBER 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State HANGUP going to sleep 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State Change CS_HANGUP -> CS_REPORTING 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) Running State Change CS_REPORTING (Cur 711 Tot 780256) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State REPORTING 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [INFO] mod_json_cdr.c:271 Process [2515b661-970d-43c3-81b1-dc3edf6f2cc0.cdr.json] 01f9ff26-c456-4dbf-aab9-33e10170eb17 2018-10-01 10:44:07.793593 [DEBUG] switch_rtp.c:5134 Send middle packet for [5] ts=4665992 dur=960/960/1280 seq=60327 lw=4666792 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [DEBUG] switch_core_state_machine.c:174 sofia/internal/27104760600@sbc.brightnetworks.co.za. Standard REPORTING, cause: UNALLOCATED_NUMBER 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State REPORTING going to sleep 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State Change CS_REPORTING -> CS_DESTROY 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [DEBUG] switch_core_session.c:1665 Session 780256 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) Locked, Waiting on external entities 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [NOTICE] switch_core_session.c:1683 Session 780256 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) Ended 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/27104760600@sbc.brightnetworks.co.za. [CS_DESTROY] 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) Running State Change CS_DESTROY (Cur 710 Tot 780256) 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State DESTROY 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [DEBUG] mod_sofia.c:343 sofia/internal/27104760600@sbc.brightnetworks.co.za. SOFIA DESTROY 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [DEBUG] switch_core_state_machine.c:181 sofia/internal/27104760600@sbc.brightnetworks.co.za. Standard DESTROY 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.793593 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/27104760600@sbc.brightnetworks.co.za.) State DESTROY going to sleep 01f9ff26-c456-4dbf-aab9-33e10170eb17 2018-10-01 10:44:07.813594 [DEBUG] switch_rtp.c:5134 Send middle packet for [5] ts=4665992 dur=1120/1120/1280 seq=60328 lw=4666952 01f9ff26-c456-4dbf-aab9-33e10170eb17 2018-10-01 10:44:07.833592 [DEBUG] switch_rtp.c:5134 Send end packet for [5] ts=4665992 dur=1280/1280/1280 seq=60329 lw=4666952 01f9ff26-c456-4dbf-aab9-33e10170eb17 2018-10-01 10:44:07.833592 [DEBUG] switch_rtp.c:5134 Send end packet for [5] ts=4665992 dur=1280/1280/1280 seq=60330 lw=4666952 01f9ff26-c456-4dbf-aab9-33e10170eb17 2018-10-01 10:44:07.833592 [DEBUG] switch_rtp.c:5134 Send end packet for [5] ts=4665992 dur=1280/1280/1280 seq=60331 lw=4666952 01f9ff26-c456-4dbf-aab9-33e10170eb17 2018-10-01 10:44:07.833592 [DEBUG] switch_rtp.c:5082 Queue digit delay of 40ms c65c5f64-f917-43a6-9aa3-61d9e69e1cb5 2018-10-01 10:44:07.833592 [INFO] switch_rtp.c:7231 Auto Changing audio port from 10.192.0.162:27892 to 10.192.0.162:2996 55d04b27-66ac-463c-b404-b9d9a1c16915 2018-10-01 10:44:07.853594 [DEBUG] sofia.c:7084 Channel sofia/external/0800203925 entering state [completing][200] 55d04b27-66ac-463c-b404-b9d9a1c16915 2018-10-01 10:44:07.853594 [DEBUG] sofia.c:7094 Remote SDP: 55d04b27-66ac-463c-b404-b9d9a1c16915 v=0 55d04b27-66ac-463c-b404-b9d9a1c16915 o=CvtVoice-2 1811789279 1811789279 IN IP4 154.118.252.38 55d04b27-66ac-463c-b404-b9d9a1c16915 s=CvtVoice-2 55d04b27-66ac-463c-b404-b9d9a1c16915 c=IN IP4 154.118.252.38 55d04b27-66ac-463c-b404-b9d9a1c16915 t=0 0 55d04b27-66ac-463c-b404-b9d9a1c16915 m=audio 23602 RTP/AVP 18 101 55d04b27-66ac-463c-b404-b9d9a1c16915 a=rtpmap:18 G729/8000 55d04b27-66ac-463c-b404-b9d9a1c16915 a=fmtp:18 annexb=no 55d04b27-66ac-463c-b404-b9d9a1c16915 a=rtpmap:101 telephone-event/8000 55d04b27-66ac-463c-b404-b9d9a1c16915 a=fmtp:101 0-16