1. 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
2. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->unloop] continue=false
3. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
4. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
5. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->call_debug] continue=true
6. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
7. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Freeswitch] continue=true
8. 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
9. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_FusionPBX] continue=true
10. 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
11. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Telkom] continue=false
12. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_Telkom] destination_number(0823103007) =~ /^D8990(.*)$/ break=on-false
13. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Vodacom] continue=false
14. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_Vodacom] destination_number(0823103007) =~ /^\+?27D899?(.*)$/ break=on-false
15. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_MNP] continue=false
16. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_MNP] destination_number(0823103007) =~ /^\+?27?(.*)$/ break=on-false
17. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Vodacom] continue=false
18. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_Vodacom] destination_number(0823103007) =~ /^0027D899(.*)$/ break=on-false
19. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Vodacom] continue=false
20. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Regex (FAIL) [From_Vodacom] destination_number(0823103007) =~ /^27D899(.*)$/ break=on-false
21. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. parsing [public->From_Bitco_and_others] continue=false
22. 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
23. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action set(destination_number=27823103007)
24. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action set(inside_call=true)
25. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action export(fax_enable_t38_request=true)
26. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action export(fax_enable_t38=true)
27. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 Dialplan: sofia/internal/27104760600@sbc.brightnetworks.co.za. Action lua(/usr/local/freeswitch/scripts/cgrtbilling/cgrtbilling_did_v2.luac)
28. 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
29. 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
30. 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)
31. 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
32. 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
33. 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
34. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. set(destination_number=27823103007)
35. 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]
36. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. set(inside_call=true)
37. 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]
38. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. export(fax_enable_t38_request=true)
39. 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]
40. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. export(fax_enable_t38=true)
41. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [fax_enable_t38]=[true]
42. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 EXECUTE sofia/internal/27104760600@sbc.brightnetworks.co.za. lua(/usr/local/freeswitch/scripts/cgrtbilling/cgrtbilling_did_v2.luac)
43. 2018-10-01 10:44:07.773592 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0fefd9e020 Connected.
44. 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
45.         FROM management_did
46.         INNER JOIN management_tenant ON management_did.tenant_id = management_tenant.id
47.         WHERE management_did.did_number = '27823103007'
48.  
49. 2018-10-01 10:44:07.773592 [ALERT] switch_cpp.cpp:1365 <------------  The DID 27823103007 has not been found  ------------>
50.  
51. 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)
52. 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<-------------
53. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] switch_cpp.cpp:721 CoreSession::hangup
54. 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]
55. 2018-10-01 10:44:07.773592 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0fefd9e020 released.
56. 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
57. 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)
58. 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
59. 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)
60. 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
61. 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
62. 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
63. 2515b661-970d-43c3-81b1-dc3edf6f2cc0 2018-10-01 10:44:07.773592 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 404
64. 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
65. 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
66. 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
67. 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)
68. 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
69. 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]
70. 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
71. 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
72. 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
73. 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
74. 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
75. 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
76. 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]
77. 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)
78. 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
79. 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
80. 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
81. 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
82. 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
83. 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
84. 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
85. 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
86. 01f9ff26-c456-4dbf-aab9-33e10170eb17 2018-10-01 10:44:07.833592 [DEBUG] switch_rtp.c:5082 Queue digit delay of 40ms
87. 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
88. 55d04b27-66ac-463c-b404-b9d9a1c16915 2018-10-01 10:44:07.853594 [DEBUG] sofia.c:7084 Channel sofia/external/0800203925 entering state [completing][200]
89. 55d04b27-66ac-463c-b404-b9d9a1c16915 2018-10-01 10:44:07.853594 [DEBUG] sofia.c:7094 Remote SDP:
90. 55d04b27-66ac-463c-b404-b9d9a1c16915 v=0
91. 55d04b27-66ac-463c-b404-b9d9a1c16915 o=CvtVoice-2 1811789279 1811789279 IN IP4 154.118.252.38
92. 55d04b27-66ac-463c-b404-b9d9a1c16915 s=CvtVoice-2
93. 55d04b27-66ac-463c-b404-b9d9a1c16915 c=IN IP4 154.118.252.38
94. 55d04b27-66ac-463c-b404-b9d9a1c16915 t=0 0
95. 55d04b27-66ac-463c-b404-b9d9a1c16915 m=audio 23602 RTP/AVP 18 101
96. 55d04b27-66ac-463c-b404-b9d9a1c16915 a=rtpmap:18 G729/8000
97. 55d04b27-66ac-463c-b404-b9d9a1c16915 a=fmtp:18 annexb=no
98. 55d04b27-66ac-463c-b404-b9d9a1c16915 a=rtpmap:101 telephone-event/8000
99. 55d04b27-66ac-463c-b404-b9d9a1c16915 a=fmtp:101 0-16
100.  
101.