2022-12-06 12:07:51.186191 92.40% [ERR] sofia_reg.c:2677 813646 Failed Registration with status Service Unavailable [503]. failure #130 2022-12-06 12:07:52.186181 92.50% [WARNING] sofia_reg.c:516 813646 Failed Registration [503], setting retry to 30 seconds. 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-12-06 12:08:06.346166 90.80% [NOTICE] switch_channel.c:1123 New Channel sofia/default/990010@astppqa601.astppbilling.org:5076 [7bd8d17a-0e2a-4669-90a6-234cd2842d3c] 2022-12-06 12:08:06.346166 90.80% [DEBUG] mod_sofia.c:5121 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_NEW -> CS_INIT 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_INIT (Cur 1 Tot 107) 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/990010@astppqa601.astppbilling.org:5076) State INIT 2022-12-06 12:08:06.346166 90.80% [DEBUG] mod_sofia.c:97 sofia/default/990010@astppqa601.astppbilling.org:5076 SOFIA INIT 2022-12-06 12:08:06.346166 90.80% [INFO] sofia_glue.c:1651 sofia/default/990010@astppqa601.astppbilling.org:5076 sending invite call-id: (null) 2022-12-06 12:08:06.346166 90.80% [DEBUG] sofia_glue.c:1654 sofia/default/990010@astppqa601.astppbilling.org:5076 sending invite version: 1.10.7 -release 64bit Local SDP: v=0 o=FreeSWITCH 1670298466 1670298467 IN IP4 142.132.184.134 s=FreeSWITCH c=IN IP4 142.132.184.134 t=0 0 m=audio 30020 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:40 sofia/default/990010@astppqa601.astppbilling.org:5076 Standard INIT 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:48 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_INIT -> CS_ROUTING 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/990010@astppqa601.astppbilling.org:5076) State INIT going to sleep 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_ROUTING (Cur 1 Tot 107) 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:640 (sofia/default/990010@astppqa601.astppbilling.org:5076) State ROUTING 2022-12-06 12:08:06.346166 90.80% [DEBUG] mod_sofia.c:158 sofia/default/990010@astppqa601.astppbilling.org:5076 SOFIA ROUTING 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_ivr_originate.c:67 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:640 (sofia/default/990010@astppqa601.astppbilling.org:5076) State ROUTING going to sleep 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 107) 2022-12-06 12:08:06.346166 90.80% [DEBUG] sofia.c:7499 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 entering state [calling][0] 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:659 (sofia/default/990010@astppqa601.astppbilling.org:5076) State CONSUME_MEDIA 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:659 (sofia/default/990010@astppqa601.astppbilling.org:5076) State CONSUME_MEDIA going to sleep 2022-12-06 12:08:06.346166 90.80% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1515121325@142.132.184.134 [6df21960-68b8-497f-a313-c865353eeeeb] 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@142.132.184.134) Running State Change CS_NEW (Cur 2 Tot 108) 2022-12-06 12:08:06.346166 90.80% [INFO] sofia.c:10462 sofia/default/1515121325@142.132.184.134 receiving invite from 142.132.184.134:5076 version: 1.10.7 -release 64bit call-id: 7bbe7d3d-f001-123b-c298-9600013c8d14 2022-12-06 12:08:06.346166 90.80% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 142.132.184.134:0. 2022-12-06 12:08:06.346166 90.80% [DEBUG] sofia.c:10585 IP 142.132.184.134 Approved by acl "default[]". Access Granted. 2022-12-06 12:08:06.346166 90.80% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@142.132.184.134 entering state [received][100] 2022-12-06 12:08:06.346166 90.80% [DEBUG] sofia.c:7509 Remote SDP: v=0 o=FreeSWITCH 1670298466 1670298467 IN IP4 142.132.184.134 s=FreeSWITCH c=IN IP4 142.132.184.134 t=0 0 m=audio 30020 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1515121325@142.132.184.134 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_codec.c:111 sofia/default/1515121325@142.132.184.134 Original read codec set to PCMA:8 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_media.c:5973 sofia/default/1515121325@142.132.184.134 Set 2833 dtmf send payload to 101 recv payload to 101 2022-12-06 12:08:06.346166 90.80% [DEBUG] sofia.c:7933 (sofia/default/1515121325@142.132.184.134) State Change CS_NEW -> CS_INIT 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1515121325@142.132.184.134) State NEW 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@142.132.184.134) Running State Change CS_INIT (Cur 2 Tot 108) 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@142.132.184.134) State INIT 2022-12-06 12:08:06.346166 90.80% [DEBUG] mod_sofia.c:97 sofia/default/1515121325@142.132.184.134 SOFIA INIT 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:40 sofia/default/1515121325@142.132.184.134 Standard INIT 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1515121325@142.132.184.134) State Change CS_INIT -> CS_ROUTING 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@142.132.184.134) State INIT going to sleep 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@142.132.184.134) Running State Change CS_ROUTING (Cur 2 Tot 108) 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_channel.c:2380 (sofia/default/1515121325@142.132.184.134) Callstate Change DOWN -> RINGING 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@142.132.184.134) State ROUTING 2022-12-06 12:08:06.346166 90.80% [DEBUG] mod_sofia.c:158 sofia/default/1515121325@142.132.184.134 SOFIA ROUTING 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_core_state_machine.c:230 sofia/default/1515121325@142.132.184.134 Standard ROUTING 2022-12-06 12:08:06.346166 90.80% [INFO] mod_dialplan_xml.c:639 Processing 990010 <1515121325>->990010 in context default 2022-12-06 12:08:06.346166 90.80% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2022-12-06 12:08:06.346166 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Voice Broadcast Destination Number 1515121325 2022-12-06 12:08:06.366141 90.80% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0 2022-12-06 12:08:06.366141 90.80% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1515121325 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '1515121325' limit 1 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '1515121325' and status=0 LIMIT 1 2022-12-06 12:08:06.366141 90.80% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBERmod_sofia 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '1515121325' limit 1 2022-12-06 12:08:06.366141 90.80% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID 2022-12-06 12:08:06.366141 90.80% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 990010 / 1515121325 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number 1325 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 151512 AND status=0 limit 1 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0 2022-12-06 12:08:06.366141 90.80% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1515121325" AND domains.domain="astppqa601.astppbilling.org" limit 1 2022-12-06 12:08:06.366141 90.80% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound 2022-12-06 12:08:06.366141 90.80% [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("142.132.184.134") 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 2022-12-06 12:08:06.366141 90.80% [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("142.132.184.134") 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] [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.31]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',(-(1))))) - 1)' ] 2022-12-06 12:08:06.366141 90.80% [ERR] mod_lua.cpp:202 /usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:307: assertion failed! stack traceback: [C]: in function 'assert' /usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:307: in function (...tail calls...) ./astpp/scripts/astpp.dialplan.lua:266: in main chunk [C]: in function 'dofile' ./astpp/astpp_deb9.lua:138: in main chunk 2022-12-06 12:08:06.366141 90.80% [ERR] mod_lua.cpp:270 LUA script parse/execute error! 2022-12-06 12:08:06.366141 90.80% [WARNING] mod_dialplan_xml.c:669 Context default not found 2022-12-06 12:08:06.366141 90.80% [INFO] switch_core_state_machine.c:306 No Route, Aborting 2022-12-06 12:08:06.366141 90.80% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/1515121325@142.132.184.134 [CS_ROUTING] [NO_ROUTE_DESTINATION] 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@142.132.184.134) State ROUTING going to sleep 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@142.132.184.134) Running State Change CS_HANGUP (Cur 2 Tot 108) 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1515121325@142.132.184.134) Callstate Change RINGING -> HANGUP 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@142.132.184.134) State HANGUP 2022-12-06 12:08:06.366141 90.80% [DEBUG] mod_sofia.c:468 Channel sofia/default/1515121325@142.132.184.134 hanging up, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:06.366141 90.80% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:59 sofia/default/1515121325@142.132.184.134 Standard HANGUP, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@142.132.184.134) State HANGUP going to sleep 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1515121325@142.132.184.134) State Change CS_HANGUP -> CS_REPORTING 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@142.132.184.134) Running State Change CS_REPORTING (Cur 2 Tot 108) 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@142.132.184.134) State REPORTING 2022-12-06 12:08:06.366141 90.80% [DEBUG] sofia.c:6678 Remote Reason: 3 2022-12-06 12:08:06.366141 90.80% [INFO] mod_json_cdr.c:271 Process [6df21960-68b8-497f-a313-c865353eeeeb.cdr.json] 2022-12-06 12:08:06.366141 90.80% [DEBUG] sofia.c:7499 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 entering state [terminated][404] 2022-12-06 12:08:06.366141 90.80% [NOTICE] sofia.c:8738 Hangup sofia/default/990010@astppqa601.astppbilling.org:5076 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION] 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_HANGUP (Cur 2 Tot 108) 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:844 (sofia/default/990010@astppqa601.astppbilling.org:5076) Callstate Change DOWN -> HANGUP 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:846 (sofia/default/990010@astppqa601.astppbilling.org:5076) State HANGUP 2022-12-06 12:08:06.366141 90.80% [DEBUG] mod_sofia.c:468 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 hanging up, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:59 sofia/default/990010@astppqa601.astppbilling.org:5076 Standard HANGUP, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:846 (sofia/default/990010@astppqa601.astppbilling.org:5076) State HANGUP going to sleep 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:616 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_HANGUP -> CS_REPORTING 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_REPORTING (Cur 2 Tot 108) 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:932 (sofia/default/990010@astppqa601.astppbilling.org:5076) State REPORTING 2022-12-06 12:08:06.366141 90.80% [INFO] mod_json_cdr.c:271 Process [7bd8d17a-0e2a-4669-90a6-234cd2842d3c.cdr.json] 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:168 sofia/default/1515121325@142.132.184.134 Standard REPORTING, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@142.132.184.134) State REPORTING going to sleep 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1515121325@142.132.184.134) State Change CS_REPORTING -> CS_DESTROY 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_session.c:1753 Session 108 (sofia/default/1515121325@142.132.184.134) Locked, Waiting on external entities 2022-12-06 12:08:06.366141 90.80% [NOTICE] switch_core_session.c:1771 Session 108 (sofia/default/1515121325@142.132.184.134) Ended 2022-12-06 12:08:06.366141 90.80% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1515121325@142.132.184.134 [CS_DESTROY] 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1515121325@142.132.184.134) Running State Change CS_DESTROY (Cur 1 Tot 108) 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@142.132.184.134) State DESTROY 2022-12-06 12:08:06.366141 90.80% [DEBUG] mod_sofia.c:379 sofia/default/1515121325@142.132.184.134 SOFIA DESTROY 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:175 sofia/default/1515121325@142.132.184.134 Standard DESTROY 2022-12-06 12:08:06.366141 90.80% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@142.132.184.134) State DESTROY going to sleep 2022-12-06 12:08:06.386140 90.80% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION] 2022-12-06 12:08:06.646150 90.80% [DEBUG] switch_core_state_machine.c:168 sofia/default/990010@astppqa601.astppbilling.org:5076 Standard REPORTING, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:06.646150 90.80% [DEBUG] switch_core_state_machine.c:932 (sofia/default/990010@astppqa601.astppbilling.org:5076) State REPORTING going to sleep 2022-12-06 12:08:06.646150 90.80% [DEBUG] switch_core_state_machine.c:607 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_REPORTING -> CS_DESTROY 2022-12-06 12:08:06.646150 90.80% [DEBUG] switch_core_session.c:1753 Session 107 (sofia/default/990010@astppqa601.astppbilling.org:5076) Locked, Waiting on external entities 2022-12-06 12:08:06.646150 90.80% [NOTICE] switch_core_session.c:1771 Session 107 (sofia/default/990010@astppqa601.astppbilling.org:5076) Ended 2022-12-06 12:08:06.646150 90.80% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/990010@astppqa601.astppbilling.org:5076 [CS_DESTROY] 2022-12-06 12:08:06.646150 90.80% [DEBUG] switch_core_state_machine.c:735 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_DESTROY (Cur 0 Tot 108) 2022-12-06 12:08:06.646150 90.80% [DEBUG] switch_core_state_machine.c:745 (sofia/default/990010@astppqa601.astppbilling.org:5076) State DESTROY 2022-12-06 12:08:06.646150 90.80% [DEBUG] mod_sofia.c:379 sofia/default/990010@astppqa601.astppbilling.org:5076 SOFIA DESTROY 2022-12-06 12:08:06.646150 90.80% [DEBUG] switch_core_state_machine.c:175 sofia/default/990010@astppqa601.astppbilling.org:5076 Standard DESTROY 2022-12-06 12:08:06.646150 90.80% [DEBUG] switch_core_state_machine.c:745 (sofia/default/990010@astppqa601.astppbilling.org:5076) State DESTROY going to sleep 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-12-06 12:08:11.386175 89.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/990010@astppqa601.astppbilling.org:5076 [933fe54f-8a32-48df-99cb-6415744b2d47] 2022-12-06 12:08:11.386175 89.67% [DEBUG] mod_sofia.c:5121 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_NEW -> CS_INIT 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_INIT (Cur 1 Tot 109) 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/990010@astppqa601.astppbilling.org:5076) State INIT 2022-12-06 12:08:11.386175 89.67% [DEBUG] mod_sofia.c:97 sofia/default/990010@astppqa601.astppbilling.org:5076 SOFIA INIT 2022-12-06 12:08:11.386175 89.67% [INFO] sofia_glue.c:1651 sofia/default/990010@astppqa601.astppbilling.org:5076 sending invite call-id: (null) 2022-12-06 12:08:11.386175 89.67% [DEBUG] sofia_glue.c:1654 sofia/default/990010@astppqa601.astppbilling.org:5076 sending invite version: 1.10.7 -release 64bit Local SDP: v=0 o=FreeSWITCH 1670310823 1670310824 IN IP4 142.132.184.134 s=FreeSWITCH c=IN IP4 142.132.184.134 t=0 0 m=audio 17668 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/990010@astppqa601.astppbilling.org:5076 Standard INIT 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_INIT -> CS_ROUTING 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/990010@astppqa601.astppbilling.org:5076) State INIT going to sleep 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_ROUTING (Cur 1 Tot 109) 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/990010@astppqa601.astppbilling.org:5076) State ROUTING 2022-12-06 12:08:11.386175 89.67% [DEBUG] mod_sofia.c:158 sofia/default/990010@astppqa601.astppbilling.org:5076 SOFIA ROUTING 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/990010@astppqa601.astppbilling.org:5076) State ROUTING going to sleep 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 109) 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/990010@astppqa601.astppbilling.org:5076) State CONSUME_MEDIA 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/990010@astppqa601.astppbilling.org:5076) State CONSUME_MEDIA going to sleep 2022-12-06 12:08:11.386175 89.67% [DEBUG] sofia.c:7499 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 entering state [calling][0] 2022-12-06 12:08:11.386175 89.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/2536854752@142.132.184.134 [1bd93420-f977-4dae-b9c1-0474e287a6f8] 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@142.132.184.134) Running State Change CS_NEW (Cur 2 Tot 110) 2022-12-06 12:08:11.386175 89.67% [INFO] sofia.c:10462 sofia/default/2536854752@142.132.184.134 receiving invite from 142.132.184.134:5076 version: 1.10.7 -release 64bit call-id: 7ec06274-f001-123b-c298-9600013c8d14 2022-12-06 12:08:11.386175 89.67% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 142.132.184.134:0. 2022-12-06 12:08:11.386175 89.67% [DEBUG] sofia.c:10585 IP 142.132.184.134 Approved by acl "default[]". Access Granted. 2022-12-06 12:08:11.386175 89.67% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@142.132.184.134 entering state [received][100] 2022-12-06 12:08:11.386175 89.67% [DEBUG] sofia.c:7509 Remote SDP: v=0 o=FreeSWITCH 1670310823 1670310824 IN IP4 142.132.184.134 s=FreeSWITCH c=IN IP4 142.132.184.134 t=0 0 m=audio 17668 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/2536854752@142.132.184.134 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_codec.c:111 sofia/default/2536854752@142.132.184.134 Original read codec set to PCMA:8 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_media.c:5973 sofia/default/2536854752@142.132.184.134 Set 2833 dtmf send payload to 101 recv payload to 101 2022-12-06 12:08:11.386175 89.67% [DEBUG] sofia.c:7933 (sofia/default/2536854752@142.132.184.134) State Change CS_NEW -> CS_INIT 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:600 (sofia/default/2536854752@142.132.184.134) State NEW 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@142.132.184.134) Running State Change CS_INIT (Cur 2 Tot 110) 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@142.132.184.134) State INIT 2022-12-06 12:08:11.386175 89.67% [DEBUG] mod_sofia.c:97 sofia/default/2536854752@142.132.184.134 SOFIA INIT 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/2536854752@142.132.184.134 Standard INIT 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/2536854752@142.132.184.134) State Change CS_INIT -> CS_ROUTING 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@142.132.184.134) State INIT going to sleep 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@142.132.184.134) Running State Change CS_ROUTING (Cur 2 Tot 110) 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_channel.c:2380 (sofia/default/2536854752@142.132.184.134) Callstate Change DOWN -> RINGING 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@142.132.184.134) State ROUTING 2022-12-06 12:08:11.386175 89.67% [DEBUG] mod_sofia.c:158 sofia/default/2536854752@142.132.184.134 SOFIA ROUTING 2022-12-06 12:08:11.386175 89.67% [DEBUG] switch_core_state_machine.c:230 sofia/default/2536854752@142.132.184.134 Standard ROUTING 2022-12-06 12:08:11.386175 89.67% [INFO] mod_dialplan_xml.c:639 Processing 990010 <2536854752>->990010 in context default 2022-12-06 12:08:11.406144 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Voice Broadcast Destination Number 2536854752 2022-12-06 12:08:11.406144 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0 2022-12-06 12:08:11.406144 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 2536854752 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '2536854752' limit 1 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '2536854752' and status=0 LIMIT 1 2022-12-06 12:08:11.406144 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBERmod_sofia 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '2536854752' limit 1 2022-12-06 12:08:11.406144 89.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID 2022-12-06 12:08:11.406144 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 990010 / 2536854752 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number 4752 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 253685 AND status=0 limit 1 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0 2022-12-06 12:08:11.406144 89.67% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="2536854752" AND domains.domain="astppqa601.astppbilling.org" limit 1 2022-12-06 12:08:11.406144 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound 2022-12-06 12:08:11.406144 89.67% [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("142.132.184.134") 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 2022-12-06 12:08:11.406144 89.67% [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("142.132.184.134") 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] [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.31]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',(-(1))))) - 1)' ] 2022-12-06 12:08:11.406144 89.67% [ERR] mod_lua.cpp:202 /usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:307: assertion failed! stack traceback: [C]: in function 'assert' /usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:307: in function (...tail calls...) ./astpp/scripts/astpp.dialplan.lua:266: in main chunk [C]: in function 'dofile' ./astpp/astpp_deb9.lua:138: in main chunk 2022-12-06 12:08:11.406144 89.67% [ERR] mod_lua.cpp:270 LUA script parse/execute error! 2022-12-06 12:08:11.406144 89.67% [WARNING] mod_dialplan_xml.c:669 Context default not found 2022-12-06 12:08:11.406144 89.67% [INFO] switch_core_state_machine.c:306 No Route, Aborting 2022-12-06 12:08:11.406144 89.67% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/2536854752@142.132.184.134 [CS_ROUTING] [NO_ROUTE_DESTINATION] 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@142.132.184.134) State ROUTING going to sleep 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@142.132.184.134) Running State Change CS_HANGUP (Cur 2 Tot 110) 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/2536854752@142.132.184.134) Callstate Change RINGING -> HANGUP 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@142.132.184.134) State HANGUP 2022-12-06 12:08:11.406144 89.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/2536854752@142.132.184.134 hanging up, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.406144 89.67% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/2536854752@142.132.184.134 Standard HANGUP, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@142.132.184.134) State HANGUP going to sleep 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/2536854752@142.132.184.134) State Change CS_HANGUP -> CS_REPORTING 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@142.132.184.134) Running State Change CS_REPORTING (Cur 2 Tot 110) 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@142.132.184.134) State REPORTING 2022-12-06 12:08:11.406144 89.67% [INFO] mod_json_cdr.c:271 Process [1bd93420-f977-4dae-b9c1-0474e287a6f8.cdr.json] 2022-12-06 12:08:11.406144 89.67% [DEBUG] sofia.c:6678 Remote Reason: 3 2022-12-06 12:08:11.406144 89.67% [DEBUG] sofia.c:7499 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 entering state [terminated][404] 2022-12-06 12:08:11.406144 89.67% [NOTICE] sofia.c:8738 Hangup sofia/default/990010@astppqa601.astppbilling.org:5076 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION] 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_HANGUP (Cur 2 Tot 110) 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/990010@astppqa601.astppbilling.org:5076) Callstate Change DOWN -> HANGUP 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/990010@astppqa601.astppbilling.org:5076) State HANGUP 2022-12-06 12:08:11.406144 89.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 hanging up, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/990010@astppqa601.astppbilling.org:5076 Standard HANGUP, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/990010@astppqa601.astppbilling.org:5076) State HANGUP going to sleep 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_HANGUP -> CS_REPORTING 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_REPORTING (Cur 2 Tot 110) 2022-12-06 12:08:11.406144 89.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/990010@astppqa601.astppbilling.org:5076) State REPORTING 2022-12-06 12:08:11.406144 89.67% [INFO] mod_json_cdr.c:271 Process [933fe54f-8a32-48df-99cb-6415744b2d47.cdr.json] 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/2536854752@142.132.184.134 Standard REPORTING, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@142.132.184.134) State REPORTING going to sleep 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/2536854752@142.132.184.134) State Change CS_REPORTING -> CS_DESTROY 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_session.c:1753 Session 110 (sofia/default/2536854752@142.132.184.134) Locked, Waiting on external entities 2022-12-06 12:08:11.426133 89.67% [NOTICE] switch_core_session.c:1771 Session 110 (sofia/default/2536854752@142.132.184.134) Ended 2022-12-06 12:08:11.426133 89.67% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/2536854752@142.132.184.134 [CS_DESTROY] 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/2536854752@142.132.184.134) Running State Change CS_DESTROY (Cur 1 Tot 110) 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@142.132.184.134) State DESTROY 2022-12-06 12:08:11.426133 89.67% [DEBUG] mod_sofia.c:379 sofia/default/2536854752@142.132.184.134 SOFIA DESTROY 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/2536854752@142.132.184.134 Standard DESTROY 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@142.132.184.134) State DESTROY going to sleep 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION] 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-12-06 12:08:11.426133 89.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/990010@astppqa601.astppbilling.org:5076 [5a7d58bd-a27e-4eef-8b4f-89bed8eb76e9] 2022-12-06 12:08:11.426133 89.67% [DEBUG] mod_sofia.c:5121 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_NEW -> CS_INIT 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_INIT (Cur 2 Tot 111) 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/990010@astppqa601.astppbilling.org:5076) State INIT 2022-12-06 12:08:11.426133 89.67% [DEBUG] mod_sofia.c:97 sofia/default/990010@astppqa601.astppbilling.org:5076 SOFIA INIT 2022-12-06 12:08:11.426133 89.67% [INFO] sofia_glue.c:1651 sofia/default/990010@astppqa601.astppbilling.org:5076 sending invite call-id: (null) 2022-12-06 12:08:11.426133 89.67% [DEBUG] sofia_glue.c:1654 sofia/default/990010@astppqa601.astppbilling.org:5076 sending invite version: 1.10.7 -release 64bit Local SDP: v=0 o=FreeSWITCH 1670302057 1670302058 IN IP4 142.132.184.134 s=FreeSWITCH c=IN IP4 142.132.184.134 t=0 0 m=audio 26434 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/990010@astppqa601.astppbilling.org:5076 Standard INIT 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_INIT -> CS_ROUTING 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/990010@astppqa601.astppbilling.org:5076) State INIT going to sleep 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_ROUTING (Cur 2 Tot 111) 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/990010@astppqa601.astppbilling.org:5076) State ROUTING 2022-12-06 12:08:11.426133 89.67% [DEBUG] mod_sofia.c:158 sofia/default/990010@astppqa601.astppbilling.org:5076 SOFIA ROUTING 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/990010@astppqa601.astppbilling.org:5076) State ROUTING going to sleep 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 111) 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/990010@astppqa601.astppbilling.org:5076) State CONSUME_MEDIA 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/990010@astppqa601.astppbilling.org:5076) State CONSUME_MEDIA going to sleep 2022-12-06 12:08:11.426133 89.67% [DEBUG] sofia.c:7499 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 entering state [calling][0] 2022-12-06 12:08:11.426133 89.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1554856322@142.132.184.134 [f0e85cfa-272e-4b2f-8810-fc44fd6ad88e] 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@142.132.184.134) Running State Change CS_NEW (Cur 3 Tot 112) 2022-12-06 12:08:11.426133 89.67% [INFO] sofia.c:10462 sofia/default/1554856322@142.132.184.134 receiving invite from 142.132.184.134:5076 version: 1.10.7 -release 64bit call-id: 7ec6fb95-f001-123b-c298-9600013c8d14 2022-12-06 12:08:11.426133 89.67% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 142.132.184.134:0. 2022-12-06 12:08:11.426133 89.67% [DEBUG] sofia.c:10585 IP 142.132.184.134 Approved by acl "default[]". Access Granted. 2022-12-06 12:08:11.426133 89.67% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@142.132.184.134 entering state [received][100] 2022-12-06 12:08:11.426133 89.67% [DEBUG] sofia.c:7509 Remote SDP: v=0 o=FreeSWITCH 1670302057 1670302058 IN IP4 142.132.184.134 s=FreeSWITCH c=IN IP4 142.132.184.134 t=0 0 m=audio 26434 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1554856322@142.132.184.134 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_codec.c:111 sofia/default/1554856322@142.132.184.134 Original read codec set to PCMA:8 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_media.c:5973 sofia/default/1554856322@142.132.184.134 Set 2833 dtmf send payload to 101 recv payload to 101 2022-12-06 12:08:11.426133 89.67% [DEBUG] sofia.c:7933 (sofia/default/1554856322@142.132.184.134) State Change CS_NEW -> CS_INIT 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1554856322@142.132.184.134) State NEW 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@142.132.184.134) Running State Change CS_INIT (Cur 3 Tot 112) 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@142.132.184.134) State INIT 2022-12-06 12:08:11.426133 89.67% [DEBUG] mod_sofia.c:97 sofia/default/1554856322@142.132.184.134 SOFIA INIT 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/1554856322@142.132.184.134 Standard INIT 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1554856322@142.132.184.134) State Change CS_INIT -> CS_ROUTING 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@142.132.184.134) State INIT going to sleep 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@142.132.184.134) Running State Change CS_ROUTING (Cur 3 Tot 112) 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_channel.c:2380 (sofia/default/1554856322@142.132.184.134) Callstate Change DOWN -> RINGING 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@142.132.184.134) State ROUTING 2022-12-06 12:08:11.426133 89.67% [DEBUG] mod_sofia.c:158 sofia/default/1554856322@142.132.184.134 SOFIA ROUTING 2022-12-06 12:08:11.426133 89.67% [DEBUG] switch_core_state_machine.c:230 sofia/default/1554856322@142.132.184.134 Standard ROUTING 2022-12-06 12:08:11.426133 89.67% [INFO] mod_dialplan_xml.c:639 Processing 990010 <1554856322>->990010 in context default 2022-12-06 12:08:11.446138 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Voice Broadcast Destination Number 1554856322 2022-12-06 12:08:11.446138 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0 2022-12-06 12:08:11.446138 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1554856322 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '1554856322' limit 1 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '1554856322' and status=0 LIMIT 1 2022-12-06 12:08:11.446138 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBERmod_sofia 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '1554856322' limit 1 2022-12-06 12:08:11.446138 89.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID 2022-12-06 12:08:11.446138 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 990010 / 1554856322 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number 6322 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 155485 AND status=0 limit 1 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0 2022-12-06 12:08:11.446138 89.67% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1554856322" AND domains.domain="astppqa601.astppbilling.org" limit 1 2022-12-06 12:08:11.446138 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound 2022-12-06 12:08:11.446138 89.67% [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("142.132.184.134") 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 2022-12-06 12:08:11.446138 89.67% [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("142.132.184.134") 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] [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.31]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',(-(1))))) - 1)' ] 2022-12-06 12:08:11.446138 89.67% [ERR] mod_lua.cpp:202 /usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:307: assertion failed! stack traceback: [C]: in function 'assert' /usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:307: in function (...tail calls...) ./astpp/scripts/astpp.dialplan.lua:266: in main chunk [C]: in function 'dofile' ./astpp/astpp_deb9.lua:138: in main chunk 2022-12-06 12:08:11.446138 89.67% [ERR] mod_lua.cpp:270 LUA script parse/execute error! 2022-12-06 12:08:11.446138 89.67% [WARNING] mod_dialplan_xml.c:669 Context default not found 2022-12-06 12:08:11.446138 89.67% [INFO] switch_core_state_machine.c:306 No Route, Aborting 2022-12-06 12:08:11.446138 89.67% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/1554856322@142.132.184.134 [CS_ROUTING] [NO_ROUTE_DESTINATION] 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@142.132.184.134) State ROUTING going to sleep 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@142.132.184.134) Running State Change CS_HANGUP (Cur 3 Tot 112) 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1554856322@142.132.184.134) Callstate Change RINGING -> HANGUP 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@142.132.184.134) State HANGUP 2022-12-06 12:08:11.446138 89.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/1554856322@142.132.184.134 hanging up, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.446138 89.67% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/1554856322@142.132.184.134 Standard HANGUP, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@142.132.184.134) State HANGUP going to sleep 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1554856322@142.132.184.134) State Change CS_HANGUP -> CS_REPORTING 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@142.132.184.134) Running State Change CS_REPORTING (Cur 3 Tot 112) 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@142.132.184.134) State REPORTING 2022-12-06 12:08:11.446138 89.67% [INFO] mod_json_cdr.c:271 Process [f0e85cfa-272e-4b2f-8810-fc44fd6ad88e.cdr.json] 2022-12-06 12:08:11.446138 89.67% [DEBUG] sofia.c:6678 Remote Reason: 3 2022-12-06 12:08:11.446138 89.67% [DEBUG] sofia.c:7499 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 entering state [terminated][404] 2022-12-06 12:08:11.446138 89.67% [NOTICE] sofia.c:8738 Hangup sofia/default/990010@astppqa601.astppbilling.org:5076 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION] 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_HANGUP (Cur 3 Tot 112) 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/990010@astppqa601.astppbilling.org:5076) Callstate Change DOWN -> HANGUP 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/990010@astppqa601.astppbilling.org:5076) State HANGUP 2022-12-06 12:08:11.446138 89.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 hanging up, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/990010@astppqa601.astppbilling.org:5076 Standard HANGUP, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/990010@astppqa601.astppbilling.org:5076) State HANGUP going to sleep 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_HANGUP -> CS_REPORTING 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_REPORTING (Cur 3 Tot 112) 2022-12-06 12:08:11.446138 89.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/990010@astppqa601.astppbilling.org:5076) State REPORTING 2022-12-06 12:08:11.446138 89.67% [INFO] mod_json_cdr.c:271 Process [5a7d58bd-a27e-4eef-8b4f-89bed8eb76e9.cdr.json] 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/1554856322@142.132.184.134 Standard REPORTING, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@142.132.184.134) State REPORTING going to sleep 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1554856322@142.132.184.134) State Change CS_REPORTING -> CS_DESTROY 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_session.c:1753 Session 112 (sofia/default/1554856322@142.132.184.134) Locked, Waiting on external entities 2022-12-06 12:08:11.466135 89.67% [NOTICE] switch_core_session.c:1771 Session 112 (sofia/default/1554856322@142.132.184.134) Ended 2022-12-06 12:08:11.466135 89.67% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1554856322@142.132.184.134 [CS_DESTROY] 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1554856322@142.132.184.134) Running State Change CS_DESTROY (Cur 2 Tot 112) 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@142.132.184.134) State DESTROY 2022-12-06 12:08:11.466135 89.67% [DEBUG] mod_sofia.c:379 sofia/default/1554856322@142.132.184.134 SOFIA DESTROY 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/1554856322@142.132.184.134 Standard DESTROY 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@142.132.184.134) State DESTROY going to sleep 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION] 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-12-06 12:08:11.466135 89.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/990010@astppqa601.astppbilling.org:5076 [c9109721-fd10-4323-bfc6-54a214db3f1e] 2022-12-06 12:08:11.466135 89.67% [DEBUG] mod_sofia.c:5121 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_NEW -> CS_INIT 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_INIT (Cur 3 Tot 113) 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/990010@astppqa601.astppbilling.org:5076) State INIT 2022-12-06 12:08:11.466135 89.67% [DEBUG] mod_sofia.c:97 sofia/default/990010@astppqa601.astppbilling.org:5076 SOFIA INIT 2022-12-06 12:08:11.466135 89.67% [INFO] sofia_glue.c:1651 sofia/default/990010@astppqa601.astppbilling.org:5076 sending invite call-id: (null) 2022-12-06 12:08:11.466135 89.67% [DEBUG] sofia_glue.c:1654 sofia/default/990010@astppqa601.astppbilling.org:5076 sending invite version: 1.10.7 -release 64bit Local SDP: v=0 o=FreeSWITCH 1670295751 1670295752 IN IP4 142.132.184.134 s=FreeSWITCH c=IN IP4 142.132.184.134 t=0 0 m=audio 32740 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/990010@astppqa601.astppbilling.org:5076 Standard INIT 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_INIT -> CS_ROUTING 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/990010@astppqa601.astppbilling.org:5076) State INIT going to sleep 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_ROUTING (Cur 3 Tot 113) 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/990010@astppqa601.astppbilling.org:5076) State ROUTING 2022-12-06 12:08:11.466135 89.67% [DEBUG] mod_sofia.c:158 sofia/default/990010@astppqa601.astppbilling.org:5076 SOFIA ROUTING 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/990010@astppqa601.astppbilling.org:5076) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/990010@astppqa601.astppbilling.org:5076) State ROUTING going to sleep 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/990010@astppqa601.astppbilling.org:5076) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 113) 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/990010@astppqa601.astppbilling.org:5076) State CONSUME_MEDIA 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/990010@astppqa601.astppbilling.org:5076) State CONSUME_MEDIA going to sleep 2022-12-06 12:08:11.466135 89.67% [DEBUG] sofia.c:7499 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 entering state [calling][0] 2022-12-06 12:08:11.466135 89.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1547964325@142.132.184.134 [d3926677-3c16-486f-b11f-c6d247fcf7e8] 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@142.132.184.134) Running State Change CS_NEW (Cur 4 Tot 114) 2022-12-06 12:08:11.466135 89.67% [INFO] sofia.c:10462 sofia/default/1547964325@142.132.184.134 receiving invite from 142.132.184.134:5076 version: 1.10.7 -release 64bit call-id: 7ecdba62-f001-123b-c298-9600013c8d14 2022-12-06 12:08:11.466135 89.67% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 142.132.184.134:0. 2022-12-06 12:08:11.466135 89.67% [DEBUG] sofia.c:10585 IP 142.132.184.134 Approved by acl "default[]". Access Granted. 2022-12-06 12:08:11.466135 89.67% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@142.132.184.134 entering state [received][100] 2022-12-06 12:08:11.466135 89.67% [DEBUG] sofia.c:7509 Remote SDP: v=0 o=FreeSWITCH 1670295751 1670295752 IN IP4 142.132.184.134 s=FreeSWITCH c=IN IP4 142.132.184.134 t=0 0 m=audio 32740 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1547964325@142.132.184.134 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_codec.c:111 sofia/default/1547964325@142.132.184.134 Original read codec set to PCMA:8 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_media.c:5973 sofia/default/1547964325@142.132.184.134 Set 2833 dtmf send payload to 101 recv payload to 101 2022-12-06 12:08:11.466135 89.67% [DEBUG] sofia.c:7933 (sofia/default/1547964325@142.132.184.134) State Change CS_NEW -> CS_INIT 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1547964325@142.132.184.134) State NEW 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@142.132.184.134) Running State Change CS_INIT (Cur 4 Tot 114) 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@142.132.184.134) State INIT 2022-12-06 12:08:11.466135 89.67% [DEBUG] mod_sofia.c:97 sofia/default/1547964325@142.132.184.134 SOFIA INIT 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/1547964325@142.132.184.134 Standard INIT 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1547964325@142.132.184.134) State Change CS_INIT -> CS_ROUTING 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@142.132.184.134) State INIT going to sleep 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@142.132.184.134) Running State Change CS_ROUTING (Cur 4 Tot 114) 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_channel.c:2380 (sofia/default/1547964325@142.132.184.134) Callstate Change DOWN -> RINGING 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@142.132.184.134) State ROUTING 2022-12-06 12:08:11.466135 89.67% [DEBUG] mod_sofia.c:158 sofia/default/1547964325@142.132.184.134 SOFIA ROUTING 2022-12-06 12:08:11.466135 89.67% [DEBUG] switch_core_state_machine.c:230 sofia/default/1547964325@142.132.184.134 Standard ROUTING 2022-12-06 12:08:11.466135 89.67% [INFO] mod_dialplan_xml.c:639 Processing 990010 <1547964325>->990010 in context default 2022-12-06 12:08:11.486147 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Voice Broadcast Destination Number 1547964325 2022-12-06 12:08:11.486147 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0 2022-12-06 12:08:11.486147 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1547964325 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '1547964325' limit 1 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '1547964325' and status=0 LIMIT 1 2022-12-06 12:08:11.486147 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBERmod_sofia 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '1547964325' limit 1 2022-12-06 12:08:11.486147 89.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID 2022-12-06 12:08:11.486147 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 990010 / 1547964325 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number 4325 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 154796 AND status=0 limit 1 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0 2022-12-06 12:08:11.486147 89.67% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1547964325" AND domains.domain="astppqa601.astppbilling.org" limit 1 2022-12-06 12:08:11.486147 89.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound 2022-12-06 12:08:11.486147 89.67% [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("142.132.184.134") 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 2022-12-06 12:08:11.486147 89.67% [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("142.132.184.134") 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] [STATE: HY000 CODE 1690 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.31]BIGINT UNSIGNED value is out of range in '((0x01 << (32 - substring_index(`astpp`.`ip_map`.`ip`,'/',(-(1))))) - 1)' ] 2022-12-06 12:08:11.486147 89.67% [ERR] mod_lua.cpp:202 /usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:307: assertion failed! stack traceback: [C]: in function 'assert' /usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:307: in function (...tail calls...) ./astpp/scripts/astpp.dialplan.lua:266: in main chunk [C]: in function 'dofile' ./astpp/astpp_deb9.lua:138: in main chunk 2022-12-06 12:08:11.486147 89.67% [ERR] mod_lua.cpp:270 LUA script parse/execute error! 2022-12-06 12:08:11.486147 89.67% [WARNING] mod_dialplan_xml.c:669 Context default not found 2022-12-06 12:08:11.486147 89.67% [INFO] switch_core_state_machine.c:306 No Route, Aborting 2022-12-06 12:08:11.486147 89.67% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/1547964325@142.132.184.134 [CS_ROUTING] [NO_ROUTE_DESTINATION] 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@142.132.184.134) State ROUTING going to sleep 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@142.132.184.134) Running State Change CS_HANGUP (Cur 4 Tot 114) 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1547964325@142.132.184.134) Callstate Change RINGING -> HANGUP 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@142.132.184.134) State HANGUP 2022-12-06 12:08:11.486147 89.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/1547964325@142.132.184.134 hanging up, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.486147 89.67% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/1547964325@142.132.184.134 Standard HANGUP, cause: NO_ROUTE_DESTINATION 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@142.132.184.134) State HANGUP going to sleep 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1547964325@142.132.184.134) State Change CS_HANGUP -> CS_REPORTING 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@142.132.184.134) Running State Change CS_REPORTING (Cur 4 Tot 114) 2022-12-06 12:08:11.486147 89.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@142.132.184.134) State REPORTING 2022-12-06 12:08:11.486147 89.67% [DEBUG] sofia.c:6678 Remote Reason: 3 2022-12-06 12:08:11.486147 89.67% [INFO] mod_json_cdr.c:271 Process [d3926677-3c16-486f-b11f-c6d247fcf7e8.cdr.json] 2022-12-06 12:08:11.486147 89.67% [DEBUG] sofia.c:7499 Channel sofia/default/990010@astppqa601.astppbilling.org:5076 entering state [terminated][404] 2022-12-06 12:08:11.486147 89.67% [NOTICE] sofia.c:8738 Hangup sofia/default/990010@astppqa601.astppbilling.org:5076 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]