ORIGNATION_RATE_NOT_FOUND [31]

gangasagar · June 8, 2022, 6:12am

2022-06-08 11:18:10.921982 98.20% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile ‘default’ for [9009206195@XXX.XXX.XXX.XXX] from ip 117.234.76.21
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:13.762031 98.13% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:13.762031 98.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Running State Change CS_NEW (Cur 1 Tot 9495)
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:13.762031 98.13% [INFO] sofia.c:10462 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 receiving invite from 117.234.76.21:15728 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 970a096fa43d5142MzU3ZGNkODgzNzA0MDMxZDliN2RiMWUwOGRhOWQ3YWU.
2022-06-08 11:18:13.762031 98.13% [DEBUG] sofia.c:10556 verifying acl “default” for ip/port 117.234.76.21:0.
2022-06-08 11:18:13.762031 98.13% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile ‘default’ for [919368690906@XXX.XXX.XXX.XXX] from ip 117.234.76.21
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:13.762031 98.13% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State NEW
2022-06-08 11:18:13.762031 98.13% [DEBUG] sofia.c:2419 detaching session c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0
2022-06-08 11:18:13.801960 98.13% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile ‘default’ for [9009206195@XXX.XXX.XXX.XXX] from ip 117.234.76.21
2022-06-08 11:18:14.521989 98.13% [DEBUG] sofia.c:2532 Re-attaching to session c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [INFO] sofia.c:10462 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 receiving invite from 117.234.76.21:15728 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 970a096fa43d5142MzU3ZGNkODgzNzA0MDMxZDliN2RiMWUwOGRhOWQ3YWU.
2022-06-08 11:18:14.541965 98.13% [DEBUG] sofia.c:10556 verifying acl “default” for ip/port 117.234.76.21:0.
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] sofia.c:7499 Channel sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 entering state [received][100]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] sofia.c:7509 Remote SDP:
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 v=0
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 o=- 5106954 2 IN IP4 192.168.42.164
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 s=<CounterPath eyeBeam 1.5>
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 c=IN IP4 192.168.42.164
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 t=0 0
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 m=audio 57702 RTP/AVP 107 100 106 6 0 105 8 18 3 5 101
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=rtpmap:107 BV32/16000
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=rtpmap:100 SPEEX/16000
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=rtpmap:106 SPEEX-FEC/16000
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=rtpmap:105 SPEEX-FEC/8000
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=rtpmap:101 telephone-event/8000
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=fmtp:101 0-15
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=alt:1 3 : pdsyfqvq eojituqg 169.254.9.184 57702
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=alt:2 2 : gysoowja gjshtclx 169.254.18.232 57702
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=alt:3 1 : fqeldxqt wdufhrlp 192.168.42.164 57702
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 a=x-rtp-session-id:E0C8051B87E4478A82C83E36C0A0FE1F
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [BV32:107:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [BV32:107:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [SPEEX:100:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [SPEEX:100:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [SPEEX-FEC:106:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [SPEEX-FEC:106:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [SPEEX-FEC:105:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [SPEEX-FEC:105:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_codec.c:111 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Original read codec set to PCMU:0
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_media.c:5973 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Set 2833 dtmf send payload to 101 recv payload to 101
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] sofia.c:7933 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State Change CS_NEW → CS_INIT
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Running State Change CS_INIT (Cur 1 Tot 9495)
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State INIT
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] mod_sofia.c:97 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 SOFIA INIT
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_state_machine.c:40 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Standard INIT
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State Change CS_INIT → CS_ROUTING
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State INIT going to sleep
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Running State Change CS_ROUTING (Cur 1 Tot 9495)
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_channel.c:2380 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Callstate Change DOWN → RINGING
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State ROUTING
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] mod_sofia.c:158 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 SOFIA ROUTING
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [DEBUG] switch_core_state_machine.c:230 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Standard ROUTING
c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:14.541965 98.13% [INFO] mod_dialplan_xml.c:639 Processing 9009206195 <9009206195>->919368690906 in context default
2022-06-08 11:18:14.582741 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN (‘global’,‘opensips’,‘callingcard’,‘calls’,‘InternationalPrefixes’)
2022-06-08 11:18:14.622020 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2022-06-08 11:18:14.801964 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 919368690906
2022-06-08 11:18:14.801964 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = ‘919368690906’ AND status=0 limit 1
2022-06-08 11:18:14.801964 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 9009206195 / 9009206195
2022-06-08 11:18:14.801964 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number =“919368690906” LIMIT 1
2022-06-08 11:18:14.801964 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username=“919368690906” limit 1
2022-06-08 11:18:14.822068 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
2022-06-08 11:18:14.822068 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9009206195]
2022-06-08 11:18:14.822068 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = “9009206195” AND deleted = 0 limit 1
2022-06-08 11:18:15.081975 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = ‘^919368690906.’ OR blocked_patterns = '^91936869090.’ OR blocked_patterns = ‘^9193686909.’ OR blocked_patterns = '^919368690.’ OR blocked_patterns = ‘^91936869.’ OR blocked_patterns = '^9193686.’ OR blocked_patterns = ‘^919368.’ OR blocked_patterns = '^91936.’ OR blocked_patterns = ‘^9193.’ OR blocked_patterns = '^919.’ OR blocked_patterns = ‘^91.’ OR blocked_patterns = '^9.’ OR blocked_patterns =’–’) AND accountid = 13 limit 1
2022-06-08 11:18:15.081975 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num =“919368690906” AND A.accountid = ‘13’ limit 1
2022-06-08 11:18:15.081975 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 919368690906
2022-06-08 11:18:15.081975 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number =“919368690906” LIMIT 1
2022-06-08 11:18:15.081975 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username=“919368690906” limit 1
2022-06-08 11:18:15.081975 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call direction : outbound
2022-06-08 11:18:15.081975 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number =“919368690906” AND (accountid = 0 OR status = 1) LIMIT 1
2022-06-08 11:18:15.081975 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT ,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^919368690906.’ OR patterns = ‘^91936869090.’ OR patterns = '^9193686909.’ OR patterns = ‘^919368690.’ OR patterns = '^91936869.’ OR patterns = ‘^9193686.’ OR patterns = '^919368.’ OR patterns = ‘^91936.’ OR patterns = '^9193.’ OR patterns = ‘^919.’ OR patterns = '^91.’ OR patterns = ‘^9.’ OR patterns =’–’) AND accountid = 13 ORDER BY LENGTH(PKGPTR.patterns) DESC
2022-06-08 11:18:15.081975 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
2022-06-08 11:18:15.081975 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 13
2022-06-08 11:18:15.081975 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9009206195
2022-06-08 11:18:15.081975 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 100
2022-06-08 11:18:15.081975 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2022-06-08 11:18:15.081975 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 4
2022-06-08 11:18:15.081975 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
2022-06-08 11:18:15.081975 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
2022-06-08 11:18:15.081975 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0
2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^919368690906.’ OR pattern = ‘^91936869090.’ OR pattern = '^9193686909.’ OR pattern = ‘^919368690.’ OR pattern = '^91936869.’ OR pattern = ‘^9193686.’ OR pattern = '^919368.’ OR pattern = ‘^91936.’ OR pattern = '^9193.’ OR pattern = ‘^919.’ OR pattern = '^91.’ OR pattern = ‘^9.*’ OR pattern =’–’) AND status = 0 AND (pricelist_id = 4 OR accountid=13) ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
2022-06-08 11:18:15.101962 98.07% [INFO] switch_cpp.cpp:1465 [ASTPP] call_direction:::::: outbound
2022-06-08 11:18:15.101962 98.07% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Rates not found!!!
2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] post_cdrs:::0
2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 13
2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:

<?xml version="1.0" encoding="UTF-8" standalone="no"?> c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 parsing [default->919368690906] continue=false c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Regex (PASS) [919368690906] destination_number(919368690906) =~ /919368690906/ break=on-false c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action log(WARNING Accountcode 9009206195. Dialed number (919368690906) origination rates not found!!) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(original_caller_id_name=9009206195) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(original_caller_id_number=9009206195) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(error_cdr=1) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(callstart=2022-06-08 05:48:15) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(account_id=13) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(parent_id=0) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(call_direction=outbound) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(sip_ignore_remote_cause=true) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(call_processed=internal) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(effective_destination_number=919368690906) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action set(last_bridge_hangup_cause=ORIGNATION_RATE_NOT_FOUND) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 Dialplan: sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Action hangup(ORIGNATION_RATE_NOT_FOUND) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State Change CS_ROUTING -> CS_EXECUTE c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State ROUTING going to sleep c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Running State Change CS_EXECUTE (Cur 1 Tot 9495) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State EXECUTE c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_sofia.c:213 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 SOFIA EXECUTE c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:323 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Standard EXECUTE c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 log(WARNING Accountcode 9009206195. Dialed number (919368690906) origination rates not found!!) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [WARNING] mod_dptools.c:1879 Accountcode 9009206195. Dialed number (919368690906) origination rates not found!! c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(original_caller_id_name=9009206195) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [original_caller_id_name]=[9009206195] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(original_caller_id_number=9009206195) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [original_caller_id_number]=[9009206195] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(error_cdr=1) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [error_cdr]=[1] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(callstart=2022-06-08 05:48:15) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [callstart]=[2022-06-08 05:48:15] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(account_id=13) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [account_id]=[13] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(parent_id=0) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [parent_id]=[0] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(call_direction=outbound) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [call_direction]=[outbound] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(sip_ignore_remote_cause=true) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [sip_ignore_remote_cause]=[true] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(call_processed=internal) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [call_processed]=[internal] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(effective_destination_number=919368690906) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [effective_destination_number]=[919368690906] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 set(last_bridge_hangup_cause=ORIGNATION_RATE_NOT_FOUND) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_dptools.c:1685 SET sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [last_bridge_hangup_cause]=[ORIGNATION_RATE_NOT_FOUND] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 hangup(ORIGNATION_RATE_NOT_FOUND) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [NOTICE] mod_dptools.c:1387 Hangup sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [CS_EXECUTE] [NORMAL_CLEARING] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_session.c:2983 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 skip receive message [PHONE_EVENT] (channel is hungup already) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State EXECUTE going to sleep c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Running State Change CS_HANGUP (Cur 1 Tot 9495) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Callstate Change RINGING -> HANGUP c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State HANGUP c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_sofia.c:468 Channel sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 hanging up, cause: NORMAL_CLEARING c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 480 c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:59 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Standard HANGUP, cause: NORMAL_CLEARING c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State HANGUP going to sleep c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State Change CS_HANGUP -> CS_REPORTING c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Running State Change CS_REPORTING (Cur 1 Tot 9495) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State REPORTING c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.101962 98.07% [INFO] mod_json_cdr.c:271 Process [c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0.cdr.json] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [DEBUG] switch_core_state_machine.c:168 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Standard REPORTING, cause: NORMAL_CLEARING c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State REPORTING going to sleep c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State Change CS_REPORTING -> CS_DESTROY c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [DEBUG] switch_core_session.c:1753 Session 9495 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Locked, Waiting on external entities c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [NOTICE] switch_core_session.c:1771 Session 9495 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Ended c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 [CS_DESTROY] c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) Running State Change CS_DESTROY (Cur 0 Tot 9495) c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State DESTROY c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [DEBUG] mod_sofia.c:379 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 SOFIA DESTROY c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [DEBUG] switch_core_state_machine.c:175 sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 Standard DESTROY c0b2dc6f-37c3-4e9a-955e-fca9974b8fd0 2022-06-08 11:18:15.143108 98.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9009206195@XXX.XXX.XXX.XXX:5060) State DESTROY going to sleep

