2024-01-23 10:48:15.025926 [NOTICE] switch_channel.c:1118 New Channel sofia/default/102@95.217.215.250 [13a74182-da94-4472-ad2a-175186dce73a] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:585 (sofia/default/102@95.217.215.250) Running State Change CS_NEW (Cur 1 Tot 3122007) 2024-01-23 10:48:15.025926 [DEBUG] sofia.c:10255 sofia/default/102@95.217.215.250 receiving invite from 199.167.144.226:5060 version: 1.10.2-release git f7bdd38 2019-12-31 14:01:19Z 64bit 2024-01-23 10:48:15.025926 [DEBUG] sofia.c:10349 verifying acl "default" for ip/port 199.167.144.226:0. 2024-01-23 10:48:15.025926 [DEBUG] sofia.c:10378 IP 199.167.144.226 Approved by acl "default[]". Access Granted. 2024-01-23 10:48:15.025926 [DEBUG] sofia.c:7301 Channel sofia/default/102@95.217.215.250 entering state [received][100] 2024-01-23 10:48:15.025926 [DEBUG] sofia.c:7311 Remote SDP: v=0 o=FreeSWITCH 1705980916 1705980917 IN IP4 95.217.215.250 s=FreeSWITCH c=IN IP4 95.217.215.250 t=0 0 m=audio 25978 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1] 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/102@95.217.215.250 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2024-01-23 10:48:15.025926 [DEBUG] switch_core_codec.c:111 sofia/default/102@95.217.215.250 Original read codec set to PCMA:8 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000 2024-01-23 10:48:15.025926 [DEBUG] switch_core_media.c:5909 sofia/default/102@95.217.215.250 Set 2833 dtmf send payload to 101 recv payload to 101 2024-01-23 10:48:15.025926 [DEBUG] sofia.c:7735 (sofia/default/102@95.217.215.250) State Change CS_NEW -> CS_INIT 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:604 (sofia/default/102@95.217.215.250) State NEW 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:585 (sofia/default/102@95.217.215.250) Running State Change CS_INIT (Cur 1 Tot 3122007) 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:628 (sofia/default/102@95.217.215.250) State INIT 2024-01-23 10:48:15.025926 [DEBUG] mod_sofia.c:93 sofia/default/102@95.217.215.250 SOFIA INIT 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:40 sofia/default/102@95.217.215.250 Standard INIT 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:48 (sofia/default/102@95.217.215.250) State Change CS_INIT -> CS_ROUTING 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:628 (sofia/default/102@95.217.215.250) State INIT going to sleep 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:585 (sofia/default/102@95.217.215.250) Running State Change CS_ROUTING (Cur 1 Tot 3122007) 2024-01-23 10:48:15.025926 [DEBUG] switch_channel.c:2332 (sofia/default/102@95.217.215.250) Callstate Change DOWN -> RINGING 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:644 (sofia/default/102@95.217.215.250) State ROUTING 2024-01-23 10:48:15.025926 [DEBUG] mod_sofia.c:154 sofia/default/102@95.217.215.250 SOFIA ROUTING 2024-01-23 10:48:15.025926 [DEBUG] switch_core_state_machine.c:236 sofia/default/102@95.217.215.250 Standard ROUTING 2024-01-23 10:48:15.025926 [INFO] mod_dialplan_xml.c:637 Processing 102 <102>->21347188890004 in context default 2024-01-23 10:48:15.045923 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes') 2024-01-23 10:48:15.045923 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-01-23 10:48:15.045923 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] start time : 2024-01-23 10:48:15 2024-01-23 10:48:15.045923 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 21347188890004 2024-01-23 10:48:15.045923 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] =======line2 : 2024-01-23 10:48:15.045923 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] =======line3 : 2024-01-23 10:48:15.045923 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number : 102 / 102 2024-01-23 10:48:15.045923 [DEBUG] switch_cpp.cpp:1443 [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 ="21347188890004" LIMIT 1 2024-01-23 10:48:15.045923 [DEBUG] switch_cpp.cpp:1443 [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="21347188890004" limit 1 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : outbound 2024-01-23 10:48:15.065934 [DEBUG] switch_cpp.cpp:1443 [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("95.217.215.250") 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 "21347188890004" LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1 2024-01-23 10:48:15.065934 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 21347188890004 2024-01-23 10:48:15.065934 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 7188890004 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 0143142808] 2024-01-23 10:48:15.065934 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "0143142808" AND deleted = 0 limit 1 2024-01-23 10:48:15.065934 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="7188890004" AND (accountid = 0 OR status = 1) LIMIT 1 2024-01-23 10:48:15.065934 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call performance report start 2024-01-23 10:48:15.065934 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call performance report end 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information =================== 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 3387 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 0143142808 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 36.856 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 0 [0:prepaid,1:postpaid] 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 11 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 0 2024-01-23 10:48:15.065934 [INFO] switch_cpp.cpp:1443 [ASTPP] ======================================================== 2024-01-23 10:48:15.065934 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 11 AND status = 0 2024-01-23 10:48:15.065934 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :outbound 2024-01-23 10:48:15.065934 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^7188890004.*' OR pattern = '^718889000.*' OR pattern = '^71888900.*' OR pattern = '^7188890.*' OR pattern = '^718889.*' OR pattern = '^71888.*' OR pattern = '^7188.*' OR pattern = '^718.*' OR pattern = '^71.*' OR pattern = '^7.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 11 OR accountid=3387) ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: outbound 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Rates Information =================== 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 2704945 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Includedseconds : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Cost : 0.16640 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] comment : RUSSIA 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Country Id : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Accid : 3387 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk ID: 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Routing type: 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ================================================================ 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Your36.856 balance Accountid 3387 !!! 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Call Max length duration : 221.49038461538 minutes 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Apply callerid 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] First char 7 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] [DIALPLAN] User Rate ID : 2704945 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id :0 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=11 ORDER by id asc 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.callerid_pool,TK.name as trunk_name,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^7188890004.*' OR pattern = '^718889000.*' OR pattern = '^71888900.*' OR pattern = '^7188890.*' OR pattern = '^718889.*' OR pattern = '^71888.*' OR pattern = '^7188.*' OR pattern = '^718.*' OR pattern = '^71.*' OR pattern = '^7.*' OR pattern ='--') AND TR.status = 0 AND TR.trunk_id IN (5,10,12,19,81,311) ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Termination Rates Information =================== 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 8787083 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Code : ^7.* 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Destination : Russia 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Free Seconds : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Prefix : ^7.* 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Strip : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Prepend : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Carrier id : 12 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] carrier_name : 328comInternational 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] dialplan_variable : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Failover gateway : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Vendor id : 72 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Max channels : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk Name : 328comInternational 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================END OF TERMINATION RATES======================= 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Termination Rates Information =================== 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 9304073 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Code : ^7.* 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Destination : Russia 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Free Seconds : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Prefix : ^7.* 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Strip : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Prepend : 99900 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Carrier id : 5 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] carrier_name : IDT 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] dialplan_variable : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Failover gateway : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Vendor id : 6 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Max channels : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk Name : IDT 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================END OF TERMINATION RATES======================= 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Termination Rates Information =================== 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 9456125 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Code : ^7.* 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Destination : Russia 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Free Seconds : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Prefix : ^7.* 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Strip : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Prepend : 99901 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Carrier id : 10 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] carrier_name : IDT 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] dialplan_variable : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Failover gateway : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Vendor id : 1797 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Max channels : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk Name : IDT 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================END OF TERMINATION RATES======================= 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Termination Rates Information =================== 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 8972663 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Code : ^7.* 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Destination : RUSSIA 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Free Seconds : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Prefix : ^7.* 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Strip : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Prepend : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Carrier id : 19 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] carrier_name : globalnet 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] dialplan_variable : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Failover gateway : 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Vendor id : 862 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Max channels : 0 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk Name : globalnet 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================END OF TERMINATION RATES======================= 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 3387 AND status=0 LIMIT 1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] maxlength::::::::: 221.49038461538 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 11 AND status = 0 2024-01-23 10:48:15.085928 [NOTICE] switch_cpp.cpp:1443 [ASTPP] outbound_1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Ankit_start111 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk ID 12Callerid Pool(1->Inactive,0->Active) : 1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Ankit_end 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 11 AND status = 0 2024-01-23 10:48:15.085928 [NOTICE] switch_cpp.cpp:1443 [ASTPP] outbound_1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Ankit_start111 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk ID 5Callerid Pool(1->Inactive,0->Active) : 1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Ankit_end 2024-01-23 10:48:15.085928 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 7188890004 2024-01-23 10:48:15.085928 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 999007188890004 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 11 AND status = 0 2024-01-23 10:48:15.085928 [NOTICE] switch_cpp.cpp:1443 [ASTPP] outbound_1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Ankit_start111 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk ID 10Callerid Pool(1->Inactive,0->Active) : 1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Ankit_end 2024-01-23 10:48:15.085928 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 7188890004 2024-01-23 10:48:15.085928 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 999017188890004 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 11 AND status = 0 2024-01-23 10:48:15.085928 [NOTICE] switch_cpp.cpp:1443 [ASTPP] outbound_1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Ankit_start111 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk ID 19Callerid Pool(1->Inactive,0->Active) : 1 2024-01-23 10:48:15.085928 [INFO] switch_cpp.cpp:1443 [ASTPP] Ankit_end 2024-01-23 10:48:15.085928 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Generated XML:
Dialplan: sofia/default/102@95.217.215.250 parsing [default->7188890004] continue=false Dialplan: sofia/default/102@95.217.215.250 Regex (PASS) [7188890004] destination_number(21347188890004) =~ /21347188890004/ break=on-false Dialplan: sofia/default/102@95.217.215.250 Action set(effective_destination_number=7188890004) Dialplan: sofia/default/102@95.217.215.250 Action set(bridge_pre_execute_bleg_app=sched_hangup) Dialplan: sofia/default/102@95.217.215.250 Action set(bridge_pre_execute_bleg_data=+13289.423076923 normal_clearing) Dialplan: sofia/default/102@95.217.215.250 Action set(callstart=2024-01-23 10:48:15) Dialplan: sofia/default/102@95.217.215.250 Action set(hangup_after_bridge=true) Dialplan: sofia/default/102@95.217.215.250 Action set(continue_on_fail=TRUE) Dialplan: sofia/default/102@95.217.215.250 Action set(account_id=3387) Dialplan: sofia/default/102@95.217.215.250 Action set(parent_id=0) Dialplan: sofia/default/102@95.217.215.250 Action set(entity_id=0) Dialplan: sofia/default/102@95.217.215.250 Action set(call_processed=internal) Dialplan: sofia/default/102@95.217.215.250 Action set(call_direction=outbound) Dialplan: sofia/default/102@95.217.215.250 Action set(accountname=default) Dialplan: sofia/default/102@95.217.215.250 Action set(origination_rates=ID:2704945|CODE:^7.*|DESTINATION:RUSSIA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16640|INC:6|INITIALBLOCK:6|RATEGROUP:11|MARKUP:0|CI:0|ACCID:3387) Dialplan: sofia/default/102@95.217.215.250 Action set(original_caller_id_name=102) Dialplan: sofia/default/102@95.217.215.250 Action set(original_caller_id_number=102) Dialplan: sofia/default/102@95.217.215.250 Action set(effective_caller_id_name=102) Dialplan: sofia/default/102@95.217.215.250 Action set(effective_caller_id_number=102) Dialplan: sofia/default/102@95.217.215.250 Action set(calltype=STANDARD) Dialplan: sofia/default/102@95.217.215.250 Action set(termination_rates=ID:8787083|CODE:^7.*|DESTINATION:Russia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.03222|INC:6|INITIALBLOCK:6|TRUNK:12|PROVIDER:72) Dialplan: sofia/default/102@95.217.215.250 Action set(trunk_id=12) Dialplan: sofia/default/102@95.217.215.250 Action set(provider_id=72) Dialplan: sofia/default/102@95.217.215.250 Action set(rate_flag=0) Dialplan: sofia/default/102@95.217.215.250 Action set(force_trunk_flag=2704945) Dialplan: sofia/default/102@95.217.215.250 Action export(presence_data=trunk_id=12) Dialplan: sofia/default/102@95.217.215.250 Action set(intcall=0) Dialplan: sofia/default/102@95.217.215.250 Action export(presence_data=x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||328comInternational // ^7.* // Russia // 0.03222 // trunk_id=12|||STD) Dialplan: sofia/default/102@95.217.215.250 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU:G729]sofia/gateway/328comInternational/7188890004) Dialplan: sofia/default/102@95.217.215.250 Action set(effective_caller_id_name=102) Dialplan: sofia/default/102@95.217.215.250 Action set(effective_caller_id_number=102) Dialplan: sofia/default/102@95.217.215.250 Action set(calltype=STANDARD) Dialplan: sofia/default/102@95.217.215.250 Action set(termination_rates=ID:9304073|CODE:^7.*|DESTINATION:Russia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.08840|INC:1|INITIALBLOCK:1|TRUNK:5|PROVIDER:6) Dialplan: sofia/default/102@95.217.215.250 Action set(trunk_id=5) Dialplan: sofia/default/102@95.217.215.250 Action set(provider_id=6) Dialplan: sofia/default/102@95.217.215.250 Action set(rate_flag=0) Dialplan: sofia/default/102@95.217.215.250 Action set(force_trunk_flag=2704945) Dialplan: sofia/default/102@95.217.215.250 Action export(presence_data=trunk_id=5) Dialplan: sofia/default/102@95.217.215.250 Action set(intcall=0) Dialplan: sofia/default/102@95.217.215.250 Action export(presence_data=x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=5|||STD) Dialplan: sofia/default/102@95.217.215.250 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMU:PCMA:G729]sofia/gateway/IDT/999007188890004) Dialplan: sofia/default/102@95.217.215.250 Action set(effective_caller_id_name=102) Dialplan: sofia/default/102@95.217.215.250 Action set(effective_caller_id_number=102) Dialplan: sofia/default/102@95.217.215.250 Action set(calltype=STANDARD) Dialplan: sofia/default/102@95.217.215.250 Action set(termination_rates=ID:9456125|CODE:^7.*|DESTINATION:Russia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.08840|INC:1|INITIALBLOCK:1|TRUNK:10|PROVIDER:1797) Dialplan: sofia/default/102@95.217.215.250 Action set(trunk_id=10) Dialplan: sofia/default/102@95.217.215.250 Action set(provider_id=1797) Dialplan: sofia/default/102@95.217.215.250 Action set(rate_flag=0) Dialplan: sofia/default/102@95.217.215.250 Action set(force_trunk_flag=2704945) Dialplan: sofia/default/102@95.217.215.250 Action export(presence_data=trunk_id=10) Dialplan: sofia/default/102@95.217.215.250 Action set(intcall=0) Dialplan: sofia/default/102@95.217.215.250 Action export(presence_data=x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=10|||STD) Dialplan: sofia/default/102@95.217.215.250 Action bridge([leg_timeout=0]sofia/gateway/IDT/999017188890004) Dialplan: sofia/default/102@95.217.215.250 Action set(effective_caller_id_name=102) Dialplan: sofia/default/102@95.217.215.250 Action set(effective_caller_id_number=102) Dialplan: sofia/default/102@95.217.215.250 Action set(calltype=STANDARD) Dialplan: sofia/default/102@95.217.215.250 Action set(termination_rates=ID:8972663|CODE:^7.*|DESTINATION:RUSSIA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10400|INC:1|INITIALBLOCK:1|TRUNK:19|PROVIDER:862) Dialplan: sofia/default/102@95.217.215.250 Action set(trunk_id=19) Dialplan: sofia/default/102@95.217.215.250 Action set(provider_id=862) Dialplan: sofia/default/102@95.217.215.250 Action set(rate_flag=0) Dialplan: sofia/default/102@95.217.215.250 Action set(force_trunk_flag=2704945) Dialplan: sofia/default/102@95.217.215.250 Action export(presence_data=trunk_id=19) Dialplan: sofia/default/102@95.217.215.250 Action set(intcall=0) Dialplan: sofia/default/102@95.217.215.250 Action export(presence_data=x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD) Dialplan: sofia/default/102@95.217.215.250 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMU:G729:PCMA]sofia/gateway/globalnet/7188890004) 2024-01-23 10:48:15.085928 [DEBUG] switch_core_state_machine.c:287 (sofia/default/102@95.217.215.250) State Change CS_ROUTING -> CS_EXECUTE 2024-01-23 10:48:15.085928 [DEBUG] switch_core_state_machine.c:644 (sofia/default/102@95.217.215.250) State ROUTING going to sleep 2024-01-23 10:48:15.085928 [DEBUG] switch_core_state_machine.c:585 (sofia/default/102@95.217.215.250) Running State Change CS_EXECUTE (Cur 1 Tot 3122007) 2024-01-23 10:48:15.085928 [DEBUG] switch_core_state_machine.c:651 (sofia/default/102@95.217.215.250) State EXECUTE 2024-01-23 10:48:15.085928 [DEBUG] mod_sofia.c:209 sofia/default/102@95.217.215.250 SOFIA EXECUTE 2024-01-23 10:48:15.085928 [DEBUG] switch_core_state_machine.c:329 sofia/default/102@95.217.215.250 Standard EXECUTE EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(effective_destination_number=7188890004) 2024-01-23 10:48:15.085928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [effective_destination_number]=[7188890004] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(bridge_pre_execute_bleg_app=sched_hangup) 2024-01-23 10:48:15.085928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [bridge_pre_execute_bleg_app]=[sched_hangup] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(bridge_pre_execute_bleg_data=+13289.423076923 normal_clearing) 2024-01-23 10:48:15.085928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [bridge_pre_execute_bleg_data]=[+13289.423076923 normal_clearing] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(callstart=2024-01-23 10:48:15) 2024-01-23 10:48:15.085928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [callstart]=[2024-01-23 10:48:15] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(hangup_after_bridge=true) 2024-01-23 10:48:15.085928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [hangup_after_bridge]=[true] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(continue_on_fail=TRUE) 2024-01-23 10:48:15.085928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [continue_on_fail]=[TRUE] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(account_id=3387) 2024-01-23 10:48:15.085928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [account_id]=[3387] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(parent_id=0) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [parent_id]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(entity_id=0) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [entity_id]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(call_processed=internal) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [call_processed]=[internal] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(call_direction=outbound) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [call_direction]=[outbound] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(accountname=default) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [accountname]=[default] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(origination_rates=ID:2704945|CODE:^7.*|DESTINATION:RUSSIA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16640|INC:6|INITIALBLOCK:6|RATEGROUP:11|MARKUP:0|CI:0|ACCID:3387) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [origination_rates]=[ID:2704945|CODE:^7.*|DESTINATION:RUSSIA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16640|INC:6|INITIALBLOCK:6|RATEGROUP:11|MARKUP:0|CI:0|ACCID:3387] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(original_caller_id_name=102) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [original_caller_id_name]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(original_caller_id_number=102) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [original_caller_id_number]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(effective_caller_id_name=102) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [effective_caller_id_name]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(effective_caller_id_number=102) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [effective_caller_id_number]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(calltype=STANDARD) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [calltype]=[STANDARD] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(termination_rates=ID:8787083|CODE:^7.*|DESTINATION:Russia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.03222|INC:6|INITIALBLOCK:6|TRUNK:12|PROVIDER:72) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [termination_rates]=[ID:8787083|CODE:^7.*|DESTINATION:Russia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.03222|INC:6|INITIALBLOCK:6|TRUNK:12|PROVIDER:72] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(trunk_id=12) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [trunk_id]=[12] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(provider_id=72) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [provider_id]=[72] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(rate_flag=0) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [rate_flag]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(force_trunk_flag=2704945) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [force_trunk_flag]=[2704945] EXECUTE [depth=0] sofia/default/102@95.217.215.250 export(presence_data=trunk_id=12) 2024-01-23 10:48:15.105923 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=12] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(intcall=0) 2024-01-23 10:48:15.105923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [intcall]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 export(presence_data=x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||328comInternational // ^7.* // Russia // 0.03222 // trunk_id=12|||STD) 2024-01-23 10:48:15.105923 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||328comInternational // ^7.* // Russia // 0.03222 // trunk_id=12|||STD] EXECUTE [depth=0] sofia/default/102@95.217.215.250 bridge([leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU:G729]sofia/gateway/328comInternational/7188890004) 2024-01-23 10:48:15.105923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||328comInternational // ^7.* // Russia // 0.03222 // trunk_id=12|||STD] to event 2024-01-23 10:48:15.105923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||328comInternational // ^7.* // Russia // 0.03222 // trunk_id=12|||STD] to event 2024-01-23 10:48:15.105923 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables 2024-01-23 10:48:15.105923 [DEBUG] switch_ivr_originate.c:2760 Parsing session specific variables 2024-01-23 10:48:15.105923 [NOTICE] switch_channel.c:1118 New Channel sofia/default/7188890004 [7e093230-5ef5-4f7d-b3b4-1a25c49c20e5] 2024-01-23 10:48:15.105923 [DEBUG] mod_sofia.c:5089 (sofia/default/7188890004) State Change CS_NEW -> CS_INIT 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_INIT (Cur 2 Tot 3122008) 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:628 (sofia/default/7188890004) State INIT 2024-01-23 10:48:15.105923 [DEBUG] mod_sofia.c:93 sofia/default/7188890004 SOFIA INIT 2024-01-23 10:48:15.105923 [DEBUG] sofia_glue.c:1587 sip:199.167.144.226 Setting proxy route to sofia/default/7188890004 2024-01-23 10:48:15.105923 [DEBUG] sofia_glue.c:1618 sofia/default/7188890004 sending invite version: 1.10.2-release git f7bdd38 2019-12-31 14:01:19Z 64bit Local SDP: v=0 o=FreeSWITCH 1705980453 1705980454 IN IP4 199.167.144.233 s=FreeSWITCH c=IN IP4 199.167.144.233 t=0 0 m=audio 26442 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:40 sofia/default/7188890004 Standard INIT 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:48 (sofia/default/7188890004) State Change CS_INIT -> CS_ROUTING 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:628 (sofia/default/7188890004) State INIT going to sleep 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_ROUTING (Cur 2 Tot 3122008) 2024-01-23 10:48:15.105923 [DEBUG] sofia.c:7301 Channel sofia/default/7188890004 entering state [calling][0] 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:644 (sofia/default/7188890004) State ROUTING 2024-01-23 10:48:15.105923 [DEBUG] mod_sofia.c:154 sofia/default/7188890004 SOFIA ROUTING 2024-01-23 10:48:15.105923 [DEBUG] switch_ivr_originate.c:67 (sofia/default/7188890004) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:644 (sofia/default/7188890004) State ROUTING going to sleep 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 3122008) 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:663 (sofia/default/7188890004) State CONSUME_MEDIA 2024-01-23 10:48:15.105923 [DEBUG] switch_core_state_machine.c:663 (sofia/default/7188890004) State CONSUME_MEDIA going to sleep 2024-01-23 10:48:15.265977 [DEBUG] sofia.c:6477 Remote Reason: 1 2024-01-23 10:48:15.265977 [DEBUG] sofia.c:7301 Channel sofia/default/7188890004 entering state [terminated][404] 2024-01-23 10:48:15.265977 [NOTICE] sofia.c:8534 Hangup sofia/default/7188890004 [CS_CONSUME_MEDIA] [UNALLOCATED_NUMBER] 2024-01-23 10:48:15.265977 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_HANGUP (Cur 2 Tot 3122008) 2024-01-23 10:48:15.265977 [DEBUG] switch_core_state_machine.c:848 (sofia/default/7188890004) Callstate Change DOWN -> HANGUP 2024-01-23 10:48:15.265977 [DEBUG] switch_core_state_machine.c:850 (sofia/default/7188890004) State HANGUP 2024-01-23 10:48:15.265977 [DEBUG] mod_sofia.c:453 Channel sofia/default/7188890004 hanging up, cause: UNALLOCATED_NUMBER 2024-01-23 10:48:15.265977 [DEBUG] switch_core_state_machine.c:60 sofia/default/7188890004 Standard HANGUP, cause: UNALLOCATED_NUMBER 2024-01-23 10:48:15.265977 [DEBUG] switch_core_state_machine.c:850 (sofia/default/7188890004) State HANGUP going to sleep 2024-01-23 10:48:15.265977 [DEBUG] switch_core_state_machine.c:620 (sofia/default/7188890004) State Change CS_HANGUP -> CS_REPORTING 2024-01-23 10:48:15.265977 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_REPORTING (Cur 2 Tot 3122008) 2024-01-23 10:48:15.265977 [DEBUG] switch_core_state_machine.c:936 (sofia/default/7188890004) State REPORTING 2024-01-23 10:48:15.265977 [INFO] mod_json_cdr.c:271 Process [7e093230-5ef5-4f7d-b3b4-1a25c49c20e5.cdr.json] 2024-01-23 10:48:15.285928 [DEBUG] switch_ivr_originate.c:3952 Originate Resulted in Error Cause: 1 [UNALLOCATED_NUMBER] 2024-01-23 10:48:15.285928 [INFO] mod_dptools.c:3631 Originate Failed. Cause: UNALLOCATED_NUMBER EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(effective_caller_id_name=102) 2024-01-23 10:48:15.285928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [effective_caller_id_name]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(effective_caller_id_number=102) 2024-01-23 10:48:15.285928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [effective_caller_id_number]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(calltype=STANDARD) 2024-01-23 10:48:15.285928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [calltype]=[STANDARD] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(termination_rates=ID:9304073|CODE:^7.*|DESTINATION:Russia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.08840|INC:1|INITIALBLOCK:1|TRUNK:5|PROVIDER:6) 2024-01-23 10:48:15.285928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [termination_rates]=[ID:9304073|CODE:^7.*|DESTINATION:Russia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.08840|INC:1|INITIALBLOCK:1|TRUNK:5|PROVIDER:6] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(trunk_id=5) 2024-01-23 10:48:15.285928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [trunk_id]=[5] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(provider_id=6) 2024-01-23 10:48:15.285928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [provider_id]=[6] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(rate_flag=0) 2024-01-23 10:48:15.285928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [rate_flag]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(force_trunk_flag=2704945) 2024-01-23 10:48:15.285928 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [force_trunk_flag]=[2704945] EXECUTE [depth=0] sofia/default/102@95.217.215.250 export(presence_data=trunk_id=5) 2024-01-23 10:48:15.285928 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=5] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(intcall=0) 2024-01-23 10:48:15.305924 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [intcall]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 export(presence_data=x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=5|||STD) 2024-01-23 10:48:15.305924 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=5|||STD] EXECUTE [depth=0] sofia/default/102@95.217.215.250 bridge([leg_timeout=0,absolute_codec_string=^^:PCMU:PCMA:G729]sofia/gateway/IDT/999007188890004) 2024-01-23 10:48:15.305924 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=5|||STD] to event 2024-01-23 10:48:15.305924 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=5|||STD] to event 2024-01-23 10:48:15.305924 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=5|||STD] to event 2024-01-23 10:48:15.305924 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=5|||STD] to event 2024-01-23 10:48:15.305924 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables 2024-01-23 10:48:15.305924 [DEBUG] switch_ivr_originate.c:2760 Parsing session specific variables 2024-01-23 10:48:15.305924 [NOTICE] switch_channel.c:1118 New Channel sofia/default/999007188890004 [219b482a-c71a-43c7-ae45-e6098f74f422] 2024-01-23 10:48:15.305924 [DEBUG] mod_sofia.c:5089 (sofia/default/999007188890004) State Change CS_NEW -> CS_INIT 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999007188890004) Running State Change CS_INIT (Cur 3 Tot 3122009) 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:628 (sofia/default/999007188890004) State INIT 2024-01-23 10:48:15.305924 [DEBUG] mod_sofia.c:93 sofia/default/999007188890004 SOFIA INIT 2024-01-23 10:48:15.305924 [DEBUG] sofia_glue.c:1587 sip:199.167.144.226 Setting proxy route to sofia/default/999007188890004 2024-01-23 10:48:15.305924 [DEBUG] sofia_glue.c:1618 sofia/default/999007188890004 sending invite version: 1.10.2-release git f7bdd38 2019-12-31 14:01:19Z 64bit Local SDP: v=0 o=FreeSWITCH 1705992203 1705992204 IN IP4 199.167.144.233 s=FreeSWITCH c=IN IP4 199.167.144.233 t=0 0 m=audio 14692 RTP/AVP 0 8 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:40 sofia/default/999007188890004 Standard INIT 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:48 (sofia/default/999007188890004) State Change CS_INIT -> CS_ROUTING 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:628 (sofia/default/999007188890004) State INIT going to sleep 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999007188890004) Running State Change CS_ROUTING (Cur 3 Tot 3122009) 2024-01-23 10:48:15.305924 [DEBUG] sofia.c:7301 Channel sofia/default/999007188890004 entering state [calling][0] 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:644 (sofia/default/999007188890004) State ROUTING 2024-01-23 10:48:15.305924 [DEBUG] mod_sofia.c:154 sofia/default/999007188890004 SOFIA ROUTING 2024-01-23 10:48:15.305924 [DEBUG] switch_ivr_originate.c:67 (sofia/default/999007188890004) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:644 (sofia/default/999007188890004) State ROUTING going to sleep 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999007188890004) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 3122009) 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:663 (sofia/default/999007188890004) State CONSUME_MEDIA 2024-01-23 10:48:15.305924 [DEBUG] switch_core_state_machine.c:663 (sofia/default/999007188890004) State CONSUME_MEDIA going to sleep 2024-01-23 10:48:15.405952 [DEBUG] switch_core_state_machine.c:174 sofia/default/7188890004 Standard REPORTING, cause: UNALLOCATED_NUMBER 2024-01-23 10:48:15.405952 [DEBUG] switch_core_state_machine.c:936 (sofia/default/7188890004) State REPORTING going to sleep 2024-01-23 10:48:15.405952 [DEBUG] switch_core_state_machine.c:611 (sofia/default/7188890004) State Change CS_REPORTING -> CS_DESTROY 2024-01-23 10:48:15.405952 [DEBUG] switch_core_session.c:1726 Session 3122008 (sofia/default/7188890004) Locked, Waiting on external entities 2024-01-23 10:48:15.405952 [NOTICE] switch_core_session.c:1744 Session 3122008 (sofia/default/7188890004) Ended 2024-01-23 10:48:15.405952 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/7188890004 [CS_DESTROY] 2024-01-23 10:48:15.405952 [DEBUG] switch_core_state_machine.c:739 (sofia/default/7188890004) Running State Change CS_DESTROY (Cur 2 Tot 3122009) 2024-01-23 10:48:15.405952 [DEBUG] switch_core_state_machine.c:749 (sofia/default/7188890004) State DESTROY 2024-01-23 10:48:15.405952 [DEBUG] mod_sofia.c:364 sofia/default/7188890004 SOFIA DESTROY 2024-01-23 10:48:15.405952 [DEBUG] switch_core_state_machine.c:181 sofia/default/7188890004 Standard DESTROY 2024-01-23 10:48:15.405952 [DEBUG] switch_core_state_machine.c:749 (sofia/default/7188890004) State DESTROY going to sleep 2024-01-23 10:48:15.445932 [DEBUG] sofia.c:6477 Remote Reason: 34 2024-01-23 10:48:15.445932 [DEBUG] sofia.c:7301 Channel sofia/default/999007188890004 entering state [terminated][500] 2024-01-23 10:48:15.445932 [NOTICE] sofia.c:8534 Hangup sofia/default/999007188890004 [CS_CONSUME_MEDIA] [NORMAL_CIRCUIT_CONGESTION] 2024-01-23 10:48:15.445932 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999007188890004) Running State Change CS_HANGUP (Cur 2 Tot 3122009) 2024-01-23 10:48:15.445932 [DEBUG] switch_core_state_machine.c:848 (sofia/default/999007188890004) Callstate Change DOWN -> HANGUP 2024-01-23 10:48:15.445932 [DEBUG] switch_core_state_machine.c:850 (sofia/default/999007188890004) State HANGUP 2024-01-23 10:48:15.445932 [DEBUG] mod_sofia.c:453 Channel sofia/default/999007188890004 hanging up, cause: NORMAL_CIRCUIT_CONGESTION 2024-01-23 10:48:15.445932 [DEBUG] switch_core_state_machine.c:60 sofia/default/999007188890004 Standard HANGUP, cause: NORMAL_CIRCUIT_CONGESTION 2024-01-23 10:48:15.445932 [DEBUG] switch_core_state_machine.c:850 (sofia/default/999007188890004) State HANGUP going to sleep 2024-01-23 10:48:15.445932 [DEBUG] switch_core_state_machine.c:620 (sofia/default/999007188890004) State Change CS_HANGUP -> CS_REPORTING 2024-01-23 10:48:15.445932 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999007188890004) Running State Change CS_REPORTING (Cur 2 Tot 3122009) 2024-01-23 10:48:15.445932 [DEBUG] switch_core_state_machine.c:936 (sofia/default/999007188890004) State REPORTING 2024-01-23 10:48:15.445932 [INFO] mod_json_cdr.c:271 Process [219b482a-c71a-43c7-ae45-e6098f74f422.cdr.json] 2024-01-23 10:48:15.465925 [DEBUG] switch_ivr_originate.c:3952 Originate Resulted in Error Cause: 34 [NORMAL_CIRCUIT_CONGESTION] 2024-01-23 10:48:15.465925 [INFO] mod_dptools.c:3631 Originate Failed. Cause: NORMAL_CIRCUIT_CONGESTION EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(effective_caller_id_name=102) 2024-01-23 10:48:15.465925 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [effective_caller_id_name]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(effective_caller_id_number=102) 2024-01-23 10:48:15.465925 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [effective_caller_id_number]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(calltype=STANDARD) 2024-01-23 10:48:15.465925 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [calltype]=[STANDARD] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(termination_rates=ID:9456125|CODE:^7.*|DESTINATION:Russia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.08840|INC:1|INITIALBLOCK:1|TRUNK:10|PROVIDER:1797) 2024-01-23 10:48:15.465925 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [termination_rates]=[ID:9456125|CODE:^7.*|DESTINATION:Russia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.08840|INC:1|INITIALBLOCK:1|TRUNK:10|PROVIDER:1797] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(trunk_id=10) 2024-01-23 10:48:15.465925 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [trunk_id]=[10] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(provider_id=1797) 2024-01-23 10:48:15.465925 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [provider_id]=[1797] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(rate_flag=0) 2024-01-23 10:48:15.465925 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [rate_flag]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(force_trunk_flag=2704945) 2024-01-23 10:48:15.465925 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [force_trunk_flag]=[2704945] EXECUTE [depth=0] sofia/default/102@95.217.215.250 export(presence_data=trunk_id=10) 2024-01-23 10:48:15.465925 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=10] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(intcall=0) 2024-01-23 10:48:15.465925 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [intcall]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 export(presence_data=x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=10|||STD) 2024-01-23 10:48:15.465925 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=10|||STD] EXECUTE [depth=0] sofia/default/102@95.217.215.250 bridge([leg_timeout=0]sofia/gateway/IDT/999017188890004) 2024-01-23 10:48:15.465925 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=10|||STD] to event 2024-01-23 10:48:15.465925 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=10|||STD] to event 2024-01-23 10:48:15.465925 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=10|||STD] to event 2024-01-23 10:48:15.465925 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=10|||STD] to event 2024-01-23 10:48:15.465925 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=10|||STD] to event 2024-01-23 10:48:15.465925 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||IDT // ^7.* // Russia // 0.08840 // trunk_id=10|||STD] to event 2024-01-23 10:48:15.465925 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables 2024-01-23 10:48:15.465925 [DEBUG] switch_ivr_originate.c:2760 Parsing session specific variables 2024-01-23 10:48:15.465925 [NOTICE] switch_channel.c:1118 New Channel sofia/default/999017188890004 [124604d5-2982-411f-83af-70bbea404877] 2024-01-23 10:48:15.465925 [DEBUG] mod_sofia.c:5089 (sofia/default/999017188890004) State Change CS_NEW -> CS_INIT 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999017188890004) Running State Change CS_INIT (Cur 3 Tot 3122010) 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:628 (sofia/default/999017188890004) State INIT 2024-01-23 10:48:15.465925 [DEBUG] mod_sofia.c:93 sofia/default/999017188890004 SOFIA INIT 2024-01-23 10:48:15.465925 [DEBUG] sofia_glue.c:1587 sip:199.167.144.226 Setting proxy route to sofia/default/999017188890004 2024-01-23 10:48:15.465925 [DEBUG] sofia_glue.c:1618 sofia/default/999017188890004 sending invite version: 1.10.2-release git f7bdd38 2019-12-31 14:01:19Z 64bit Local SDP: v=0 o=FreeSWITCH 1705982347 1705982348 IN IP4 199.167.144.233 s=FreeSWITCH c=IN IP4 199.167.144.233 t=0 0 m=audio 24548 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:40 sofia/default/999017188890004 Standard INIT 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:48 (sofia/default/999017188890004) State Change CS_INIT -> CS_ROUTING 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:628 (sofia/default/999017188890004) State INIT going to sleep 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999017188890004) Running State Change CS_ROUTING (Cur 3 Tot 3122010) 2024-01-23 10:48:15.465925 [DEBUG] sofia.c:7301 Channel sofia/default/999017188890004 entering state [calling][0] 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:644 (sofia/default/999017188890004) State ROUTING 2024-01-23 10:48:15.465925 [DEBUG] mod_sofia.c:154 sofia/default/999017188890004 SOFIA ROUTING 2024-01-23 10:48:15.465925 [DEBUG] switch_ivr_originate.c:67 (sofia/default/999017188890004) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:644 (sofia/default/999017188890004) State ROUTING going to sleep 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999017188890004) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 3122010) 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:663 (sofia/default/999017188890004) State CONSUME_MEDIA 2024-01-23 10:48:15.465925 [DEBUG] switch_core_state_machine.c:663 (sofia/default/999017188890004) State CONSUME_MEDIA going to sleep 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:174 sofia/default/999007188890004 Standard REPORTING, cause: NORMAL_CIRCUIT_CONGESTION 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:936 (sofia/default/999007188890004) State REPORTING going to sleep 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:611 (sofia/default/999007188890004) State Change CS_REPORTING -> CS_DESTROY 2024-01-23 10:48:15.585880 [DEBUG] switch_core_session.c:1726 Session 3122009 (sofia/default/999007188890004) Locked, Waiting on external entities 2024-01-23 10:48:15.585880 [NOTICE] switch_core_session.c:1744 Session 3122009 (sofia/default/999007188890004) Ended 2024-01-23 10:48:15.585880 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/999007188890004 [CS_DESTROY] 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:739 (sofia/default/999007188890004) Running State Change CS_DESTROY (Cur 2 Tot 3122010) 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:749 (sofia/default/999007188890004) State DESTROY 2024-01-23 10:48:15.585880 [DEBUG] mod_sofia.c:364 sofia/default/999007188890004 SOFIA DESTROY 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:181 sofia/default/999007188890004 Standard DESTROY 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:749 (sofia/default/999007188890004) State DESTROY going to sleep 2024-01-23 10:48:15.585880 [DEBUG] sofia.c:6477 Remote Reason: 34 2024-01-23 10:48:15.585880 [DEBUG] sofia.c:7301 Channel sofia/default/999017188890004 entering state [terminated][500] 2024-01-23 10:48:15.585880 [NOTICE] sofia.c:8534 Hangup sofia/default/999017188890004 [CS_CONSUME_MEDIA] [NORMAL_CIRCUIT_CONGESTION] 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999017188890004) Running State Change CS_HANGUP (Cur 2 Tot 3122010) 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:848 (sofia/default/999017188890004) Callstate Change DOWN -> HANGUP 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:850 (sofia/default/999017188890004) State HANGUP 2024-01-23 10:48:15.585880 [DEBUG] mod_sofia.c:453 Channel sofia/default/999017188890004 hanging up, cause: NORMAL_CIRCUIT_CONGESTION 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:60 sofia/default/999017188890004 Standard HANGUP, cause: NORMAL_CIRCUIT_CONGESTION 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:850 (sofia/default/999017188890004) State HANGUP going to sleep 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:620 (sofia/default/999017188890004) State Change CS_HANGUP -> CS_REPORTING 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:585 (sofia/default/999017188890004) Running State Change CS_REPORTING (Cur 2 Tot 3122010) 2024-01-23 10:48:15.585880 [DEBUG] switch_core_state_machine.c:936 (sofia/default/999017188890004) State REPORTING 2024-01-23 10:48:15.585880 [INFO] mod_json_cdr.c:271 Process [124604d5-2982-411f-83af-70bbea404877.cdr.json] 2024-01-23 10:48:15.585880 [DEBUG] switch_ivr_originate.c:3952 Originate Resulted in Error Cause: 34 [NORMAL_CIRCUIT_CONGESTION] 2024-01-23 10:48:15.585880 [INFO] mod_dptools.c:3631 Originate Failed. Cause: NORMAL_CIRCUIT_CONGESTION EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(effective_caller_id_name=102) 2024-01-23 10:48:15.585880 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [effective_caller_id_name]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(effective_caller_id_number=102) 2024-01-23 10:48:15.585880 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [effective_caller_id_number]=[102] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(calltype=STANDARD) 2024-01-23 10:48:15.605923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [calltype]=[STANDARD] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(termination_rates=ID:8972663|CODE:^7.*|DESTINATION:RUSSIA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10400|INC:1|INITIALBLOCK:1|TRUNK:19|PROVIDER:862) 2024-01-23 10:48:15.605923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [termination_rates]=[ID:8972663|CODE:^7.*|DESTINATION:RUSSIA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10400|INC:1|INITIALBLOCK:1|TRUNK:19|PROVIDER:862] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(trunk_id=19) 2024-01-23 10:48:15.605923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [trunk_id]=[19] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(provider_id=862) 2024-01-23 10:48:15.605923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [provider_id]=[862] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(rate_flag=0) 2024-01-23 10:48:15.605923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [rate_flag]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(force_trunk_flag=2704945) 2024-01-23 10:48:15.605923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [force_trunk_flag]=[2704945] EXECUTE [depth=0] sofia/default/102@95.217.215.250 export(presence_data=trunk_id=19) 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=19] EXECUTE [depth=0] sofia/default/102@95.217.215.250 set(intcall=0) 2024-01-23 10:48:15.605923 [DEBUG] mod_dptools.c:1672 SET sofia/default/102@95.217.215.250 [intcall]=[0] EXECUTE [depth=0] sofia/default/102@95.217.215.250 export(presence_data=x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD) 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD] EXECUTE [depth=0] sofia/default/102@95.217.215.250 bridge([leg_timeout=0,absolute_codec_string=^^:PCMU:G729:PCMA]sofia/gateway/globalnet/7188890004) 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD] to event 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD] to event 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD] to event 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD] to event 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD] to event 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD] to event 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD] to event 2024-01-23 10:48:15.605923 [DEBUG] switch_channel.c:1264 sofia/default/102@95.217.215.250 EXPORTING[export_vars] [presence_data]=[x|||isaac(0143142808)|||^7.* // RUSSIA // 0.16640 |||globalnet // ^7.* // RUSSIA // 0.10400 // trunk_id=19|||STD] to event 2024-01-23 10:48:15.605923 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables 2024-01-23 10:48:15.605923 [DEBUG] switch_ivr_originate.c:2760 Parsing session specific variables 2024-01-23 10:48:15.605923 [NOTICE] switch_channel.c:1118 New Channel sofia/default/7188890004 [6b0710e0-c0e7-4269-8941-04326c6e76b3] 2024-01-23 10:48:15.605923 [DEBUG] mod_sofia.c:5089 (sofia/default/7188890004) State Change CS_NEW -> CS_INIT 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_INIT (Cur 3 Tot 3122011) 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:628 (sofia/default/7188890004) State INIT 2024-01-23 10:48:15.605923 [DEBUG] mod_sofia.c:93 sofia/default/7188890004 SOFIA INIT 2024-01-23 10:48:15.605923 [DEBUG] sofia_glue.c:1587 sip:199.167.144.226 Setting proxy route to sofia/default/7188890004 2024-01-23 10:48:15.605923 [DEBUG] sofia_glue.c:1618 sofia/default/7188890004 sending invite version: 1.10.2-release git f7bdd38 2019-12-31 14:01:19Z 64bit Local SDP: v=0 o=FreeSWITCH 1705985713 1705985714 IN IP4 199.167.144.233 s=FreeSWITCH c=IN IP4 199.167.144.233 t=0 0 m=audio 21182 RTP/AVP 0 18 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:40 sofia/default/7188890004 Standard INIT 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:48 (sofia/default/7188890004) State Change CS_INIT -> CS_ROUTING 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:628 (sofia/default/7188890004) State INIT going to sleep 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_ROUTING (Cur 3 Tot 3122011) 2024-01-23 10:48:15.605923 [DEBUG] sofia.c:7301 Channel sofia/default/7188890004 entering state [calling][0] 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:644 (sofia/default/7188890004) State ROUTING 2024-01-23 10:48:15.605923 [DEBUG] mod_sofia.c:154 sofia/default/7188890004 SOFIA ROUTING 2024-01-23 10:48:15.605923 [DEBUG] switch_ivr_originate.c:67 (sofia/default/7188890004) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:644 (sofia/default/7188890004) State ROUTING going to sleep 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 3122011) 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:663 (sofia/default/7188890004) State CONSUME_MEDIA 2024-01-23 10:48:15.605923 [DEBUG] switch_core_state_machine.c:663 (sofia/default/7188890004) State CONSUME_MEDIA going to sleep 2024-01-23 10:48:15.645933 [DEBUG] switch_core_state_machine.c:174 sofia/default/999017188890004 Standard REPORTING, cause: NORMAL_CIRCUIT_CONGESTION 2024-01-23 10:48:15.645933 [DEBUG] switch_core_state_machine.c:936 (sofia/default/999017188890004) State REPORTING going to sleep 2024-01-23 10:48:15.665925 [DEBUG] switch_core_state_machine.c:611 (sofia/default/999017188890004) State Change CS_REPORTING -> CS_DESTROY 2024-01-23 10:48:15.665925 [DEBUG] switch_core_session.c:1726 Session 3122010 (sofia/default/999017188890004) Locked, Waiting on external entities 2024-01-23 10:48:15.665925 [NOTICE] switch_core_session.c:1744 Session 3122010 (sofia/default/999017188890004) Ended 2024-01-23 10:48:15.665925 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/999017188890004 [CS_DESTROY] 2024-01-23 10:48:15.665925 [DEBUG] switch_core_state_machine.c:739 (sofia/default/999017188890004) Running State Change CS_DESTROY (Cur 2 Tot 3122011) 2024-01-23 10:48:15.665925 [DEBUG] switch_core_state_machine.c:749 (sofia/default/999017188890004) State DESTROY 2024-01-23 10:48:15.665925 [DEBUG] mod_sofia.c:364 sofia/default/999017188890004 SOFIA DESTROY 2024-01-23 10:48:15.665925 [DEBUG] switch_core_state_machine.c:181 sofia/default/999017188890004 Standard DESTROY 2024-01-23 10:48:15.665925 [DEBUG] switch_core_state_machine.c:749 (sofia/default/999017188890004) State DESTROY going to sleep 2024-01-23 10:48:15.725922 [DEBUG] sofia.c:6477 Remote Reason: 1 2024-01-23 10:48:15.725922 [DEBUG] sofia.c:7301 Channel sofia/default/7188890004 entering state [terminated][404] 2024-01-23 10:48:15.725922 [NOTICE] sofia.c:8534 Hangup sofia/default/7188890004 [CS_CONSUME_MEDIA] [UNALLOCATED_NUMBER] 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_HANGUP (Cur 2 Tot 3122011) 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:848 (sofia/default/7188890004) Callstate Change DOWN -> HANGUP 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:850 (sofia/default/7188890004) State HANGUP 2024-01-23 10:48:15.725922 [DEBUG] mod_sofia.c:453 Channel sofia/default/7188890004 hanging up, cause: UNALLOCATED_NUMBER 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:60 sofia/default/7188890004 Standard HANGUP, cause: UNALLOCATED_NUMBER 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:850 (sofia/default/7188890004) State HANGUP going to sleep 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:620 (sofia/default/7188890004) State Change CS_HANGUP -> CS_REPORTING 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7188890004) Running State Change CS_REPORTING (Cur 2 Tot 3122011) 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:936 (sofia/default/7188890004) State REPORTING 2024-01-23 10:48:15.725922 [INFO] mod_json_cdr.c:271 Process [6b0710e0-c0e7-4269-8941-04326c6e76b3.cdr.json] 2024-01-23 10:48:15.725922 [DEBUG] switch_ivr_originate.c:3952 Originate Resulted in Error Cause: 1 [UNALLOCATED_NUMBER] 2024-01-23 10:48:15.725922 [INFO] mod_dptools.c:3631 Originate Failed. Cause: UNALLOCATED_NUMBER 2024-01-23 10:48:15.725922 [NOTICE] switch_core_state_machine.c:386 sofia/default/102@95.217.215.250 has executed the last dialplan instruction, hanging up. 2024-01-23 10:48:15.725922 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/102@95.217.215.250 [CS_EXECUTE] [NORMAL_CLEARING] 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:651 (sofia/default/102@95.217.215.250) State EXECUTE going to sleep 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:585 (sofia/default/102@95.217.215.250) Running State Change CS_HANGUP (Cur 2 Tot 3122011) 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:848 (sofia/default/102@95.217.215.250) Callstate Change RINGING -> HANGUP 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:850 (sofia/default/102@95.217.215.250) State HANGUP 2024-01-23 10:48:15.725922 [DEBUG] mod_sofia.c:447 sofia/default/102@95.217.215.250 Overriding SIP cause 480 with 404 from the other leg 2024-01-23 10:48:15.725922 [DEBUG] mod_sofia.c:453 Channel sofia/default/102@95.217.215.250 hanging up, cause: NORMAL_CLEARING 2024-01-23 10:48:15.725922 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 404 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:60 sofia/default/102@95.217.215.250 Standard HANGUP, cause: NORMAL_CLEARING 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:850 (sofia/default/102@95.217.215.250) State HANGUP going to sleep 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:620 (sofia/default/102@95.217.215.250) State Change CS_HANGUP -> CS_REPORTING 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:585 (sofia/default/102@95.217.215.250) Running State Change CS_REPORTING (Cur 2 Tot 3122011) 2024-01-23 10:48:15.725922 [DEBUG] switch_core_state_machine.c:936 (sofia/default/102@95.217.215.250) State REPORTING 2024-01-23 10:48:15.725922 [INFO] mod_json_cdr.c:271 Process [13a74182-da94-4472-ad2a-175186dce73a.cdr.json] 2024-01-23 10:48:15.805926 [DEBUG] switch_core_state_machine.c:174 sofia/default/102@95.217.215.250 Standard REPORTING, cause: NORMAL_CLEARING 2024-01-23 10:48:15.805926 [DEBUG] switch_core_state_machine.c:936 (sofia/default/102@95.217.215.250) State REPORTING going to sleep 2024-01-23 10:48:15.805926 [DEBUG] switch_core_state_machine.c:611 (sofia/default/102@95.217.215.250) State Change CS_REPORTING -> CS_DESTROY 2024-01-23 10:48:15.805926 [DEBUG] switch_core_session.c:1726 Session 3122007 (sofia/default/102@95.217.215.250) Locked, Waiting on external entities 2024-01-23 10:48:15.805926 [NOTICE] switch_core_session.c:1744 Session 3122007 (sofia/default/102@95.217.215.250) Ended 2024-01-23 10:48:15.805926 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/102@95.217.215.250 [CS_DESTROY] 2024-01-23 10:48:15.805926 [DEBUG] switch_core_state_machine.c:739 (sofia/default/102@95.217.215.250) Running State Change CS_DESTROY (Cur 1 Tot 3122011) 2024-01-23 10:48:15.805926 [DEBUG] switch_core_state_machine.c:749 (sofia/default/102@95.217.215.250) State DESTROY 2024-01-23 10:48:15.805926 [DEBUG] mod_sofia.c:364 sofia/default/102@95.217.215.250 SOFIA DESTROY 2024-01-23 10:48:15.805926 [DEBUG] switch_core_state_machine.c:181 sofia/default/102@95.217.215.250 Standard DESTROY 2024-01-23 10:48:15.805926 [DEBUG] switch_core_state_machine.c:749 (sofia/default/102@95.217.215.250) State DESTROY going to sleep 2024-01-23 10:48:15.845967 [DEBUG] switch_core_state_machine.c:174 sofia/default/7188890004 Standard REPORTING, cause: UNALLOCATED_NUMBER 2024-01-23 10:48:15.845967 [DEBUG] switch_core_state_machine.c:936 (sofia/default/7188890004) State REPORTING going to sleep 2024-01-23 10:48:15.865924 [DEBUG] switch_core_state_machine.c:611 (sofia/default/7188890004) State Change CS_REPORTING -> CS_DESTROY 2024-01-23 10:48:15.865924 [DEBUG] switch_core_session.c:1726 Session 3122011 (sofia/default/7188890004) Locked, Waiting on external entities 2024-01-23 10:48:15.865924 [NOTICE] switch_core_session.c:1744 Session 3122011 (sofia/default/7188890004) Ended 2024-01-23 10:48:15.865924 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/7188890004 [CS_DESTROY] 2024-01-23 10:48:15.865924 [DEBUG] switch_core_state_machine.c:739 (sofia/default/7188890004) Running State Change CS_DESTROY (Cur 0 Tot 3122011) 2024-01-23 10:48:15.865924 [DEBUG] switch_core_state_machine.c:749 (sofia/default/7188890004) State DESTROY 2024-01-23 10:48:15.865924 [DEBUG] mod_sofia.c:364 sofia/default/7188890004 SOFIA DESTROY 2024-01-23 10:48:15.865924 [DEBUG] switch_core_state_machine.c:181 sofia/default/7188890004 Standard DESTROY 2024-01-23 10:48:15.865924 [DEBUG] switch_core_state_machine.c:749 (sofia/default/7188890004) State DESTROY going to sleep