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`,'/',<cache>(-(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 </usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:300>
(...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`,'/',<cache>(-(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 </usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:300>
(...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`,'/',<cache>(-(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 </usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:300>
(...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`,'/',<cache>(-(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 </usr/local/freeswitch/scripts/astpp/lib/astpp.functions.lua:300>
(...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]