palak · June 8, 2022, 6:59am

Hello @gangasagar ,

You need to create origination rate from Tarrif → Origination rates in order to resolve the issue.
Thanks.

gangasagar · June 8, 2022, 12:29pm

Hi @palak Thanks, but after adding origination rates Now its showing [NORMAL_TEMPORARY_FAILURE] error

685dfe1a-92b9-4b2a-98ed-eaa7a9ee8784 2022-06-08 17:55:06.302022 97.67% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]

tinku.keot · June 8, 2022, 1:13pm

@gangasagar Can you please check if the termination is happen from your gateway side.Kindly check sip trace using sngrep tool.

gangasagar · June 10, 2022, 9:42am

IN ASTPP saying invalid_gateway, but in other appliation same is working
error comin Normal_ temporaryError

9452fd06-af4a-4e1d-b404-60ff97ae4d57 2022-06-10 15:07:24.061958 97.87% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [sofia] cause: [INVALID_GATEWAY]
9452fd06-af4a-4e1d-b404-60ff97ae4d57 2022-06-10 15:07:24.061958 97.87% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 608 [INVALID_GATEWAY]
9452fd06-af4a-4e1d-b404-60ff97ae4d57 2022-06-10 15:07:24.061958 97.87% [INFO] mod_dptools.c:3653 Originate Failed. Cause: INVALID_GATEWAY
9452fd06-af4a-4e1d-b404-60ff97ae4d57 EXECUTE [depth=0] sofia/default/9009206195@XXX.XXX.XXX.XXX:5060 bridge([leg_timeout=0,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/91XXXXXXXXXXX)

palak · June 13, 2022, 5:14am

Hello @gangasagar ,
Please share the whole logs in pastebin so we can check accordingly.
Thanks.

palak · June 13, 2022, 5:17am

Hello @gangasagar ,
Did you create the gateway with username & password or IP based. If its with username then you need to set the register as TRUE in order to make the calls workable .
Thanks.

gangasagar · June 16, 2022, 5:07am

Hello @palak , Now call landed on mobile phone but it disconnect immediately

palak · June 16, 2022, 5:09am

Hello @gangasagar ,
Please let me know the call flow, is it outbound call ?
Please check if your gateway is rejecting the call? Through sngrep you can check it and if possible share the sngrep pcap file.
Thanks.

devangn · June 17, 2022, 1:24pm

@gangasagar Probably codec mismatching