1. span style="color: goldenrod; font-weight: bold;"> 2022-10-03 12:49:03.188834 76.47% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
2. 2022-10-03 12:49:03.208900 76.47% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1515121325@172.31.40.49:5074 [91d771dc-94b0-4ecb-9ce3-929e0cb7ec7e]
3. 2022-10-03 12:49:03.208900 76.47% [DEBUG] mod_sofia.c:5121 (sofia/default/1515121325@172.31.40.49:5074) State Change CS_NEW -> CS_INIT
4. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49:5074) Running State Change CS_INIT (Cur 1 Tot 178)
5. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@172.31.40.49:5074) State INIT
6. 2022-10-03 12:49:03.208900 76.47% [DEBUG] mod_sofia.c:97 sofia/default/1515121325@172.31.40.49:5074 SOFIA INIT
7. 2022-10-03 12:49:03.208900 76.47% [INFO] sofia_glue.c:1651 sofia/default/1515121325@172.31.40.49:5074 sending invite call-id: (null)
8. 2022-10-03 12:49:03.208900 76.47% [DEBUG] sofia_glue.c:1654 sofia/default/1515121325@172.31.40.49:5074 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
9. Local SDP:
10. v=0
11. o=FreeSWITCH 1664769281 1664769282 IN IP4 172.31.40.49
12. s=FreeSWITCH
13. c=IN IP4 172.31.40.49
14. t=0 0
15. m=audio 32062 RTP/AVP 8 0 101
16. a=rtpmap:8 PCMA/8000
17. a=rtpmap:0 PCMU/8000
18. a=rtpmap:101 telephone-event/8000
19. a=fmtp:101 0-15
20. a=ptime:20
21. a=sendrecv
22.  
23. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:40 sofia/default/1515121325@172.31.40.49:5074 Standard INIT
24. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1515121325@172.31.40.49:5074) State Change CS_INIT -> CS_ROUTING
25. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@172.31.40.49:5074) State INIT going to sleep
26. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49:5074) Running State Change CS_ROUTING (Cur 1 Tot 178)
27. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@172.31.40.49:5074) State ROUTING
28. 2022-10-03 12:49:03.208900 76.47% [DEBUG] mod_sofia.c:158 sofia/default/1515121325@172.31.40.49:5074 SOFIA ROUTING
29. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1515121325@172.31.40.49:5074) State Change CS_ROUTING -> CS_CONSUME_MEDIA
30. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@172.31.40.49:5074) State ROUTING going to sleep
31. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49:5074) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 178)
32. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1515121325@172.31.40.49:5074) State CONSUME_MEDIA
33. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1515121325@172.31.40.49:5074) State CONSUME_MEDIA going to sleep
34. 2022-10-03 12:49:03.208900 76.47% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@172.31.40.49:5074 entering state [calling][0]
35. 2022-10-03 12:49:03.208900 76.47% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1515121325@172.31.40.49 [28262aab-007e-4552-ac2f-c6b654a059ac]
36. 2022-10-03 12:49:03.208900 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49) Running State Change CS_NEW (Cur 2 Tot 179)
37. 2022-10-03 12:49:03.208900 76.47% [INFO] sofia.c:10462 sofia/default/1515121325@172.31.40.49 receiving invite from 172.31.40.49:5074 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 99b6e811-bdbc-123b-66b8-026398ac7998
38. 2022-10-03 12:49:03.208900 76.47% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 172.31.40.49:0.
39. 2022-10-03 12:49:03.228829 76.47% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
40. 2022-10-03 12:49:03.228829 76.47% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@172.31.40.49 entering state [received][100]
41. 2022-10-03 12:49:03.228829 76.47% [DEBUG] sofia.c:7509 Remote SDP:
42. v=0
43. o=FreeSWITCH 1664769281 1664769282 IN IP4 172.31.40.49
44. s=FreeSWITCH
45. c=IN IP4 172.31.40.49
46. t=0 0
47. m=audio 32062 RTP/AVP 8 0 101
48. a=rtpmap:8 PCMA/8000
49. a=rtpmap:0 PCMU/8000
50. a=rtpmap:101 telephone-event/8000
51. a=fmtp:101 0-15
52. a=ptime:20
53.  
54. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
55. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
56. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
57. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
58. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
59. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
60. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
61. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1515121325@172.31.40.49 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
62. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_codec.c:111 sofia/default/1515121325@172.31.40.49 Original read codec set to PCMA:8
63. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
64. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_media.c:5973 sofia/default/1515121325@172.31.40.49 Set 2833 dtmf send payload to 101 recv payload to 101
65. 2022-10-03 12:49:03.228829 76.47% [DEBUG] sofia.c:7933 (sofia/default/1515121325@172.31.40.49) State Change CS_NEW -> CS_INIT
66. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1515121325@172.31.40.49) State NEW
67. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49) Running State Change CS_INIT (Cur 2 Tot 179)
68. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@172.31.40.49) State INIT
69. 2022-10-03 12:49:03.228829 76.47% [DEBUG] mod_sofia.c:97 sofia/default/1515121325@172.31.40.49 SOFIA INIT
70. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_state_machine.c:40 sofia/default/1515121325@172.31.40.49 Standard INIT
71. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1515121325@172.31.40.49) State Change CS_INIT -> CS_ROUTING
72. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@172.31.40.49) State INIT going to sleep
73. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49) Running State Change CS_ROUTING (Cur 2 Tot 179)
74. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_channel.c:2380 (sofia/default/1515121325@172.31.40.49) Callstate Change DOWN -> RINGING
75. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@172.31.40.49) State ROUTING
76. 2022-10-03 12:49:03.228829 76.47% [DEBUG] mod_sofia.c:158 sofia/default/1515121325@172.31.40.49 SOFIA ROUTING
77. 2022-10-03 12:49:03.228829 76.47% [DEBUG] switch_core_state_machine.c:230 sofia/default/1515121325@172.31.40.49 Standard ROUTING
78. 2022-10-03 12:49:03.228829 76.47% [INFO] mod_dialplan_xml.c:639 Processing 9497482480 <1515121325>->1515121325 in context default
79. 2022-10-03 12:49:03.288877 76.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
80. 2022-10-03 12:49:03.288877 76.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
81. 2022-10-03 12:49:03.328834 76.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1515121325
82. 2022-10-03 12:49:03.328834 76.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Clicktocall Destination Number 1515121325
83. 2022-10-03 12:49:03.328834 76.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1515121325' AND status=0 limit 1
84. 2022-10-03 12:49:03.328834 76.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 9497482480 / 1515121325
85. 2022-10-03 12:49:03.328834 76.47% [DEBUG] switch_cpp.cpp:1465 [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 ="1515121325" LIMIT 1
86. 2022-10-03 12:49:03.328834 76.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec 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="1515121325" limit 1
87. 2022-10-03 12:49:03.328834 76.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
88. 2022-10-03 12:49:03.328834 76.47% [DEBUG] switch_cpp.cpp:1465 [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("172.31.40.49") 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 "1515121325"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
89. 2022-10-03 12:49:03.348863 76.47% [ERR] switch_core_sqldb.c:1310 ERR: [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("172.31.40.49") 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 "1515121325"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1]
90. [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.30]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',<cache>(-(1))))) - 1)'
91. ]
92. 2022-10-03 12:49:03.348863 76.47% [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: assertion failed!
93. stack traceback:
94.         [C]: in function 'assert'
95.         /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: in function </usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:150>
96.         (...tail calls...)
97.         ...hare/freeswitch/scripts/astpp/scripts/astpp.dialplan.lua:119: in main chunk
98.         [C]: in function 'dofile'
99.         /usr/share/freeswitch/scripts/astpp/astpp.lua:85: in main chunk
100. 2022-10-03 12:49:03.348863 76.47% [ERR] mod_lua.cpp:270 LUA script parse/execute error!
101. 2022-10-03 12:49:03.348863 76.47% [WARNING] mod_dialplan_xml.c:669 Context default not found
102. 2022-10-03 12:49:03.348863 76.47% [INFO] switch_core_state_machine.c:306 No Route, Aborting
103. 2022-10-03 12:49:03.348863 76.47% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/1515121325@172.31.40.49 [CS_ROUTING] [NO_ROUTE_DESTINATION]
104. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@172.31.40.49) State ROUTING going to sleep
105. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49) Running State Change CS_HANGUP (Cur 2 Tot 179)
106. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1515121325@172.31.40.49) Callstate Change RINGING -> HANGUP
107. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@172.31.40.49) State HANGUP
108. 2022-10-03 12:49:03.348863 76.47% [DEBUG] mod_sofia.c:468 Channel sofia/default/1515121325@172.31.40.49 hanging up, cause: NO_ROUTE_DESTINATION
109. 2022-10-03 12:49:03.348863 76.47% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
110. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:59 sofia/default/1515121325@172.31.40.49 Standard HANGUP, cause: NO_ROUTE_DESTINATION
111. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@172.31.40.49) State HANGUP going to sleep
112. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1515121325@172.31.40.49) State Change CS_HANGUP -> CS_REPORTING
113. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49) Running State Change CS_REPORTING (Cur 2 Tot 179)
114. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@172.31.40.49) State REPORTING
115. 2022-10-03 12:49:03.348863 76.47% [DEBUG] sofia.c:6678 Remote Reason: 3
116. 2022-10-03 12:49:03.348863 76.47% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@172.31.40.49:5074 entering state [terminated][404]
117. 2022-10-03 12:49:03.348863 76.47% [NOTICE] sofia.c:8738 Hangup sofia/default/1515121325@172.31.40.49:5074 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
118. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49:5074) Running State Change CS_HANGUP (Cur 2 Tot 179)
119. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1515121325@172.31.40.49:5074) Callstate Change DOWN -> HANGUP
120. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@172.31.40.49:5074) State HANGUP
121. 2022-10-03 12:49:03.348863 76.47% [DEBUG] mod_sofia.c:468 Channel sofia/default/1515121325@172.31.40.49:5074 hanging up, cause: NO_ROUTE_DESTINATION
122. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:59 sofia/default/1515121325@172.31.40.49:5074 Standard HANGUP, cause: NO_ROUTE_DESTINATION
123. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@172.31.40.49:5074) State HANGUP going to sleep
124. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1515121325@172.31.40.49:5074) State Change CS_HANGUP -> CS_REPORTING
125. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@172.31.40.49:5074) Running State Change CS_REPORTING (Cur 2 Tot 179)
126. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@172.31.40.49:5074) State REPORTING
127. 2022-10-03 12:49:03.348863 76.47% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
128. 2022-10-03 12:49:03.348863 76.47% [INFO] mod_json_cdr.c:271 Process [28262aab-007e-4552-ac2f-c6b654a059ac.cdr.json]
129. 2022-10-03 12:49:03.348863 76.47% [INFO] mod_json_cdr.c:271 Process [91d771dc-94b0-4ecb-9ce3-929e0cb7ec7e.cdr.json]
130. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:168 sofia/default/1515121325@172.31.40.49:5074 Standard REPORTING, cause: NO_ROUTE_DESTINATION
131. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@172.31.40.49:5074) State REPORTING going to sleep
132. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1515121325@172.31.40.49:5074) State Change CS_REPORTING -> CS_DESTROY
133. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_session.c:1753 Session 178 (sofia/default/1515121325@172.31.40.49:5074) Locked, Waiting on external entities
134. 2022-10-03 12:49:03.368838 76.47% [NOTICE] switch_core_session.c:1771 Session 178 (sofia/default/1515121325@172.31.40.49:5074) Ended
135. 2022-10-03 12:49:03.368838 76.47% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1515121325@172.31.40.49:5074 [CS_DESTROY]
136. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1515121325@172.31.40.49:5074) Running State Change CS_DESTROY (Cur 1 Tot 179)
137. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@172.31.40.49:5074) State DESTROY
138. 2022-10-03 12:49:03.368838 76.47% [DEBUG] mod_sofia.c:379 sofia/default/1515121325@172.31.40.49:5074 SOFIA DESTROY
139. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:175 sofia/default/1515121325@172.31.40.49:5074 Standard DESTROY
140. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@172.31.40.49:5074) State DESTROY going to sleep
141. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:168 sofia/default/1515121325@172.31.40.49 Standard REPORTING, cause: NO_ROUTE_DESTINATION
142. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@172.31.40.49) State REPORTING going to sleep
143. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1515121325@172.31.40.49) State Change CS_REPORTING -> CS_DESTROY
144. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_session.c:1753 Session 179 (sofia/default/1515121325@172.31.40.49) Locked, Waiting on external entities
145. 2022-10-03 12:49:03.368838 76.47% [NOTICE] switch_core_session.c:1771 Session 179 (sofia/default/1515121325@172.31.40.49) Ended
146. 2022-10-03 12:49:03.368838 76.47% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1515121325@172.31.40.49 [CS_DESTROY]
147. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1515121325@172.31.40.49) Running State Change CS_DESTROY (Cur 0 Tot 179)
148. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@172.31.40.49) State DESTROY
149. 2022-10-03 12:49:03.368838 76.47% [DEBUG] mod_sofia.c:379 sofia/default/1515121325@172.31.40.49 SOFIA DESTROY
150. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:175 sofia/default/1515121325@172.31.40.49 Standard DESTROY
151. 2022-10-03 12:49:03.368838 76.47% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@172.31.40.49) State DESTROY going to sleep
152. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
153. 2022-10-03 12:49:05.368836 76.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/2536854752@172.31.40.49:5074 [d689c65e-6cca-46f9-ac45-49cb3de8b52e]
154. 2022-10-03 12:49:05.368836 76.90% [DEBUG] mod_sofia.c:5121 (sofia/default/2536854752@172.31.40.49:5074) State Change CS_NEW -> CS_INIT
155. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49:5074) Running State Change CS_INIT (Cur 1 Tot 180)
156. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@172.31.40.49:5074) State INIT
157. 2022-10-03 12:49:05.368836 76.90% [DEBUG] mod_sofia.c:97 sofia/default/2536854752@172.31.40.49:5074 SOFIA INIT
158. 2022-10-03 12:49:05.368836 76.90% [INFO] sofia_glue.c:1651 sofia/default/2536854752@172.31.40.49:5074 sending invite call-id: (null)
159. 2022-10-03 12:49:05.368836 76.90% [DEBUG] sofia_glue.c:1654 sofia/default/2536854752@172.31.40.49:5074 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
160. Local SDP:
161. v=0
162. o=FreeSWITCH 1664783359 1664783360 IN IP4 172.31.40.49
163. s=FreeSWITCH
164. c=IN IP4 172.31.40.49
165. t=0 0
166. m=audio 17986 RTP/AVP 8 0 101
167. a=rtpmap:8 PCMA/8000
168. a=rtpmap:0 PCMU/8000
169. a=rtpmap:101 telephone-event/8000
170. a=fmtp:101 0-15
171. a=ptime:20
172. a=sendrecv
173.  
174. 2022-10-03 12:49:05.368836 76.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/2536854752@172.31.40.49 [37c0ee26-e612-40a7-860b-25fbef9dd33a]
175. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/2536854752@172.31.40.49:5074 Standard INIT
176. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/2536854752@172.31.40.49:5074) State Change CS_INIT -> CS_ROUTING
177. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@172.31.40.49:5074) State INIT going to sleep
178. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49:5074) Running State Change CS_ROUTING (Cur 2 Tot 181)
179. 2022-10-03 12:49:05.368836 76.90% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@172.31.40.49:5074 entering state [calling][0]
180. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@172.31.40.49:5074) State ROUTING
181. 2022-10-03 12:49:05.368836 76.90% [DEBUG] mod_sofia.c:158 sofia/default/2536854752@172.31.40.49:5074 SOFIA ROUTING
182. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_ivr_originate.c:67 (sofia/default/2536854752@172.31.40.49:5074) State Change CS_ROUTING -> CS_CONSUME_MEDIA
183. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@172.31.40.49:5074) State ROUTING going to sleep
184. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49:5074) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 181)
185. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/2536854752@172.31.40.49:5074) State CONSUME_MEDIA
186. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/2536854752@172.31.40.49:5074) State CONSUME_MEDIA going to sleep
187. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49) Running State Change CS_NEW (Cur 2 Tot 181)
188. 2022-10-03 12:49:05.368836 76.90% [INFO] sofia.c:10462 sofia/default/2536854752@172.31.40.49 receiving invite from 172.31.40.49:5074 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 9b005ada-bdbc-123b-66b8-026398ac7998
189. 2022-10-03 12:49:05.368836 76.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 172.31.40.49:0.
190. 2022-10-03 12:49:05.368836 76.90% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
191. 2022-10-03 12:49:05.368836 76.90% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@172.31.40.49 entering state [received][100]
192. 2022-10-03 12:49:05.368836 76.90% [DEBUG] sofia.c:7509 Remote SDP:
193. v=0
194. o=FreeSWITCH 1664783359 1664783360 IN IP4 172.31.40.49
195. s=FreeSWITCH
196. c=IN IP4 172.31.40.49
197. t=0 0
198. m=audio 17986 RTP/AVP 8 0 101
199. a=rtpmap:8 PCMA/8000
200. a=rtpmap:0 PCMU/8000
201. a=rtpmap:101 telephone-event/8000
202. a=fmtp:101 0-15
203. a=ptime:20
204.  
205. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
206. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
207. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
208. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
209. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
210. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
211. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
212. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/2536854752@172.31.40.49 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
213. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_codec.c:111 sofia/default/2536854752@172.31.40.49 Original read codec set to PCMA:8
214. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
215. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_media.c:5973 sofia/default/2536854752@172.31.40.49 Set 2833 dtmf send payload to 101 recv payload to 101
216. 2022-10-03 12:49:05.368836 76.90% [DEBUG] sofia.c:7933 (sofia/default/2536854752@172.31.40.49) State Change CS_NEW -> CS_INIT
217. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/2536854752@172.31.40.49) State NEW
218. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49) Running State Change CS_INIT (Cur 2 Tot 181)
219. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@172.31.40.49) State INIT
220. 2022-10-03 12:49:05.368836 76.90% [DEBUG] mod_sofia.c:97 sofia/default/2536854752@172.31.40.49 SOFIA INIT
221. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/2536854752@172.31.40.49 Standard INIT
222. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/2536854752@172.31.40.49) State Change CS_INIT -> CS_ROUTING
223. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@172.31.40.49) State INIT going to sleep
224. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49) Running State Change CS_ROUTING (Cur 2 Tot 181)
225. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_channel.c:2380 (sofia/default/2536854752@172.31.40.49) Callstate Change DOWN -> RINGING
226. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@172.31.40.49) State ROUTING
227. 2022-10-03 12:49:05.368836 76.90% [DEBUG] mod_sofia.c:158 sofia/default/2536854752@172.31.40.49 SOFIA ROUTING
228. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/2536854752@172.31.40.49 Standard ROUTING
229. 2022-10-03 12:49:05.368836 76.90% [INFO] mod_dialplan_xml.c:639 Processing 9497482480 <2536854752>->2536854752 in context default
230. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
231. 2022-10-03 12:49:05.368836 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
232. 2022-10-03 12:49:05.388838 76.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 2536854752
233. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Clicktocall Destination Number 2536854752
234. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '2536854752' AND status=0 limit 1
235. 2022-10-03 12:49:05.388838 76.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 9497482480 / 2536854752
236. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_cpp.cpp:1465 [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 ="2536854752" LIMIT 1
237. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec 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="2536854752" limit 1
238. 2022-10-03 12:49:05.388838 76.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
239. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_cpp.cpp:1465 [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("172.31.40.49") 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 "2536854752"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
240. 2022-10-03 12:49:05.388838 76.90% [ERR] switch_core_sqldb.c:1310 ERR: [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("172.31.40.49") 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 "2536854752"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1]
241. [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.30]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',<cache>(-(1))))) - 1)'
242. ]
243. 2022-10-03 12:49:05.388838 76.90% [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: assertion failed!
244. stack traceback:
245.         [C]: in function 'assert'
246.         /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: in function </usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:150>
247.         (...tail calls...)
248.         ...hare/freeswitch/scripts/astpp/scripts/astpp.dialplan.lua:119: in main chunk
249.         [C]: in function 'dofile'
250.         /usr/share/freeswitch/scripts/astpp/astpp.lua:85: in main chunk
251. 2022-10-03 12:49:05.388838 76.90% [ERR] mod_lua.cpp:270 LUA script parse/execute error!
252. 2022-10-03 12:49:05.388838 76.90% [WARNING] mod_dialplan_xml.c:669 Context default not found
253. 2022-10-03 12:49:05.388838 76.90% [INFO] switch_core_state_machine.c:306 No Route, Aborting
254. 2022-10-03 12:49:05.388838 76.90% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/2536854752@172.31.40.49 [CS_ROUTING] [NO_ROUTE_DESTINATION]
255. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@172.31.40.49) State ROUTING going to sleep
256. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49) Running State Change CS_HANGUP (Cur 2 Tot 181)
257. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/2536854752@172.31.40.49) Callstate Change RINGING -> HANGUP
258. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@172.31.40.49) State HANGUP
259. 2022-10-03 12:49:05.388838 76.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/2536854752@172.31.40.49 hanging up, cause: NO_ROUTE_DESTINATION
260. 2022-10-03 12:49:05.388838 76.90% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
261. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/2536854752@172.31.40.49 Standard HANGUP, cause: NO_ROUTE_DESTINATION
262. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@172.31.40.49) State HANGUP going to sleep
263. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/2536854752@172.31.40.49) State Change CS_HANGUP -> CS_REPORTING
264. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49) Running State Change CS_REPORTING (Cur 2 Tot 181)
265. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@172.31.40.49) State REPORTING
266. 2022-10-03 12:49:05.388838 76.90% [INFO] mod_json_cdr.c:271 Process [37c0ee26-e612-40a7-860b-25fbef9dd33a.cdr.json]
267. 2022-10-03 12:49:05.388838 76.90% [DEBUG] sofia.c:6678 Remote Reason: 3
268. 2022-10-03 12:49:05.388838 76.90% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@172.31.40.49:5074 entering state [terminated][404]
269. 2022-10-03 12:49:05.388838 76.90% [NOTICE] sofia.c:8738 Hangup sofia/default/2536854752@172.31.40.49:5074 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
270. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49:5074) Running State Change CS_HANGUP (Cur 2 Tot 181)
271. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/2536854752@172.31.40.49:5074) Callstate Change DOWN -> HANGUP
272. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@172.31.40.49:5074) State HANGUP
273. 2022-10-03 12:49:05.388838 76.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/2536854752@172.31.40.49:5074 hanging up, cause: NO_ROUTE_DESTINATION
274. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/2536854752@172.31.40.49:5074 Standard HANGUP, cause: NO_ROUTE_DESTINATION
275. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@172.31.40.49:5074) State HANGUP going to sleep
276. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/2536854752@172.31.40.49:5074) State Change CS_HANGUP -> CS_REPORTING
277. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@172.31.40.49:5074) Running State Change CS_REPORTING (Cur 2 Tot 181)
278. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@172.31.40.49:5074) State REPORTING
279. 2022-10-03 12:49:05.388838 76.90% [INFO] mod_json_cdr.c:271 Process [d689c65e-6cca-46f9-ac45-49cb3de8b52e.cdr.json]
280. 2022-10-03 12:49:05.388838 76.90% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
281. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/2536854752@172.31.40.49:5074 Standard REPORTING, cause: NO_ROUTE_DESTINATION
282. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@172.31.40.49:5074) State REPORTING going to sleep
283. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/2536854752@172.31.40.49:5074) State Change CS_REPORTING -> CS_DESTROY
284. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_session.c:1753 Session 180 (sofia/default/2536854752@172.31.40.49:5074) Locked, Waiting on external entities
285. 2022-10-03 12:49:05.408834 76.90% [NOTICE] switch_core_session.c:1771 Session 180 (sofia/default/2536854752@172.31.40.49:5074) Ended
286. 2022-10-03 12:49:05.408834 76.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/2536854752@172.31.40.49:5074 [CS_DESTROY]
287. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/2536854752@172.31.40.49:5074) Running State Change CS_DESTROY (Cur 1 Tot 181)
288. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@172.31.40.49:5074) State DESTROY
289. 2022-10-03 12:49:05.408834 76.90% [DEBUG] mod_sofia.c:379 sofia/default/2536854752@172.31.40.49:5074 SOFIA DESTROY
290. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/2536854752@172.31.40.49:5074 Standard DESTROY
291. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@172.31.40.49:5074) State DESTROY going to sleep
292. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/2536854752@172.31.40.49 Standard REPORTING, cause: NO_ROUTE_DESTINATION
293. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@172.31.40.49) State REPORTING going to sleep
294. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/2536854752@172.31.40.49) State Change CS_REPORTING -> CS_DESTROY
295. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_session.c:1753 Session 181 (sofia/default/2536854752@172.31.40.49) Locked, Waiting on external entities
296. 2022-10-03 12:49:05.408834 76.90% [NOTICE] switch_core_session.c:1771 Session 181 (sofia/default/2536854752@172.31.40.49) Ended
297. 2022-10-03 12:49:05.408834 76.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/2536854752@172.31.40.49 [CS_DESTROY]
298. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/2536854752@172.31.40.49) Running State Change CS_DESTROY (Cur 0 Tot 181)
299. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@172.31.40.49) State DESTROY
300. 2022-10-03 12:49:05.408834 76.90% [DEBUG] mod_sofia.c:379 sofia/default/2536854752@172.31.40.49 SOFIA DESTROY
301. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/2536854752@172.31.40.49 Standard DESTROY
302. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@172.31.40.49) State DESTROY going to sleep
303. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
304. 2022-10-03 12:49:05.408834 76.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1554856322@172.31.40.49:5074 [d0521ed0-4417-4c59-9e4b-87fee9b532a7]
305. 2022-10-03 12:49:05.408834 76.90% [DEBUG] mod_sofia.c:5121 (sofia/default/1554856322@172.31.40.49:5074) State Change CS_NEW -> CS_INIT
306. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49:5074) Running State Change CS_INIT (Cur 1 Tot 182)
307. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@172.31.40.49:5074) State INIT
308. 2022-10-03 12:49:05.408834 76.90% [DEBUG] mod_sofia.c:97 sofia/default/1554856322@172.31.40.49:5074 SOFIA INIT
309. 2022-10-03 12:49:05.408834 76.90% [INFO] sofia_glue.c:1651 sofia/default/1554856322@172.31.40.49:5074 sending invite call-id: (null)
310. 2022-10-03 12:49:05.408834 76.90% [DEBUG] sofia_glue.c:1654 sofia/default/1554856322@172.31.40.49:5074 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
311. Local SDP:
312. v=0
313. o=FreeSWITCH 1664776951 1664776952 IN IP4 172.31.40.49
314. s=FreeSWITCH
315. c=IN IP4 172.31.40.49
316. t=0 0
317. m=audio 24394 RTP/AVP 8 0 101
318. a=rtpmap:8 PCMA/8000
319. a=rtpmap:0 PCMU/8000
320. a=rtpmap:101 telephone-event/8000
321. a=fmtp:101 0-15
322. a=ptime:20
323. a=sendrecv
324.  
325. 2022-10-03 12:49:05.408834 76.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1554856322@172.31.40.49 [0de87b40-98c0-40ba-afb4-a65b20f24e3c]
326. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/1554856322@172.31.40.49:5074 Standard INIT
327. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1554856322@172.31.40.49:5074) State Change CS_INIT -> CS_ROUTING
328. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@172.31.40.49:5074) State INIT going to sleep
329. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49:5074) Running State Change CS_ROUTING (Cur 2 Tot 183)
330. 2022-10-03 12:49:05.408834 76.90% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@172.31.40.49:5074 entering state [calling][0]
331. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@172.31.40.49:5074) State ROUTING
332. 2022-10-03 12:49:05.408834 76.90% [DEBUG] mod_sofia.c:158 sofia/default/1554856322@172.31.40.49:5074 SOFIA ROUTING
333. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1554856322@172.31.40.49:5074) State Change CS_ROUTING -> CS_CONSUME_MEDIA
334. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@172.31.40.49:5074) State ROUTING going to sleep
335. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49:5074) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 183)
336. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1554856322@172.31.40.49:5074) State CONSUME_MEDIA
337. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1554856322@172.31.40.49:5074) State CONSUME_MEDIA going to sleep
338. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49) Running State Change CS_NEW (Cur 2 Tot 183)
339. 2022-10-03 12:49:05.408834 76.90% [INFO] sofia.c:10462 sofia/default/1554856322@172.31.40.49 receiving invite from 172.31.40.49:5074 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 9b05e229-bdbc-123b-66b8-026398ac7998
340. 2022-10-03 12:49:05.408834 76.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 172.31.40.49:0.
341. 2022-10-03 12:49:05.408834 76.90% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
342. 2022-10-03 12:49:05.408834 76.90% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@172.31.40.49 entering state [received][100]
343. 2022-10-03 12:49:05.408834 76.90% [DEBUG] sofia.c:7509 Remote SDP:
344. v=0
345. o=FreeSWITCH 1664776951 1664776952 IN IP4 172.31.40.49
346. s=FreeSWITCH
347. c=IN IP4 172.31.40.49
348. t=0 0
349. m=audio 24394 RTP/AVP 8 0 101
350. a=rtpmap:8 PCMA/8000
351. a=rtpmap:0 PCMU/8000
352. a=rtpmap:101 telephone-event/8000
353. a=fmtp:101 0-15
354. a=ptime:20
355.  
356. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
357. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
358. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
359. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
360. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
361. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
362. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
363. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1554856322@172.31.40.49 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
364. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_codec.c:111 sofia/default/1554856322@172.31.40.49 Original read codec set to PCMA:8
365. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
366. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_media.c:5973 sofia/default/1554856322@172.31.40.49 Set 2833 dtmf send payload to 101 recv payload to 101
367. 2022-10-03 12:49:05.408834 76.90% [DEBUG] sofia.c:7933 (sofia/default/1554856322@172.31.40.49) State Change CS_NEW -> CS_INIT
368. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1554856322@172.31.40.49) State NEW
369. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49) Running State Change CS_INIT (Cur 2 Tot 183)
370. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@172.31.40.49) State INIT
371. 2022-10-03 12:49:05.408834 76.90% [DEBUG] mod_sofia.c:97 sofia/default/1554856322@172.31.40.49 SOFIA INIT
372. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/1554856322@172.31.40.49 Standard INIT
373. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1554856322@172.31.40.49) State Change CS_INIT -> CS_ROUTING
374. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@172.31.40.49) State INIT going to sleep
375. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49) Running State Change CS_ROUTING (Cur 2 Tot 183)
376. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_channel.c:2380 (sofia/default/1554856322@172.31.40.49) Callstate Change DOWN -> RINGING
377. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@172.31.40.49) State ROUTING
378. 2022-10-03 12:49:05.408834 76.90% [DEBUG] mod_sofia.c:158 sofia/default/1554856322@172.31.40.49 SOFIA ROUTING
379. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/1554856322@172.31.40.49 Standard ROUTING
380. 2022-10-03 12:49:05.408834 76.90% [INFO] mod_dialplan_xml.c:639 Processing 9497482480 <1554856322>->1554856322 in context default
381. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
382. 2022-10-03 12:49:05.408834 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
383. 2022-10-03 12:49:05.428835 76.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1554856322
384. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Clicktocall Destination Number 1554856322
385. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1554856322' AND status=0 limit 1
386. 2022-10-03 12:49:05.428835 76.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 9497482480 / 1554856322
387. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_cpp.cpp:1465 [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 ="1554856322" LIMIT 1
388. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec 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="1554856322" limit 1
389. 2022-10-03 12:49:05.428835 76.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
390. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_cpp.cpp:1465 [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("172.31.40.49") 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 "1554856322"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
391. 2022-10-03 12:49:05.428835 76.90% [ERR] switch_core_sqldb.c:1310 ERR: [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("172.31.40.49") 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 "1554856322"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1]
392. [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.30]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',<cache>(-(1))))) - 1)'
393. ]
394. 2022-10-03 12:49:05.428835 76.90% [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: assertion failed!
395. stack traceback:
396.         [C]: in function 'assert'
397.         /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: in function </usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:150>
398.         (...tail calls...)
399.         ...hare/freeswitch/scripts/astpp/scripts/astpp.dialplan.lua:119: in main chunk
400.         [C]: in function 'dofile'
401.         /usr/share/freeswitch/scripts/astpp/astpp.lua:85: in main chunk
402. 2022-10-03 12:49:05.428835 76.90% [ERR] mod_lua.cpp:270 LUA script parse/execute error!
403. 2022-10-03 12:49:05.428835 76.90% [WARNING] mod_dialplan_xml.c:669 Context default not found
404. 2022-10-03 12:49:05.428835 76.90% [INFO] switch_core_state_machine.c:306 No Route, Aborting
405. 2022-10-03 12:49:05.428835 76.90% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/1554856322@172.31.40.49 [CS_ROUTING] [NO_ROUTE_DESTINATION]
406. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@172.31.40.49) State ROUTING going to sleep
407. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49) Running State Change CS_HANGUP (Cur 2 Tot 183)
408. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1554856322@172.31.40.49) Callstate Change RINGING -> HANGUP
409. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@172.31.40.49) State HANGUP
410. 2022-10-03 12:49:05.428835 76.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/1554856322@172.31.40.49 hanging up, cause: NO_ROUTE_DESTINATION
411. 2022-10-03 12:49:05.428835 76.90% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
412. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/1554856322@172.31.40.49 Standard HANGUP, cause: NO_ROUTE_DESTINATION
413. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@172.31.40.49) State HANGUP going to sleep
414. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1554856322@172.31.40.49) State Change CS_HANGUP -> CS_REPORTING
415. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49) Running State Change CS_REPORTING (Cur 2 Tot 183)
416. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@172.31.40.49) State REPORTING
417. 2022-10-03 12:49:05.428835 76.90% [INFO] mod_json_cdr.c:271 Process [0de87b40-98c0-40ba-afb4-a65b20f24e3c.cdr.json]
418. 2022-10-03 12:49:05.428835 76.90% [DEBUG] sofia.c:6678 Remote Reason: 3
419. 2022-10-03 12:49:05.428835 76.90% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@172.31.40.49:5074 entering state [terminated][404]
420. 2022-10-03 12:49:05.428835 76.90% [NOTICE] sofia.c:8738 Hangup sofia/default/1554856322@172.31.40.49:5074 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
421. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49:5074) Running State Change CS_HANGUP (Cur 2 Tot 183)
422. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1554856322@172.31.40.49:5074) Callstate Change DOWN -> HANGUP
423. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@172.31.40.49:5074) State HANGUP
424. 2022-10-03 12:49:05.428835 76.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/1554856322@172.31.40.49:5074 hanging up, cause: NO_ROUTE_DESTINATION
425. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/1554856322@172.31.40.49:5074 Standard HANGUP, cause: NO_ROUTE_DESTINATION
426. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@172.31.40.49:5074) State HANGUP going to sleep
427. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1554856322@172.31.40.49:5074) State Change CS_HANGUP -> CS_REPORTING
428. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@172.31.40.49:5074) Running State Change CS_REPORTING (Cur 2 Tot 183)
429. 2022-10-03 12:49:05.428835 76.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@172.31.40.49:5074) State REPORTING
430. 2022-10-03 12:49:05.428835 76.90% [INFO] mod_json_cdr.c:271 Process [d0521ed0-4417-4c59-9e4b-87fee9b532a7.cdr.json]
431. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/1554856322@172.31.40.49:5074 Standard REPORTING, cause: NO_ROUTE_DESTINATION
432. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@172.31.40.49:5074) State REPORTING going to sleep
433. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1554856322@172.31.40.49:5074) State Change CS_REPORTING -> CS_DESTROY
434. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_session.c:1753 Session 182 (sofia/default/1554856322@172.31.40.49:5074) Locked, Waiting on external entities
435. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/1554856322@172.31.40.49 Standard REPORTING, cause: NO_ROUTE_DESTINATION
436. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@172.31.40.49) State REPORTING going to sleep
437. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1554856322@172.31.40.49) State Change CS_REPORTING -> CS_DESTROY
438. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_session.c:1753 Session 183 (sofia/default/1554856322@172.31.40.49) Locked, Waiting on external entities
439. 2022-10-03 12:49:05.448826 76.90% [NOTICE] switch_core_session.c:1771 Session 183 (sofia/default/1554856322@172.31.40.49) Ended
440. 2022-10-03 12:49:05.448826 76.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1554856322@172.31.40.49 [CS_DESTROY]
441. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1554856322@172.31.40.49) Running State Change CS_DESTROY (Cur 1 Tot 183)
442. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@172.31.40.49) State DESTROY
443. 2022-10-03 12:49:05.448826 76.90% [DEBUG] mod_sofia.c:379 sofia/default/1554856322@172.31.40.49 SOFIA DESTROY
444. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/1554856322@172.31.40.49 Standard DESTROY
445. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@172.31.40.49) State DESTROY going to sleep
446. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
447. 2022-10-03 12:49:05.448826 76.90% [NOTICE] switch_core_session.c:1771 Session 182 (sofia/default/1554856322@172.31.40.49:5074) Ended
448. 2022-10-03 12:49:05.448826 76.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1554856322@172.31.40.49:5074 [CS_DESTROY]
449. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1554856322@172.31.40.49:5074) Running State Change CS_DESTROY (Cur 0 Tot 183)
450. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@172.31.40.49:5074) State DESTROY
451. 2022-10-03 12:49:05.448826 76.90% [DEBUG] mod_sofia.c:379 sofia/default/1554856322@172.31.40.49:5074 SOFIA DESTROY
452. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/1554856322@172.31.40.49:5074 Standard DESTROY
453. 2022-10-03 12:49:05.448826 76.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@172.31.40.49:5074) State DESTROY going to sleep
454. 2022-10-03 12:49:06.328836 77.30% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [9497482480@13.127.15.161] from ip 103.240.35.46
455. 2022-10-03 12:49:06.488835 77.30% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [9497482480@13.127.15.161] from ip 103.240.35.46
456. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
457. 2022-10-03 12:49:07.448857 78.13% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1547964325@172.31.40.49:5074 [38854f90-12d0-463d-9836-20987ed63d52]
458. 2022-10-03 12:49:07.448857 78.13% [DEBUG] mod_sofia.c:5121 (sofia/default/1547964325@172.31.40.49:5074) State Change CS_NEW -> CS_INIT
459. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49:5074) Running State Change CS_INIT (Cur 1 Tot 184)
460. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@172.31.40.49:5074) State INIT
461. 2022-10-03 12:49:07.448857 78.13% [DEBUG] mod_sofia.c:97 sofia/default/1547964325@172.31.40.49:5074 SOFIA INIT
462. 2022-10-03 12:49:07.448857 78.13% [INFO] sofia_glue.c:1651 sofia/default/1547964325@172.31.40.49:5074 sending invite call-id: (null)
463. 2022-10-03 12:49:07.448857 78.13% [DEBUG] sofia_glue.c:1654 sofia/default/1547964325@172.31.40.49:5074 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
464. Local SDP:
465. v=0
466. o=FreeSWITCH 1664775683 1664775684 IN IP4 172.31.40.49
467. s=FreeSWITCH
468. c=IN IP4 172.31.40.49
469. t=0 0
470. m=audio 25664 RTP/AVP 8 0 101
471. a=rtpmap:8 PCMA/8000
472. a=rtpmap:0 PCMU/8000
473. a=rtpmap:101 telephone-event/8000
474. a=fmtp:101 0-15
475. a=ptime:20
476. a=sendrecv
477.  
478. 2022-10-03 12:49:07.448857 78.13% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1547964325@172.31.40.49 [76901768-0bc6-4014-87ab-e29677786de8]
479. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:40 sofia/default/1547964325@172.31.40.49:5074 Standard INIT
480. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1547964325@172.31.40.49:5074) State Change CS_INIT -> CS_ROUTING
481. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@172.31.40.49:5074) State INIT going to sleep
482. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49:5074) Running State Change CS_ROUTING (Cur 2 Tot 185)
483. 2022-10-03 12:49:07.448857 78.13% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@172.31.40.49:5074 entering state [calling][0]
484. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@172.31.40.49:5074) State ROUTING
485. 2022-10-03 12:49:07.448857 78.13% [DEBUG] mod_sofia.c:158 sofia/default/1547964325@172.31.40.49:5074 SOFIA ROUTING
486. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1547964325@172.31.40.49:5074) State Change CS_ROUTING -> CS_CONSUME_MEDIA
487. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@172.31.40.49:5074) State ROUTING going to sleep
488. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49:5074) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 185)
489. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1547964325@172.31.40.49:5074) State CONSUME_MEDIA
490. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1547964325@172.31.40.49:5074) State CONSUME_MEDIA going to sleep
491. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49) Running State Change CS_NEW (Cur 2 Tot 185)
492. 2022-10-03 12:49:07.448857 78.13% [INFO] sofia.c:10462 sofia/default/1547964325@172.31.40.49 receiving invite from 172.31.40.49:5074 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 9c3e8d20-bdbc-123b-66b8-026398ac7998
493. 2022-10-03 12:49:07.448857 78.13% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 172.31.40.49:0.
494. 2022-10-03 12:49:07.448857 78.13% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
495. 2022-10-03 12:49:07.448857 78.13% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@172.31.40.49 entering state [received][100]
496. 2022-10-03 12:49:07.448857 78.13% [DEBUG] sofia.c:7509 Remote SDP:
497. v=0
498. o=FreeSWITCH 1664775683 1664775684 IN IP4 172.31.40.49
499. s=FreeSWITCH
500. c=IN IP4 172.31.40.49
501. t=0 0
502. m=audio 25664 RTP/AVP 8 0 101
503. a=rtpmap:8 PCMA/8000
504. a=rtpmap:0 PCMU/8000
505. a=rtpmap:101 telephone-event/8000
506. a=fmtp:101 0-15
507. a=ptime:20
508.  
509. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
510. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
511. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
512. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
513. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
514. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
515. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
516. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1547964325@172.31.40.49 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
517. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_codec.c:111 sofia/default/1547964325@172.31.40.49 Original read codec set to PCMA:8
518. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
519. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_media.c:5973 sofia/default/1547964325@172.31.40.49 Set 2833 dtmf send payload to 101 recv payload to 101
520. 2022-10-03 12:49:07.448857 78.13% [DEBUG] sofia.c:7933 (sofia/default/1547964325@172.31.40.49) State Change CS_NEW -> CS_INIT
521. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1547964325@172.31.40.49) State NEW
522. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49) Running State Change CS_INIT (Cur 2 Tot 185)
523. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@172.31.40.49) State INIT
524. 2022-10-03 12:49:07.448857 78.13% [DEBUG] mod_sofia.c:97 sofia/default/1547964325@172.31.40.49 SOFIA INIT
525. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:40 sofia/default/1547964325@172.31.40.49 Standard INIT
526. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1547964325@172.31.40.49) State Change CS_INIT -> CS_ROUTING
527. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@172.31.40.49) State INIT going to sleep
528. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49) Running State Change CS_ROUTING (Cur 2 Tot 185)
529. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_channel.c:2380 (sofia/default/1547964325@172.31.40.49) Callstate Change DOWN -> RINGING
530. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@172.31.40.49) State ROUTING
531. 2022-10-03 12:49:07.448857 78.13% [DEBUG] mod_sofia.c:158 sofia/default/1547964325@172.31.40.49 SOFIA ROUTING
532. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_core_state_machine.c:230 sofia/default/1547964325@172.31.40.49 Standard ROUTING
533. 2022-10-03 12:49:07.448857 78.13% [INFO] mod_dialplan_xml.c:639 Processing 9497482480 <1547964325>->1547964325 in context default
534. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
535. 2022-10-03 12:49:07.448857 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
536. 2022-10-03 12:49:07.468834 78.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1547964325
537. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Clicktocall Destination Number 1547964325
538. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1547964325' AND status=0 limit 1
539. 2022-10-03 12:49:07.468834 78.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 9497482480 / 1547964325
540. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_cpp.cpp:1465 [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 ="1547964325" LIMIT 1
541. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec 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="1547964325" limit 1
542. 2022-10-03 12:49:07.468834 78.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
543. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_cpp.cpp:1465 [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("172.31.40.49") 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 "1547964325"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
544. 2022-10-03 12:49:07.468834 78.13% [ERR] switch_core_sqldb.c:1310 ERR: [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("172.31.40.49") 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 "1547964325"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1]
545. [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.30]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',<cache>(-(1))))) - 1)'
546. ]
547. 2022-10-03 12:49:07.468834 78.13% [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: assertion failed!
548. stack traceback:
549.         [C]: in function 'assert'
550.         /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: in function </usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:150>
551.         (...tail calls...)
552.         ...hare/freeswitch/scripts/astpp/scripts/astpp.dialplan.lua:119: in main chunk
553.         [C]: in function 'dofile'
554.         /usr/share/freeswitch/scripts/astpp/astpp.lua:85: in main chunk
555. 2022-10-03 12:49:07.468834 78.13% [ERR] mod_lua.cpp:270 LUA script parse/execute error!
556. 2022-10-03 12:49:07.468834 78.13% [WARNING] mod_dialplan_xml.c:669 Context default not found
557. 2022-10-03 12:49:07.468834 78.13% [INFO] switch_core_state_machine.c:306 No Route, Aborting
558. 2022-10-03 12:49:07.468834 78.13% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/1547964325@172.31.40.49 [CS_ROUTING] [NO_ROUTE_DESTINATION]
559. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@172.31.40.49) State ROUTING going to sleep
560. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49) Running State Change CS_HANGUP (Cur 2 Tot 185)
561. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1547964325@172.31.40.49) Callstate Change RINGING -> HANGUP
562. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@172.31.40.49) State HANGUP
563. 2022-10-03 12:49:07.468834 78.13% [DEBUG] mod_sofia.c:468 Channel sofia/default/1547964325@172.31.40.49 hanging up, cause: NO_ROUTE_DESTINATION
564. 2022-10-03 12:49:07.468834 78.13% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
565. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_core_state_machine.c:59 sofia/default/1547964325@172.31.40.49 Standard HANGUP, cause: NO_ROUTE_DESTINATION
566. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@172.31.40.49) State HANGUP going to sleep
567. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1547964325@172.31.40.49) State Change CS_HANGUP -> CS_REPORTING
568. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49) Running State Change CS_REPORTING (Cur 2 Tot 185)
569. 2022-10-03 12:49:07.468834 78.13% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@172.31.40.49) State REPORTING
570. 2022-10-03 12:49:07.468834 78.13% [INFO] mod_json_cdr.c:271 Process [76901768-0bc6-4014-87ab-e29677786de8.cdr.json]
571. 2022-10-03 12:49:07.488862 78.13% [DEBUG] sofia.c:6678 Remote Reason: 3
572. 2022-10-03 12:49:07.488862 78.13% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@172.31.40.49:5074 entering state [terminated][404]
573. 2022-10-03 12:49:07.488862 78.13% [NOTICE] sofia.c:8738 Hangup sofia/default/1547964325@172.31.40.49:5074 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
574. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49:5074) Running State Change CS_HANGUP (Cur 2 Tot 185)
575. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1547964325@172.31.40.49:5074) Callstate Change DOWN -> HANGUP
576. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@172.31.40.49:5074) State HANGUP
577. 2022-10-03 12:49:07.488862 78.13% [DEBUG] mod_sofia.c:468 Channel sofia/default/1547964325@172.31.40.49:5074 hanging up, cause: NO_ROUTE_DESTINATION
578. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:59 sofia/default/1547964325@172.31.40.49:5074 Standard HANGUP, cause: NO_ROUTE_DESTINATION
579. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@172.31.40.49:5074) State HANGUP going to sleep
580. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1547964325@172.31.40.49:5074) State Change CS_HANGUP -> CS_REPORTING
581. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@172.31.40.49:5074) Running State Change CS_REPORTING (Cur 2 Tot 185)
582. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@172.31.40.49:5074) State REPORTING
583. 2022-10-03 12:49:07.488862 78.13% [INFO] mod_json_cdr.c:271 Process [38854f90-12d0-463d-9836-20987ed63d52.cdr.json]
584. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
585. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:168 sofia/default/1547964325@172.31.40.49:5074 Standard REPORTING, cause: NO_ROUTE_DESTINATION
586. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@172.31.40.49:5074) State REPORTING going to sleep
587. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1547964325@172.31.40.49:5074) State Change CS_REPORTING -> CS_DESTROY
588. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_session.c:1753 Session 184 (sofia/default/1547964325@172.31.40.49:5074) Locked, Waiting on external entities
589. 2022-10-03 12:49:07.488862 78.13% [NOTICE] switch_core_session.c:1771 Session 184 (sofia/default/1547964325@172.31.40.49:5074) Ended
590. 2022-10-03 12:49:07.488862 78.13% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1547964325@172.31.40.49:5074 [CS_DESTROY]
591. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1547964325@172.31.40.49:5074) Running State Change CS_DESTROY (Cur 1 Tot 185)
592. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@172.31.40.49:5074) State DESTROY
593. 2022-10-03 12:49:07.488862 78.13% [DEBUG] mod_sofia.c:379 sofia/default/1547964325@172.31.40.49:5074 SOFIA DESTROY
594. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:175 sofia/default/1547964325@172.31.40.49:5074 Standard DESTROY
595. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@172.31.40.49:5074) State DESTROY going to sleep
596. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:168 sofia/default/1547964325@172.31.40.49 Standard REPORTING, cause: NO_ROUTE_DESTINATION
597. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@172.31.40.49) State REPORTING going to sleep
598. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1547964325@172.31.40.49) State Change CS_REPORTING -> CS_DESTROY
599. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_session.c:1753 Session 185 (sofia/default/1547964325@172.31.40.49) Locked, Waiting on external entities
600. 2022-10-03 12:49:07.488862 78.13% [NOTICE] switch_core_session.c:1771 Session 185 (sofia/default/1547964325@172.31.40.49) Ended
601. 2022-10-03 12:49:07.488862 78.13% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1547964325@172.31.40.49 [CS_DESTROY]
602. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1547964325@172.31.40.49) Running State Change CS_DESTROY (Cur 0 Tot 185)
603. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@172.31.40.49) State DESTROY
604. 2022-10-03 12:49:07.488862 78.13% [DEBUG] mod_sofia.c:379 sofia/default/1547964325@172.31.40.49 SOFIA DESTROY
605. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:175 sofia/default/1547964325@172.31.40.49 Standard DESTROY
606. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@172.31.40.49) State DESTROY going to sleep
607. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
608. 2022-10-03 12:49:07.488862 78.13% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1030303044@172.31.40.49:5074 [3ba30323-0066-4d65-be18-e922b63b5e5e]
609. 2022-10-03 12:49:07.488862 78.13% [DEBUG] mod_sofia.c:5121 (sofia/default/1030303044@172.31.40.49:5074) State Change CS_NEW -> CS_INIT
610. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49:5074) Running State Change CS_INIT (Cur 1 Tot 186)
611. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@172.31.40.49:5074) State INIT
612. 2022-10-03 12:49:07.488862 78.13% [DEBUG] mod_sofia.c:97 sofia/default/1030303044@172.31.40.49:5074 SOFIA INIT
613. 2022-10-03 12:49:07.488862 78.13% [INFO] sofia_glue.c:1651 sofia/default/1030303044@172.31.40.49:5074 sending invite call-id: (null)
614. 2022-10-03 12:49:07.488862 78.13% [DEBUG] sofia_glue.c:1654 sofia/default/1030303044@172.31.40.49:5074 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
615. Local SDP:
616. v=0
617. o=FreeSWITCH 1664778969 1664778970 IN IP4 172.31.40.49
618. s=FreeSWITCH
619. c=IN IP4 172.31.40.49
620. t=0 0
621. m=audio 22378 RTP/AVP 8 0 101
622. a=rtpmap:8 PCMA/8000
623. a=rtpmap:0 PCMU/8000
624. a=rtpmap:101 telephone-event/8000
625. a=fmtp:101 0-15
626. a=ptime:20
627. a=sendrecv
628.  
629. 2022-10-03 12:49:07.488862 78.13% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1030303044@172.31.40.49 [12e3d2d1-cd31-473d-b9fb-b15ca0275789]
630. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:40 sofia/default/1030303044@172.31.40.49:5074 Standard INIT
631. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1030303044@172.31.40.49:5074) State Change CS_INIT -> CS_ROUTING
632. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@172.31.40.49:5074) State INIT going to sleep
633. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49:5074) Running State Change CS_ROUTING (Cur 2 Tot 187)
634. 2022-10-03 12:49:07.488862 78.13% [DEBUG] sofia.c:7499 Channel sofia/default/1030303044@172.31.40.49:5074 entering state [calling][0]
635. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@172.31.40.49:5074) State ROUTING
636. 2022-10-03 12:49:07.488862 78.13% [DEBUG] mod_sofia.c:158 sofia/default/1030303044@172.31.40.49:5074 SOFIA ROUTING
637. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1030303044@172.31.40.49:5074) State Change CS_ROUTING -> CS_CONSUME_MEDIA
638. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@172.31.40.49:5074) State ROUTING going to sleep
639. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49:5074) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 187)
640. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1030303044@172.31.40.49:5074) State CONSUME_MEDIA
641. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1030303044@172.31.40.49:5074) State CONSUME_MEDIA going to sleep
642. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49) Running State Change CS_NEW (Cur 2 Tot 187)
643. 2022-10-03 12:49:07.488862 78.13% [INFO] sofia.c:10462 sofia/default/1030303044@172.31.40.49 receiving invite from 172.31.40.49:5074 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 9c448368-bdbc-123b-66b8-026398ac7998
644. 2022-10-03 12:49:07.488862 78.13% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 172.31.40.49:0.
645. 2022-10-03 12:49:07.488862 78.13% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
646. 2022-10-03 12:49:07.488862 78.13% [DEBUG] sofia.c:7499 Channel sofia/default/1030303044@172.31.40.49 entering state [received][100]
647. 2022-10-03 12:49:07.488862 78.13% [DEBUG] sofia.c:7509 Remote SDP:
648. v=0
649. o=FreeSWITCH 1664778969 1664778970 IN IP4 172.31.40.49
650. s=FreeSWITCH
651. c=IN IP4 172.31.40.49
652. t=0 0
653. m=audio 22378 RTP/AVP 8 0 101
654. a=rtpmap:8 PCMA/8000
655. a=rtpmap:0 PCMU/8000
656. a=rtpmap:101 telephone-event/8000
657. a=fmtp:101 0-15
658. a=ptime:20
659.  
660. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
661. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
662. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
663. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
664. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
665. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
666. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
667. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1030303044@172.31.40.49 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
668. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_codec.c:111 sofia/default/1030303044@172.31.40.49 Original read codec set to PCMA:8
669. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
670. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_media.c:5973 sofia/default/1030303044@172.31.40.49 Set 2833 dtmf send payload to 101 recv payload to 101
671. 2022-10-03 12:49:07.488862 78.13% [DEBUG] sofia.c:7933 (sofia/default/1030303044@172.31.40.49) State Change CS_NEW -> CS_INIT
672. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1030303044@172.31.40.49) State NEW
673. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49) Running State Change CS_INIT (Cur 2 Tot 187)
674. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@172.31.40.49) State INIT
675. 2022-10-03 12:49:07.488862 78.13% [DEBUG] mod_sofia.c:97 sofia/default/1030303044@172.31.40.49 SOFIA INIT
676. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:40 sofia/default/1030303044@172.31.40.49 Standard INIT
677. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1030303044@172.31.40.49) State Change CS_INIT -> CS_ROUTING
678. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@172.31.40.49) State INIT going to sleep
679. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49) Running State Change CS_ROUTING (Cur 2 Tot 187)
680. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_channel.c:2380 (sofia/default/1030303044@172.31.40.49) Callstate Change DOWN -> RINGING
681. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@172.31.40.49) State ROUTING
682. 2022-10-03 12:49:07.488862 78.13% [DEBUG] mod_sofia.c:158 sofia/default/1030303044@172.31.40.49 SOFIA ROUTING
683. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_core_state_machine.c:230 sofia/default/1030303044@172.31.40.49 Standard ROUTING
684. 2022-10-03 12:49:07.488862 78.13% [INFO] mod_dialplan_xml.c:639 Processing 9497482480 <1030303044>->1030303044 in context default
685. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
686. 2022-10-03 12:49:07.488862 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
687. 2022-10-03 12:49:07.508832 78.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1030303044
688. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Clicktocall Destination Number 1030303044
689. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1030303044' AND status=0 limit 1
690. 2022-10-03 12:49:07.508832 78.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 9497482480 / 1030303044
691. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_cpp.cpp:1465 [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 ="1030303044" LIMIT 1
692. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec 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="1030303044" limit 1
693. 2022-10-03 12:49:07.508832 78.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
694. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_cpp.cpp:1465 [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("172.31.40.49") 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 "1030303044"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
695. 2022-10-03 12:49:07.508832 78.13% [ERR] switch_core_sqldb.c:1310 ERR: [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("172.31.40.49") 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 "1030303044"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1]
696. [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.30]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',<cache>(-(1))))) - 1)'
697. ]
698. 2022-10-03 12:49:07.508832 78.13% [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: assertion failed!
699. stack traceback:
700.         [C]: in function 'assert'
701.         /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: in function </usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:150>
702.         (...tail calls...)
703.         ...hare/freeswitch/scripts/astpp/scripts/astpp.dialplan.lua:119: in main chunk
704.         [C]: in function 'dofile'
705.         /usr/share/freeswitch/scripts/astpp/astpp.lua:85: in main chunk
706. 2022-10-03 12:49:07.508832 78.13% [ERR] mod_lua.cpp:270 LUA script parse/execute error!
707. 2022-10-03 12:49:07.508832 78.13% [WARNING] mod_dialplan_xml.c:669 Context default not found
708. 2022-10-03 12:49:07.508832 78.13% [INFO] switch_core_state_machine.c:306 No Route, Aborting
709. 2022-10-03 12:49:07.508832 78.13% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/1030303044@172.31.40.49 [CS_ROUTING] [NO_ROUTE_DESTINATION]
710. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@172.31.40.49) State ROUTING going to sleep
711. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49) Running State Change CS_HANGUP (Cur 2 Tot 187)
712. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1030303044@172.31.40.49) Callstate Change RINGING -> HANGUP
713. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@172.31.40.49) State HANGUP
714. 2022-10-03 12:49:07.508832 78.13% [DEBUG] mod_sofia.c:468 Channel sofia/default/1030303044@172.31.40.49 hanging up, cause: NO_ROUTE_DESTINATION
715. 2022-10-03 12:49:07.508832 78.13% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
716. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_core_state_machine.c:59 sofia/default/1030303044@172.31.40.49 Standard HANGUP, cause: NO_ROUTE_DESTINATION
717. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@172.31.40.49) State HANGUP going to sleep
718. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1030303044@172.31.40.49) State Change CS_HANGUP -> CS_REPORTING
719. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49) Running State Change CS_REPORTING (Cur 2 Tot 187)
720. 2022-10-03 12:49:07.508832 78.13% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@172.31.40.49) State REPORTING
721. 2022-10-03 12:49:07.508832 78.13% [INFO] mod_json_cdr.c:271 Process [12e3d2d1-cd31-473d-b9fb-b15ca0275789.cdr.json]
722. 2022-10-03 12:49:07.528836 78.13% [DEBUG] sofia.c:6678 Remote Reason: 3
723. 2022-10-03 12:49:07.528836 78.13% [DEBUG] sofia.c:7499 Channel sofia/default/1030303044@172.31.40.49:5074 entering state [terminated][404]
724. 2022-10-03 12:49:07.528836 78.13% [NOTICE] sofia.c:8738 Hangup sofia/default/1030303044@172.31.40.49:5074 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
725. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49:5074) Running State Change CS_HANGUP (Cur 2 Tot 187)
726. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1030303044@172.31.40.49:5074) Callstate Change DOWN -> HANGUP
727. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@172.31.40.49:5074) State HANGUP
728. 2022-10-03 12:49:07.528836 78.13% [DEBUG] mod_sofia.c:468 Channel sofia/default/1030303044@172.31.40.49:5074 hanging up, cause: NO_ROUTE_DESTINATION
729. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:59 sofia/default/1030303044@172.31.40.49:5074 Standard HANGUP, cause: NO_ROUTE_DESTINATION
730. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@172.31.40.49:5074) State HANGUP going to sleep
731. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1030303044@172.31.40.49:5074) State Change CS_HANGUP -> CS_REPORTING
732. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@172.31.40.49:5074) Running State Change CS_REPORTING (Cur 2 Tot 187)
733. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@172.31.40.49:5074) State REPORTING
734. 2022-10-03 12:49:07.528836 78.13% [INFO] mod_json_cdr.c:271 Process [3ba30323-0066-4d65-be18-e922b63b5e5e.cdr.json]
735. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:168 sofia/default/1030303044@172.31.40.49:5074 Standard REPORTING, cause: NO_ROUTE_DESTINATION
736. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@172.31.40.49:5074) State REPORTING going to sleep
737. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1030303044@172.31.40.49:5074) State Change CS_REPORTING -> CS_DESTROY
738. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_session.c:1753 Session 186 (sofia/default/1030303044@172.31.40.49:5074) Locked, Waiting on external entities
739. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:168 sofia/default/1030303044@172.31.40.49 Standard REPORTING, cause: NO_ROUTE_DESTINATION
740. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@172.31.40.49) State REPORTING going to sleep
741. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1030303044@172.31.40.49) State Change CS_REPORTING -> CS_DESTROY
742. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_session.c:1753 Session 187 (sofia/default/1030303044@172.31.40.49) Locked, Waiting on external entities
743. 2022-10-03 12:49:07.528836 78.13% [NOTICE] switch_core_session.c:1771 Session 187 (sofia/default/1030303044@172.31.40.49) Ended
744. 2022-10-03 12:49:07.528836 78.13% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1030303044@172.31.40.49 [CS_DESTROY]
745. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1030303044@172.31.40.49) Running State Change CS_DESTROY (Cur 1 Tot 187)
746. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@172.31.40.49) State DESTROY
747. 2022-10-03 12:49:07.528836 78.13% [DEBUG] mod_sofia.c:379 sofia/default/1030303044@172.31.40.49 SOFIA DESTROY
748. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:175 sofia/default/1030303044@172.31.40.49 Standard DESTROY
749. 2022-10-03 12:49:07.528836 78.13% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@172.31.40.49) State DESTROY going to sleep
750. 2022-10-03 12:49:07.548831 78.13% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
751. 2022-10-03 12:49:07.548831 78.13% [NOTICE] switch_core_session.c:1771 Session 186 (sofia/default/1030303044@172.31.40.49:5074) Ended
752. 2022-10-03 12:49:07.548831 78.13% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1030303044@172.31.40.49:5074 [CS_DESTROY]
753. 2022-10-03 12:49:07.548831 78.13% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1030303044@172.31.40.49:5074) Running State Change CS_DESTROY (Cur 0 Tot 187)
754. 2022-10-03 12:49:07.548831 78.13% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@172.31.40.49:5074) State DESTROY
755. 2022-10-03 12:49:07.548831 78.13% [DEBUG] mod_sofia.c:379 sofia/default/1030303044@172.31.40.49:5074 SOFIA DESTROY
756. 2022-10-03 12:49:07.548831 78.13% [DEBUG] switch_core_state_machine.c:175 sofia/default/1030303044@172.31.40.49:5074 Standard DESTROY
757. 2022-10-03 12:49:07.548831 78.13% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@172.31.40.49:5074) State DESTROY going to sleep
758. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
759. 2022-10-03 12:49:09.548861 80.27% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1039999999@172.31.40.49:5074 [c1a7f36b-59f4-4706-9696-ca59997ed5de]
760. 2022-10-03 12:49:09.548861 80.27% [DEBUG] mod_sofia.c:5121 (sofia/default/1039999999@172.31.40.49:5074) State Change CS_NEW -> CS_INIT
761. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49:5074) Running State Change CS_INIT (Cur 1 Tot 188)
762. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@172.31.40.49:5074) State INIT
763. 2022-10-03 12:49:09.548861 80.27% [DEBUG] mod_sofia.c:97 sofia/default/1039999999@172.31.40.49:5074 SOFIA INIT
764. 2022-10-03 12:49:09.548861 80.27% [INFO] sofia_glue.c:1651 sofia/default/1039999999@172.31.40.49:5074 sending invite call-id: (null)
765. 2022-10-03 12:49:09.548861 80.27% [DEBUG] sofia_glue.c:1654 sofia/default/1039999999@172.31.40.49:5074 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
766. Local SDP:
767. v=0
768. o=FreeSWITCH 1664775047 1664775048 IN IP4 172.31.40.49
769. s=FreeSWITCH
770. c=IN IP4 172.31.40.49
771. t=0 0
772. m=audio 26302 RTP/AVP 8 0 101
773. a=rtpmap:8 PCMA/8000
774. a=rtpmap:0 PCMU/8000
775. a=rtpmap:101 telephone-event/8000
776. a=fmtp:101 0-15
777. a=ptime:20
778. a=sendrecv
779.  
780. 2022-10-03 12:49:09.548861 80.27% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1039999999@172.31.40.49 [84248f1c-5e21-4da7-8f6f-b87222884931]
781. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:40 sofia/default/1039999999@172.31.40.49:5074 Standard INIT
782. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1039999999@172.31.40.49:5074) State Change CS_INIT -> CS_ROUTING
783. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@172.31.40.49:5074) State INIT going to sleep
784. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49:5074) Running State Change CS_ROUTING (Cur 2 Tot 189)
785. 2022-10-03 12:49:09.548861 80.27% [DEBUG] sofia.c:7499 Channel sofia/default/1039999999@172.31.40.49:5074 entering state [calling][0]
786. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@172.31.40.49:5074) State ROUTING
787. 2022-10-03 12:49:09.548861 80.27% [DEBUG] mod_sofia.c:158 sofia/default/1039999999@172.31.40.49:5074 SOFIA ROUTING
788. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1039999999@172.31.40.49:5074) State Change CS_ROUTING -> CS_CONSUME_MEDIA
789. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@172.31.40.49:5074) State ROUTING going to sleep
790. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49:5074) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 189)
791. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1039999999@172.31.40.49:5074) State CONSUME_MEDIA
792. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1039999999@172.31.40.49:5074) State CONSUME_MEDIA going to sleep
793. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49) Running State Change CS_NEW (Cur 2 Tot 189)
794. 2022-10-03 12:49:09.548861 80.27% [INFO] sofia.c:10462 sofia/default/1039999999@172.31.40.49 receiving invite from 172.31.40.49:5074 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 9d7e18af-bdbc-123b-66b8-026398ac7998
795. 2022-10-03 12:49:09.548861 80.27% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 172.31.40.49:0.
796. 2022-10-03 12:49:09.548861 80.27% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
797. 2022-10-03 12:49:09.548861 80.27% [DEBUG] sofia.c:7499 Channel sofia/default/1039999999@172.31.40.49 entering state [received][100]
798. 2022-10-03 12:49:09.548861 80.27% [DEBUG] sofia.c:7509 Remote SDP:
799. v=0
800. o=FreeSWITCH 1664775047 1664775048 IN IP4 172.31.40.49
801. s=FreeSWITCH
802. c=IN IP4 172.31.40.49
803. t=0 0
804. m=audio 26302 RTP/AVP 8 0 101
805. a=rtpmap:8 PCMA/8000
806. a=rtpmap:0 PCMU/8000
807. a=rtpmap:101 telephone-event/8000
808. a=fmtp:101 0-15
809. a=ptime:20
810.  
811. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
812. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
813. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
814. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
815. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
816. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
817. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
818. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1039999999@172.31.40.49 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
819. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_codec.c:111 sofia/default/1039999999@172.31.40.49 Original read codec set to PCMA:8
820. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
821. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_media.c:5973 sofia/default/1039999999@172.31.40.49 Set 2833 dtmf send payload to 101 recv payload to 101
822. 2022-10-03 12:49:09.548861 80.27% [DEBUG] sofia.c:7933 (sofia/default/1039999999@172.31.40.49) State Change CS_NEW -> CS_INIT
823. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1039999999@172.31.40.49) State NEW
824. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49) Running State Change CS_INIT (Cur 2 Tot 189)
825. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@172.31.40.49) State INIT
826. 2022-10-03 12:49:09.548861 80.27% [DEBUG] mod_sofia.c:97 sofia/default/1039999999@172.31.40.49 SOFIA INIT
827. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:40 sofia/default/1039999999@172.31.40.49 Standard INIT
828. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1039999999@172.31.40.49) State Change CS_INIT -> CS_ROUTING
829. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@172.31.40.49) State INIT going to sleep
830. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49) Running State Change CS_ROUTING (Cur 2 Tot 189)
831. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_channel.c:2380 (sofia/default/1039999999@172.31.40.49) Callstate Change DOWN -> RINGING
832. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@172.31.40.49) State ROUTING
833. 2022-10-03 12:49:09.548861 80.27% [DEBUG] mod_sofia.c:158 sofia/default/1039999999@172.31.40.49 SOFIA ROUTING
834. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_core_state_machine.c:230 sofia/default/1039999999@172.31.40.49 Standard ROUTING
835. 2022-10-03 12:49:09.548861 80.27% [INFO] mod_dialplan_xml.c:639 Processing 9497482480 <1039999999>->1039999999 in context default
836. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
837. 2022-10-03 12:49:09.548861 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
838. 2022-10-03 12:49:09.568837 80.27% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1039999999
839. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Clicktocall Destination Number 1039999999
840. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1039999999' AND status=0 limit 1
841. 2022-10-03 12:49:09.568837 80.27% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 9497482480 / 1039999999
842. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_cpp.cpp:1465 [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 ="1039999999" LIMIT 1
843. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec 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="1039999999" limit 1
844. 2022-10-03 12:49:09.568837 80.27% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
845. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_cpp.cpp:1465 [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("172.31.40.49") 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 "1039999999"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
846. 2022-10-03 12:49:09.568837 80.27% [ERR] switch_core_sqldb.c:1310 ERR: [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("172.31.40.49") 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 "1039999999"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1]
847. [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.30]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',<cache>(-(1))))) - 1)'
848. ]
849. 2022-10-03 12:49:09.568837 80.27% [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: assertion failed!
850. stack traceback:
851.         [C]: in function 'assert'
852.         /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: in function </usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:150>
853.         (...tail calls...)
854.         ...hare/freeswitch/scripts/astpp/scripts/astpp.dialplan.lua:119: in main chunk
855.         [C]: in function 'dofile'
856.         /usr/share/freeswitch/scripts/astpp/astpp.lua:85: in main chunk
857. 2022-10-03 12:49:09.568837 80.27% [ERR] mod_lua.cpp:270 LUA script parse/execute error!
858. 2022-10-03 12:49:09.568837 80.27% [WARNING] mod_dialplan_xml.c:669 Context default not found
859. 2022-10-03 12:49:09.568837 80.27% [INFO] switch_core_state_machine.c:306 No Route, Aborting
860. 2022-10-03 12:49:09.568837 80.27% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/1039999999@172.31.40.49 [CS_ROUTING] [NO_ROUTE_DESTINATION]
861. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@172.31.40.49) State ROUTING going to sleep
862. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49) Running State Change CS_HANGUP (Cur 2 Tot 189)
863. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1039999999@172.31.40.49) Callstate Change RINGING -> HANGUP
864. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@172.31.40.49) State HANGUP
865. 2022-10-03 12:49:09.568837 80.27% [DEBUG] mod_sofia.c:468 Channel sofia/default/1039999999@172.31.40.49 hanging up, cause: NO_ROUTE_DESTINATION
866. 2022-10-03 12:49:09.568837 80.27% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
867. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:59 sofia/default/1039999999@172.31.40.49 Standard HANGUP, cause: NO_ROUTE_DESTINATION
868. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@172.31.40.49) State HANGUP going to sleep
869. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1039999999@172.31.40.49) State Change CS_HANGUP -> CS_REPORTING
870. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49) Running State Change CS_REPORTING (Cur 2 Tot 189)
871. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@172.31.40.49) State REPORTING
872. 2022-10-03 12:49:09.568837 80.27% [INFO] mod_json_cdr.c:271 Process [84248f1c-5e21-4da7-8f6f-b87222884931.cdr.json]
873. 2022-10-03 12:49:09.568837 80.27% [DEBUG] sofia.c:6678 Remote Reason: 3
874. 2022-10-03 12:49:09.568837 80.27% [DEBUG] sofia.c:7499 Channel sofia/default/1039999999@172.31.40.49:5074 entering state [terminated][404]
875. 2022-10-03 12:49:09.568837 80.27% [NOTICE] sofia.c:8738 Hangup sofia/default/1039999999@172.31.40.49:5074 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
876. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49:5074) Running State Change CS_HANGUP (Cur 2 Tot 189)
877. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1039999999@172.31.40.49:5074) Callstate Change DOWN -> HANGUP
878. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@172.31.40.49:5074) State HANGUP
879. 2022-10-03 12:49:09.568837 80.27% [DEBUG] mod_sofia.c:468 Channel sofia/default/1039999999@172.31.40.49:5074 hanging up, cause: NO_ROUTE_DESTINATION
880. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:59 sofia/default/1039999999@172.31.40.49:5074 Standard HANGUP, cause: NO_ROUTE_DESTINATION
881. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@172.31.40.49:5074) State HANGUP going to sleep
882. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1039999999@172.31.40.49:5074) State Change CS_HANGUP -> CS_REPORTING
883. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@172.31.40.49:5074) Running State Change CS_REPORTING (Cur 2 Tot 189)
884. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@172.31.40.49:5074) State REPORTING
885. 2022-10-03 12:49:09.568837 80.27% [INFO] mod_json_cdr.c:271 Process [c1a7f36b-59f4-4706-9696-ca59997ed5de.cdr.json]
886. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
887. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:168 sofia/default/1039999999@172.31.40.49:5074 Standard REPORTING, cause: NO_ROUTE_DESTINATION
888. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@172.31.40.49:5074) State REPORTING going to sleep
889. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1039999999@172.31.40.49:5074) State Change CS_REPORTING -> CS_DESTROY
890. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_session.c:1753 Session 188 (sofia/default/1039999999@172.31.40.49:5074) Locked, Waiting on external entities
891. 2022-10-03 12:49:09.568837 80.27% [NOTICE] switch_core_session.c:1771 Session 188 (sofia/default/1039999999@172.31.40.49:5074) Ended
892. 2022-10-03 12:49:09.568837 80.27% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1039999999@172.31.40.49:5074 [CS_DESTROY]
893. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1039999999@172.31.40.49:5074) Running State Change CS_DESTROY (Cur 1 Tot 189)
894. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@172.31.40.49:5074) State DESTROY
895. 2022-10-03 12:49:09.568837 80.27% [DEBUG] mod_sofia.c:379 sofia/default/1039999999@172.31.40.49:5074 SOFIA DESTROY
896. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:175 sofia/default/1039999999@172.31.40.49:5074 Standard DESTROY
897. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@172.31.40.49:5074) State DESTROY going to sleep
898. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:168 sofia/default/1039999999@172.31.40.49 Standard REPORTING, cause: NO_ROUTE_DESTINATION
899. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@172.31.40.49) State REPORTING going to sleep
900. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1039999999@172.31.40.49) State Change CS_REPORTING -> CS_DESTROY
901. 2022-10-03 12:49:09.568837 80.27% [DEBUG] switch_core_session.c:1753 Session 189 (sofia/default/1039999999@172.31.40.49) Locked, Waiting on external entities
902. 2022-10-03 12:49:09.568837 80.27% [NOTICE] switch_core_session.c:1771 Session 189 (sofia/default/1039999999@172.31.40.49) Ended
903. 2022-10-03 12:49:09.568837 80.27% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1039999999@172.31.40.49 [CS_DESTROY]
904. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1039999999@172.31.40.49) Running State Change CS_DESTROY (Cur 0 Tot 189)
905. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@172.31.40.49) State DESTROY
906. 2022-10-03 12:49:09.588833 80.27% [DEBUG] mod_sofia.c:379 sofia/default/1039999999@172.31.40.49 SOFIA DESTROY
907. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:175 sofia/default/1039999999@172.31.40.49 Standard DESTROY
908. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@172.31.40.49) State DESTROY going to sleep
909. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
910. 2022-10-03 12:49:09.588833 80.27% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1077777777@172.31.40.49:5074 [0dec4fc7-972e-4bd8-a2a5-99863c14e60f]
911. 2022-10-03 12:49:09.588833 80.27% [DEBUG] mod_sofia.c:5121 (sofia/default/1077777777@172.31.40.49:5074) State Change CS_NEW -> CS_INIT
912. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49:5074) Running State Change CS_INIT (Cur 1 Tot 190)
913. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@172.31.40.49:5074) State INIT
914. 2022-10-03 12:49:09.588833 80.27% [DEBUG] mod_sofia.c:97 sofia/default/1077777777@172.31.40.49:5074 SOFIA INIT
915. 2022-10-03 12:49:09.588833 80.27% [INFO] sofia_glue.c:1651 sofia/default/1077777777@172.31.40.49:5074 sending invite call-id: (null)
916. 2022-10-03 12:49:09.588833 80.27% [DEBUG] sofia_glue.c:1654 sofia/default/1077777777@172.31.40.49:5074 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
917. Local SDP:
918. v=0
919. o=FreeSWITCH 1664773063 1664773064 IN IP4 172.31.40.49
920. s=FreeSWITCH
921. c=IN IP4 172.31.40.49
922. t=0 0
923. m=audio 28286 RTP/AVP 8 0 101
924. a=rtpmap:8 PCMA/8000
925. a=rtpmap:0 PCMU/8000
926. a=rtpmap:101 telephone-event/8000
927. a=fmtp:101 0-15
928. a=ptime:20
929. a=sendrecv
930.  
931. 2022-10-03 12:49:09.588833 80.27% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1077777777@172.31.40.49 [a911e019-fbcf-4f53-9ded-9d5c398c556b]
932. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:40 sofia/default/1077777777@172.31.40.49:5074 Standard INIT
933. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1077777777@172.31.40.49:5074) State Change CS_INIT -> CS_ROUTING
934. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@172.31.40.49:5074) State INIT going to sleep
935. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49:5074) Running State Change CS_ROUTING (Cur 2 Tot 191)
936. 2022-10-03 12:49:09.588833 80.27% [DEBUG] sofia.c:7499 Channel sofia/default/1077777777@172.31.40.49:5074 entering state [calling][0]
937. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@172.31.40.49:5074) State ROUTING
938. 2022-10-03 12:49:09.588833 80.27% [DEBUG] mod_sofia.c:158 sofia/default/1077777777@172.31.40.49:5074 SOFIA ROUTING
939. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1077777777@172.31.40.49:5074) State Change CS_ROUTING -> CS_CONSUME_MEDIA
940. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@172.31.40.49:5074) State ROUTING going to sleep
941. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49:5074) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 191)
942. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1077777777@172.31.40.49:5074) State CONSUME_MEDIA
943. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1077777777@172.31.40.49:5074) State CONSUME_MEDIA going to sleep
944. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49) Running State Change CS_NEW (Cur 2 Tot 191)
945. 2022-10-03 12:49:09.588833 80.27% [INFO] sofia.c:10462 sofia/default/1077777777@172.31.40.49 receiving invite from 172.31.40.49:5074 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 9d83951f-bdbc-123b-66b8-026398ac7998
946. 2022-10-03 12:49:09.588833 80.27% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 172.31.40.49:0.
947. 2022-10-03 12:49:09.588833 80.27% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
948. 2022-10-03 12:49:09.588833 80.27% [DEBUG] sofia.c:7499 Channel sofia/default/1077777777@172.31.40.49 entering state [received][100]
949. 2022-10-03 12:49:09.588833 80.27% [DEBUG] sofia.c:7509 Remote SDP:
950. v=0
951. o=FreeSWITCH 1664773063 1664773064 IN IP4 172.31.40.49
952. s=FreeSWITCH
953. c=IN IP4 172.31.40.49
954. t=0 0
955. m=audio 28286 RTP/AVP 8 0 101
956. a=rtpmap:8 PCMA/8000
957. a=rtpmap:0 PCMU/8000
958. a=rtpmap:101 telephone-event/8000
959. a=fmtp:101 0-15
960. a=ptime:20
961.  
962. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
963. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
964. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
965. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
966. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
967. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
968. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
969. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1077777777@172.31.40.49 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
970. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_codec.c:111 sofia/default/1077777777@172.31.40.49 Original read codec set to PCMA:8
971. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
972. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_media.c:5973 sofia/default/1077777777@172.31.40.49 Set 2833 dtmf send payload to 101 recv payload to 101
973. 2022-10-03 12:49:09.588833 80.27% [DEBUG] sofia.c:7933 (sofia/default/1077777777@172.31.40.49) State Change CS_NEW -> CS_INIT
974. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1077777777@172.31.40.49) State NEW
975. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49) Running State Change CS_INIT (Cur 2 Tot 191)
976. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@172.31.40.49) State INIT
977. 2022-10-03 12:49:09.588833 80.27% [DEBUG] mod_sofia.c:97 sofia/default/1077777777@172.31.40.49 SOFIA INIT
978. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:40 sofia/default/1077777777@172.31.40.49 Standard INIT
979. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1077777777@172.31.40.49) State Change CS_INIT -> CS_ROUTING
980. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@172.31.40.49) State INIT going to sleep
981. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49) Running State Change CS_ROUTING (Cur 2 Tot 191)
982. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_channel.c:2380 (sofia/default/1077777777@172.31.40.49) Callstate Change DOWN -> RINGING
983. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@172.31.40.49) State ROUTING
984. 2022-10-03 12:49:09.588833 80.27% [DEBUG] mod_sofia.c:158 sofia/default/1077777777@172.31.40.49 SOFIA ROUTING
985. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_core_state_machine.c:230 sofia/default/1077777777@172.31.40.49 Standard ROUTING
986. 2022-10-03 12:49:09.588833 80.27% [INFO] mod_dialplan_xml.c:639 Processing 9497482480 <1077777777>->1077777777 in context default
987. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
988. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
989. 2022-10-03 12:49:09.588833 80.27% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1077777777
990. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Clicktocall Destination Number 1077777777
991. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1077777777' AND status=0 limit 1
992. 2022-10-03 12:49:09.588833 80.27% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 9497482480 / 1077777777
993. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_cpp.cpp:1465 [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 ="1077777777" LIMIT 1
994. 2022-10-03 12:49:09.588833 80.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec 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="1077777777" limit 1
995. 2022-10-03 12:49:09.608839 80.27% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
996. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_cpp.cpp:1465 [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("172.31.40.49") 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 "1077777777"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
997. 2022-10-03 12:49:09.608839 80.27% [ERR] switch_core_sqldb.c:1310 ERR: [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("172.31.40.49") 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 "1077777777"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1]
998. [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.30]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',<cache>(-(1))))) - 1)'
999. ]
1000. 2022-10-03 12:49:09.608839 80.27% [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: assertion failed!
1001. stack traceback:
1002.         [C]: in function 'assert'
1003.         /usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:154: in function </usr/share/freeswitch/scripts/astpp/lib/astpp.functions.lua:150>
1004.         (...tail calls...)
1005.         ...hare/freeswitch/scripts/astpp/scripts/astpp.dialplan.lua:119: in main chunk
1006.         [C]: in function 'dofile'
1007.         /usr/share/freeswitch/scripts/astpp/astpp.lua:85: in main chunk
1008. 2022-10-03 12:49:09.608839 80.27% [ERR] mod_lua.cpp:270 LUA script parse/execute error!
1009. 2022-10-03 12:49:09.608839 80.27% [WARNING] mod_dialplan_xml.c:669 Context default not found
1010. 2022-10-03 12:49:09.608839 80.27% [INFO] switch_core_state_machine.c:306 No Route, Aborting
1011. 2022-10-03 12:49:09.608839 80.27% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/1077777777@172.31.40.49 [CS_ROUTING] [NO_ROUTE_DESTINATION]
1012. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@172.31.40.49) State ROUTING going to sleep
1013. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49) Running State Change CS_HANGUP (Cur 2 Tot 191)
1014. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1077777777@172.31.40.49) Callstate Change RINGING -> HANGUP
1015. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@172.31.40.49) State HANGUP
1016. 2022-10-03 12:49:09.608839 80.27% [DEBUG] mod_sofia.c:468 Channel sofia/default/1077777777@172.31.40.49 hanging up, cause: NO_ROUTE_DESTINATION
1017. 2022-10-03 12:49:09.608839 80.27% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
1018. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:59 sofia/default/1077777777@172.31.40.49 Standard HANGUP, cause: NO_ROUTE_DESTINATION
1019. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@172.31.40.49) State HANGUP going to sleep
1020. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1077777777@172.31.40.49) State Change CS_HANGUP -> CS_REPORTING
1021. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49) Running State Change CS_REPORTING (Cur 2 Tot 191)
1022. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@172.31.40.49) State REPORTING
1023. 2022-10-03 12:49:09.608839 80.27% [INFO] mod_json_cdr.c:271 Process [a911e019-fbcf-4f53-9ded-9d5c398c556b.cdr.json]
1024. 2022-10-03 12:49:09.608839 80.27% [DEBUG] sofia.c:6678 Remote Reason: 3
1025. 2022-10-03 12:49:09.608839 80.27% [DEBUG] sofia.c:7499 Channel sofia/default/1077777777@172.31.40.49:5074 entering state [terminated][404]
1026. 2022-10-03 12:49:09.608839 80.27% [NOTICE] sofia.c:8738 Hangup sofia/default/1077777777@172.31.40.49:5074 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
1027. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49:5074) Running State Change CS_HANGUP (Cur 2 Tot 191)
1028. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1077777777@172.31.40.49:5074) Callstate Change DOWN -> HANGUP
1029. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@172.31.40.49:5074) State HANGUP
1030. 2022-10-03 12:49:09.608839 80.27% [DEBUG] mod_sofia.c:468 Channel sofia/default/1077777777@172.31.40.49:5074 hanging up, cause: NO_ROUTE_DESTINATION
1031. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:59 sofia/default/1077777777@172.31.40.49:5074 Standard HANGUP, cause: NO_ROUTE_DESTINATION
1032. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@172.31.40.49:5074) State HANGUP going to sleep
1033. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1077777777@172.31.40.49:5074) State Change CS_HANGUP -> CS_REPORTING
1034. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@172.31.40.49:5074) Running State Change CS_REPORTING (Cur 2 Tot 191)
1035. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@172.31.40.49:5074) State REPORTING
1036. 2022-10-03 12:49:09.608839 80.27% [INFO] mod_json_cdr.c:271 Process [0dec4fc7-972e-4bd8-a2a5-99863c14e60f.cdr.json]
1037. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
1038. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:168 sofia/default/1077777777@172.31.40.49:5074 Standard REPORTING, cause: NO_ROUTE_DESTINATION
1039. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@172.31.40.49:5074) State REPORTING going to sleep
1040. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1077777777@172.31.40.49:5074) State Change CS_REPORTING -> CS_DESTROY
1041. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_session.c:1753 Session 190 (sofia/default/1077777777@172.31.40.49:5074) Locked, Waiting on external entities
1042. 2022-10-03 12:49:09.608839 80.27% [NOTICE] switch_core_session.c:1771 Session 190 (sofia/default/1077777777@172.31.40.49:5074) Ended
1043. 2022-10-03 12:49:09.608839 80.27% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1077777777@172.31.40.49:5074 [CS_DESTROY]
1044. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1077777777@172.31.40.49:5074) Running State Change CS_DESTROY (Cur 1 Tot 191)
1045. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@172.31.40.49:5074) State DESTROY
1046. 2022-10-03 12:49:09.608839 80.27% [DEBUG] mod_sofia.c:379 sofia/default/1077777777@172.31.40.49:5074 SOFIA DESTROY
1047. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:175 sofia/default/1077777777@172.31.40.49:5074 Standard DESTROY
1048. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@172.31.40.49:5074) State DESTROY going to sleep
1049. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:168 sofia/default/1077777777@172.31.40.49 Standard REPORTING, cause: NO_ROUTE_DESTINATION
1050. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@172.31.40.49) State REPORTING going to sleep
1051. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1077777777@172.31.40.49) State Change CS_REPORTING -> CS_DESTROY
1052. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_session.c:1753 Session 191 (sofia/default/1077777777@172.31.40.49) Locked, Waiting on external entities
1053. 2022-10-03 12:49:09.608839 80.27% [NOTICE] switch_core_session.c:1771 Session 191 (sofia/default/1077777777@172.31.40.49) Ended
1054. 2022-10-03 12:49:09.608839 80.27% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1077777777@172.31.40.49 [CS_DESTROY]
1055. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1077777777@172.31.40.49) Running State Change CS_DESTROY (Cur 0 Tot 191)
1056. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@172.31.40.49) State DESTROY
1057. 2022-10-03 12:49:09.608839 80.27% [DEBUG] mod_sofia.c:379 sofia/default/1077777777@172.31.40.49 SOFIA DESTROY
1058. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:175 sofia/default/1077777777@172.31.40.49 Standard DESTROY
1059. 2022-10-03 12:49:09.608839 80.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@172.31.40.49) State DESTROY going to sleep
1060. 2022-10-03 12:49:14.348861 83.07% [NOTICE] sofia_reg.c:459 Registering 8134791837
1061. 2022-10-03 12:49:14.468842 83.07% [ERR] sofia_reg.c:2677 8134791837 Failed Registration with status Service Unavailable [503]. failure #10952
1062. 2022-10-03 12:49:15.348845 83.47% [WARNING] sofia_reg.c:516 8134791837 Failed Registration [503], setting retry to 30 seconds.
1063. 2022-10-03 12:49:17.988831 84.83% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [845296@13.127.15.161] from ip 103.240.35.46
1064. 2022-10-03 12:49:18.208836 84.83% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [845296@13.127.15.161] from ip 103.240.35.46
1065. 2022-10-03 12:49:18.648833 84.83% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [9497482480@13.127.15.161] from ip 103.240.35.46
1066. 2022-10-03 12:49:18.828858 84.97% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [9497482480@13.127.15.161] from ip 103.240.35.46
1067. 2022-10-03 12:49:26.608858 88.40% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [9497482480@13.127.15.161] from ip 103.240.35.46
1068.