GROUP:2|MARKUP:0|CI:184|ACCID:6) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [origination_rates]=[ID:12|CODE:^46.*|DESTINATION:Sverige|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0000000000|INC:1|INITIALBLOCK:1|RATEGROUP:2|MARKUP:0|CI:184|ACCID:6] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(original_caller_id_name=0708516331) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [original_caller_id_name]=[0708516331] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(original_caller_id_number=0708516331) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [original_caller_id_number]=[0708516331] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(effective_caller_id_name=0708516331) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [effective_caller_id_name]=[0708516331] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(effective_caller_id_number=0708516331) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [effective_caller_id_number]=[0708516331] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(calltype=STANDARD) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [calltype]=[STANDARD] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(termination_rates=ID:4|CODE:^46.*|DESTINATION:Sverige|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0000000000|INC:0|INITIALBLOCK:0|TRUNK:4|PROVIDER:7) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [termination_rates]=[ID:4|CODE:^46.*|DESTINATION:Sverige|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0000000000|INC:0|INITIALBLOCK:0|TRUNK:4|PROVIDER:7] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(trunk_id=4) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [trunk_id]=[4] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(provider_id=7) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [provider_id]=[7] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(rate_flag=0) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [rate_flag]=[0] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(daily_call_credit_limit=0.00000) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [daily_call_credit_limit]=[0.00000] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(monthly_call_credit_limit=0.00000) 2024-12-09 17:02:39.902490 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [monthly_call_credit_limit]=[0.00000] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(daily_call_used_limit=0.00000) 2024-12-09 17:02:39.922482 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [daily_call_used_limit]=[0.00000] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(monthly_call_used_limit=0.00000) 2024-12-09 17:02:39.922482 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [monthly_call_used_limit]=[0.00000] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(quality_trunk_seq=4) 2024-12-09 17:02:39.922482 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [quality_trunk_seq]=[4] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(force_trunk_flag=12) 2024-12-09 17:02:39.922482 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [force_trunk_flag]=[12] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 export(presence_data=trunk_id=4) 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[trunk_id=4] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 set(intcall=0) 2024-12-09 17:02:39.922482 80.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46708516331@62.80.216.236 [intcall]=[0] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 export(presence_id=46455655400) 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_id]=[46455655400] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 export(presence_data=x|||GOTANET(5504929424)|||^46.* // Sverige // 0.0000000000|||miniel_incoming // ^46.* // Sverige // 0.0000000000 // trunk_id=4|||STD|||62.80.216.236) 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[x|||GOTANET(5504929424)|||^46.* // Sverige // 0.0000000000|||miniel_incoming // ^46.* // Sverige // 0.0000000000 // trunk_id=4|||STD|||62.80.216.236] EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 bridge({sip_cid_type=none}[leg_timeout=0]sofia/gateway/minitel_inc/46455655400) 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_channel.c:1288 sofia/default/+46708516331@62.80.216.236 EXPORTING[export_vars] [presence_data]=[x|||GOTANET(5504929424)|||^46.* // Sverige // 0.0000000000|||miniel_incoming // ^46.* // Sverige // 0.0000000000 // trunk_id=4|||STD|||62.80.216.236] to event 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_channel.c:1288 sofia/default/+46708516331@62.80.216.236 EXPORTING[export_vars] [presence_id]=[46455655400] to event 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_channel.c:1288 sofia/default/+46708516331@62.80.216.236 EXPORTING[export_vars] [presence_data]=[x|||GOTANET(5504929424)|||^46.* // Sverige // 0.0000000000|||miniel_incoming // ^46.* // Sverige // 0.0000000000 // trunk_id=4|||STD|||62.80.216.236] to event 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_ivr_originate.c:2859 Parsing session specific variables 2024-12-09 17:02:39.922482 80.67% [NOTICE] switch_channel.c:1142 New Channel sofia/default/46455655400 [6883d05e-f80b-4ecc-a05f-40e38f7b25f7] 2024-12-09 17:02:39.922482 80.67% [DEBUG] mod_sofia.c:5110 (sofia/default/46455655400) State Change CS_NEW -> CS_INIT 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/46455655400) Running State Change CS_INIT (Cur 142 Tot 134742) 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/46455655400) State INIT 2024-12-09 17:02:39.922482 80.67% [DEBUG] mod_sofia.c:97 sofia/default/46455655400 SOFIA INIT 2024-12-09 17:02:39.922482 80.67% [INFO] sofia_glue.c:1659 sofia/default/46455655400 sending invite call-id: (null) 2024-12-09 17:02:39.922482 80.67% [DEBUG] sofia_glue.c:1662 sofia/default/46455655400 sending invite version: 1.10.10-release 64bit Local SDP: v=0 o=FreeSWITCH 1733739349 1733739350 IN IP4 172.232.155.199 s=FreeSWITCH c=IN IP4 172.232.155.199 t=0 0 m=audio 24410 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/46455655400 Standard INIT 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/46455655400) State Change CS_INIT -> CS_ROUTING 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/46455655400) State INIT going to sleep 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/46455655400) Running State Change CS_ROUTING (Cur 142 Tot 134742) 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/46455655400) State ROUTING 2024-12-09 17:02:39.922482 80.67% [DEBUG] mod_sofia.c:158 sofia/default/46455655400 SOFIA ROUTING 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/46455655400) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/46455655400) State ROUTING going to sleep 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/46455655400) Running State Change CS_CONSUME_MEDIA (Cur 142 Tot 134742) 2024-12-09 17:02:39.922482 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/46455655400 entering state [calling][0] 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/46455655400) State CONSUME_MEDIA 2024-12-09 17:02:39.922482 80.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/46455655400) State CONSUME_MEDIA going to sleep 2024-12-09 17:02:40.022471 80.67% [NOTICE] sofia.c:1065 Hangup sofia/default/+46731218760@46.246.2.119 [CS_EXECUTE] [NORMAL_CLEARING] 2024-12-09 17:02:40.022471 80.67% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46731218760@46.246.2.119] 2024-12-09 17:02:40.022471 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/46455655400 entering state [proceeding][180] 2024-12-09 17:02:40.022471 80.67% [NOTICE] sofia.c:7604 Ring-Ready sofia/default/46455655400! 2024-12-09 17:02:40.022471 80.67% [DEBUG] switch_channel.c:3513 (sofia/default/46455655400) Callstate Change DOWN -> RINGING 2024-12-09 17:02:40.022471 80.67% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46731218760@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:40.022471 80.67% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46707166943] 2024-12-09 17:02:40.022471 80.67% [NOTICE] switch_ivr_bridge.c:1027 Hangup sofia/default/+46707166943 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2024-12-09 17:02:40.022471 80.67% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46707166943) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:40.022471 80.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46707166943) Running State Change CS_HANGUP (Cur 142 Tot 134742) 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46707166943) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46707166943) State HANGUP 2024-12-09 17:02:40.042475 80.67% [DEBUG] mod_sofia.c:463 sofia/default/+46707166943 Overriding SIP cause 480 with 200 from the other leg 2024-12-09 17:02:40.042475 80.67% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46707166943 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46707166943 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46731218760@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_session.c:2979 sofia/default/+46731218760@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46731218760@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218760@46.246.2.119) Running State Change CS_HANGUP (Cur 142 Tot 134742) 2024-12-09 17:02:40.042475 80.67% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/default/+46707166943 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46731218760@46.246.2.119) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46707166943 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46707166943) State HANGUP going to sleep 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218760@46.246.2.119) State HANGUP 2024-12-09 17:02:40.042475 80.67% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46731218760@46.246.2.119 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46707166943) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46707166943) Running State Change CS_REPORTING (Cur 142 Tot 134742) 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46707166943) State REPORTING 2024-12-09 17:02:40.042475 80.67% [INFO] mod_json_cdr.c:272 Process [34062ecd-d61c-4172-b60c-76c11440458a.cdr.json] 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46731218760@46.246.2.119 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218760@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46731218760@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218760@46.246.2.119) Running State Change CS_REPORTING (Cur 142 Tot 134742) 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218760@46.246.2.119) State REPORTING 2024-12-09 17:02:40.042475 80.67% [INFO] mod_json_cdr.c:272 Process [a22ff739-b702-4b15-874e-1b15b80430e1.cdr.json] 2024-12-09 17:02:40.042475 80.67% [NOTICE] mod_sofia.c:2514 Ring-Ready sofia/default/+46708516331@62.80.216.236! 2024-12-09 17:02:40.042475 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/+46708516331@62.80.216.236 entering state [early][180] 2024-12-09 17:02:40.042475 80.67% [NOTICE] switch_ivr_originate.c:568 Ring Ready sofia/default/+46708516331@62.80.216.236! 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46707166943 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46707166943) State REPORTING going to sleep 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46707166943) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_session.c:1744 Session 134561 (sofia/default/+46707166943) Locked, Waiting on external entities 2024-12-09 17:02:40.042475 80.67% [NOTICE] switch_core_session.c:1762 Session 134561 (sofia/default/+46707166943) Ended 2024-12-09 17:02:40.042475 80.67% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46707166943 [CS_DESTROY] 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46707166943) Running State Change CS_DESTROY (Cur 141 Tot 134742) 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46707166943) State DESTROY 2024-12-09 17:02:40.042475 80.67% [DEBUG] mod_sofia.c:380 sofia/default/+46707166943 SOFIA DESTROY 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46707166943 Standard DESTROY 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46707166943) State DESTROY going to sleep 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46731218760@46.246.2.119 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218760@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46731218760@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_session.c:1744 Session 134557 (sofia/default/+46731218760@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:40.042475 80.67% [NOTICE] switch_core_session.c:1762 Session 134557 (sofia/default/+46731218760@46.246.2.119) Ended 2024-12-09 17:02:40.042475 80.67% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46731218760@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46731218760@46.246.2.119) Running State Change CS_DESTROY (Cur 140 Tot 134742) 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218760@46.246.2.119) State DESTROY 2024-12-09 17:02:40.042475 80.67% [DEBUG] mod_sofia.c:380 sofia/default/+46731218760@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46731218760@46.246.2.119 Standard DESTROY 2024-12-09 17:02:40.042475 80.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218760@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:40.182474 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/+46705359757 entering state [completing][200] 2024-12-09 17:02:40.182474 80.67% [DEBUG] sofia.c:7500 Duplicate SDP v=0 o=Sonus_UAC 115340 613419 IN IP4 216.155.239.86 s=SIP Media Capabilities c=IN IP4 216.155.239.84 t=0 0 m=audio 60364 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=maxptime:20 2024-12-09 17:02:40.202475 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/+46705359757 entering state [ready][200] 2024-12-09 17:02:40.202475 80.67% [NOTICE] sofia.c:8622 Channel [sofia/default/+46705359757] has been answered 2024-12-09 17:02:40.202475 80.67% [DEBUG] switch_channel.c:3912 (sofia/default/+46705359757) Callstate Change RINGING -> ACTIVE 2024-12-09 17:02:40.202475 80.67% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/default/+46731218962@46.246.2.119. 2024-12-09 17:02:40.202475 80.67% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/+46731218962@46.246.2.119: v=0 o=FreeSWITCH 1733745049 1733745051 IN IP4 172.232.155.199 s=FreeSWITCH c=IN IP4 172.232.155.199 t=0 0 m=audio 18698 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2024-12-09 17:02:40.202475 80.67% [NOTICE] switch_ivr_bridge.c:730 Channel [sofia/default/+46731218962@46.246.2.119] has been answered EXECUTE [depth=0] sofia/default/+46731218962@46.246.2.119 sched_hangup(+6000 normal_clearing) 2024-12-09 17:02:40.202475 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218962@46.246.2.119 entering state [completed][200] 2024-12-09 17:02:40.202475 80.67% [DEBUG] switch_scheduler.c:263 Added task 39929 switch_ivr_schedule_hangup (fa7dc7da-7d72-46ad-98cb-12156ad4c132) to run at 1733769760 2024-12-09 17:02:40.202475 80.67% [DEBUG] switch_scheduler.c:147 Deleting task 39905 switch_ivr_schedule_hangup (a22ff739-b702-4b15-874e-1b15b80430e1) 2024-12-09 17:02:40.202475 80.67% [DEBUG] switch_channel.c:3912 (sofia/default/+46731218962@46.246.2.119) Callstate Change EARLY -> ACTIVE 2024-12-09 17:02:40.222465 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218962@46.246.2.119 entering state [ready][200] 2024-12-09 17:02:40.242473 80.67% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:40.262473 80.67% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:40.282471 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/46455655400 entering state [completing][200] 2024-12-09 17:02:40.282471 80.67% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=root 954783167 954783167 IN IP4 46.246.2.119 s=Asterisk PBX 16.29.0 c=IN IP4 46.246.2.119 t=0 0 m=audio 14784 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 2024-12-09 17:02:40.282471 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/46455655400 entering state [ready][200] 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/46455655400 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_codec.c:111 sofia/default/46455655400 Original read codec set to PCMA:8 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:5856 sofia/default/46455655400 Set 2833 dtmf send payload to 101 recv payload to 101 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/46455655400] 172.232.155.199 port 24410 -> 46.246.2.119 port 14784 codec: 8 ms: 20 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:8972 sofia/default/46455655400 Set 2833 dtmf send payload to 101 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:8979 sofia/default/46455655400 Set 2833 dtmf receive payload to 101 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_core_media.c:9002 sofia/default/46455655400 Set rtp dtmf delay to 40 2024-12-09 17:02:40.282471 80.67% [NOTICE] sofia.c:8680 Channel [sofia/default/46455655400] has been answered 2024-12-09 17:02:40.282471 80.67% [DEBUG] switch_channel.c:3912 (sofia/default/46455655400) Callstate Change RINGING -> ACTIVE 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+46708516331@62.80.216.236] 172.232.155.199 port 18536 -> 62.80.216.236 port 44664 codec: 8 ms: 20 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_core_media.c:8972 sofia/default/+46708516331@62.80.216.236 Set 2833 dtmf send payload to 110 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_core_media.c:8979 sofia/default/+46708516331@62.80.216.236 Set 2833 dtmf receive payload to 110 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_core_media.c:9002 sofia/default/+46708516331@62.80.216.236 Set rtp dtmf delay to 40 2024-12-09 17:02:40.302472 80.67% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/+46708516331@62.80.216.236: v=0 o=FreeSWITCH 1733745224 1733745225 IN IP4 172.232.155.199 s=FreeSWITCH c=IN IP4 172.232.155.199 t=0 0 m=audio 18536 RTP/AVP 8 110 a=rtpmap:8 PCMA/8000 a=rtpmap:110 telephone-event/8000 a=fmtp:110 0-15 a=ptime:20 a=sendrecv 2024-12-09 17:02:40.302472 80.67% [NOTICE] switch_ivr_originate.c:3851 Channel [sofia/default/+46708516331@62.80.216.236] has been answered EXECUTE [depth=0] sofia/default/+46708516331@62.80.216.236 sched_hangup(+6000 normal_clearing) 2024-12-09 17:02:40.302472 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/+46708516331@62.80.216.236 entering state [completed][200] 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_scheduler.c:263 Added task 39930 switch_ivr_schedule_hangup (3923dfbf-697e-4e6f-9aff-3144ae9390a3) to run at 1733769760 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_channel.c:3912 (sofia/default/+46708516331@62.80.216.236) Callstate Change RINGING -> ACTIVE 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_ivr_originate.c:3909 Originate Resulted in Success: [sofia/default/46455655400] Peer UUID: 6883d05e-f80b-4ecc-a05f-40e38f7b25f7 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_ivr_bridge.c:1791 (sofia/default/46455655400) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/46455655400) Running State Change CS_EXCHANGE_MEDIA (Cur 140 Tot 134742) 2024-12-09 17:02:40.302472 80.67% [DEBUG] switch_core_state_machine.c:650 (sofia/default/46455655400) State EXCHANGE_MEDIA 2024-12-09 17:02:40.302472 80.67% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA 2024-12-09 17:02:40.322469 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/+46708516331@62.80.216.236 entering state [ready][200] 2024-12-09 17:02:40.382469 80.67% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:40.462463 80.67% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:40.662471 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/+46706217547 entering state [proceeding][180] 2024-12-09 17:02:40.662471 80.67% [NOTICE] sofia.c:7604 Ring-Ready sofia/default/+46706217547! 2024-12-09 17:02:40.662471 80.67% [DEBUG] switch_channel.c:3513 (sofia/default/+46706217547) Callstate Change DOWN -> RINGING 2024-12-09 17:02:40.662471 80.67% [NOTICE] mod_sofia.c:2514 Ring-Ready sofia/default/+46791400926@46.246.2.119! 2024-12-09 17:02:40.662471 80.67% [DEBUG] sofia.c:7493 Channel sofia/default/+46791400926@46.246.2.119 entering state [early][180] 2024-12-09 17:02:40.662471 80.67% [NOTICE] switch_ivr_originate.c:568 Ring Ready sofia/default/+46791400926@46.246.2.119! 2024-12-09 17:02:40.682468 80.67% [WARNING] sofia_reg.c:1842 SIP auth challenge (REGISTER) on sofia profile 'default' for [46455680810.minitel.se@sip.minitel.se] from ip 194.0.227.250 2024-12-09 17:02:40.702470 80.67% [NOTICE] sofia.c:1065 Hangup sofia/default/+46731218962@46.246.2.119 [CS_EXECUTE] [NORMAL_CLEARING] 2024-12-09 17:02:40.702470 80.67% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46731218962@46.246.2.119] 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46731218962@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46732032095] 2024-12-09 17:02:40.722468 80.00% [NOTICE] switch_ivr_bridge.c:1027 Hangup sofia/default/+46732032095 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46732032095) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46732032095) Running State Change CS_HANGUP (Cur 140 Tot 134742) 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46732032095) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46732032095) State HANGUP 2024-12-09 17:02:40.722468 80.00% [DEBUG] mod_sofia.c:463 sofia/default/+46732032095 Overriding SIP cause 480 with 200 from the other leg 2024-12-09 17:02:40.722468 80.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46732032095 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46732032095 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46731218962@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_session.c:2979 sofia/default/+46731218962@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46731218962@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_HANGUP (Cur 140 Tot 134742) 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46731218962@46.246.2.119) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:40.722468 80.00% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/default/+46732032095 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP 2024-12-09 17:02:40.722468 80.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46731218962@46.246.2.119 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46732032095 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46732032095) State HANGUP going to sleep 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46732032095) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46732032095) Running State Change CS_REPORTING (Cur 140 Tot 134742) 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46732032095) State REPORTING 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46731218962@46.246.2.119 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:40.722468 80.00% [INFO] mod_json_cdr.c:272 Process [bbcbf181-55c2-415d-8e3f-20e20240a0ae.cdr.json] 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46731218962@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_REPORTING (Cur 140 Tot 134742) 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING 2024-12-09 17:02:40.722468 80.00% [INFO] mod_json_cdr.c:272 Process [f8cfd707-cae9-4550-8f7b-8249388c5fcf.cdr.json] 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46732032095 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46732032095) State REPORTING going to sleep 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46732032095) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_session.c:1744 Session 134584 (sofia/default/+46732032095) Locked, Waiting on external entities 2024-12-09 17:02:40.722468 80.00% [NOTICE] switch_core_session.c:1762 Session 134584 (sofia/default/+46732032095) Ended 2024-12-09 17:02:40.722468 80.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46732032095 [CS_DESTROY] 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46732032095) Running State Change CS_DESTROY (Cur 139 Tot 134742) 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46732032095) State DESTROY 2024-12-09 17:02:40.722468 80.00% [DEBUG] mod_sofia.c:380 sofia/default/+46732032095 SOFIA DESTROY 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46732032095 Standard DESTROY 2024-12-09 17:02:40.722468 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46732032095) State DESTROY going to sleep 2024-12-09 17:02:40.742465 80.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46731218962@46.246.2.119 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:40.742465 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:40.742465 80.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46731218962@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:40.742465 80.00% [DEBUG] switch_core_session.c:1744 Session 134583 (sofia/default/+46731218962@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:40.742465 80.00% [NOTICE] switch_core_session.c:1762 Session 134583 (sofia/default/+46731218962@46.246.2.119) Ended 2024-12-09 17:02:40.742465 80.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46731218962@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:40.742465 80.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_DESTROY (Cur 138 Tot 134742) 2024-12-09 17:02:40.742465 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY 2024-12-09 17:02:40.742465 80.00% [DEBUG] mod_sofia.c:380 sofia/default/+46731218962@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:40.742465 80.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46731218962@46.246.2.119 Standard DESTROY 2024-12-09 17:02:40.742465 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:40.802471 80.00% [DEBUG] switch_scheduler.c:147 Deleting task 39911 switch_ivr_schedule_hangup (f8cfd707-cae9-4550-8f7b-8249388c5fcf) 2024-12-09 17:02:40.862469 80.00% [WARNING] sofia_reg.c:1842 SIP auth challenge (REGISTER) on sofia profile 'default' for [464293390.minitel.se@sip.minitel.se] from ip 194.0.225.136 2024-12-09 17:02:41.182470 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46851760489 entering state [completing][200] 2024-12-09 17:02:41.182470 80.00% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=Sonus_UAC 19784 352050 IN IP4 216.155.239.86 s=SIP Media Capabilities c=IN IP4 216.155.239.84 t=0 0 m=audio 49784 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=maxptime:20 2024-12-09 17:02:41.182470 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46851760489 entering state [ready][200] 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/+46851760489 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_codec.c:111 sofia/default/+46851760489 Original read codec set to PCMA:8 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:5856 sofia/default/+46851760489 Set 2833 dtmf send payload to 101 recv payload to 101 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+46851760489] 172.232.155.199 port 20678 -> 216.155.239.84 port 49784 codec: 8 ms: 20 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:8972 sofia/default/+46851760489 Set 2833 dtmf send payload to 101 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:8979 sofia/default/+46851760489 Set 2833 dtmf receive payload to 101 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_core_media.c:9002 sofia/default/+46851760489 Set rtp dtmf delay to 40 2024-12-09 17:02:41.182470 80.00% [NOTICE] sofia.c:8680 Channel [sofia/default/+46851760489] has been answered 2024-12-09 17:02:41.182470 80.00% [DEBUG] switch_channel.c:3912 (sofia/default/+46851760489) Callstate Change RINGING -> ACTIVE 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+46731218752@46.246.2.119] 172.232.155.199 port 24612 -> 46.246.2.119 port 17488 codec: 8 ms: 20 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_core_media.c:8972 sofia/default/+46731218752@46.246.2.119 Set 2833 dtmf send payload to 101 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_core_media.c:8979 sofia/default/+46731218752@46.246.2.119 Set 2833 dtmf receive payload to 101 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_core_media.c:9002 sofia/default/+46731218752@46.246.2.119 Set rtp dtmf delay to 40 2024-12-09 17:02:41.202466 80.00% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/+46731218752@46.246.2.119: v=0 o=FreeSWITCH 1733739149 1733739150 IN IP4 172.232.155.199 s=FreeSWITCH c=IN IP4 172.232.155.199 t=0 0 m=audio 24612 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2024-12-09 17:02:41.202466 80.00% [NOTICE] switch_ivr_originate.c:3851 Channel [sofia/default/+46731218752@46.246.2.119] has been answered EXECUTE [depth=0] sofia/default/+46731218752@46.246.2.119 sched_hangup(+6000 normal_clearing) 2024-12-09 17:02:41.202466 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218752@46.246.2.119 entering state [completed][200] 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_scheduler.c:263 Added task 39931 switch_ivr_schedule_hangup (78dbee39-12c5-4b20-89a0-fa1300200f5b) to run at 1733769761 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_channel.c:3912 (sofia/default/+46731218752@46.246.2.119) Callstate Change RINGING -> ACTIVE 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_ivr_originate.c:3909 Originate Resulted in Success: [sofia/default/+46851760489] Peer UUID: 0661ea4e-8229-4c97-8300-4aac86a5d7d4 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_ivr_bridge.c:1791 (sofia/default/+46851760489) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46851760489) Running State Change CS_EXCHANGE_MEDIA (Cur 138 Tot 134742) 2024-12-09 17:02:41.202466 80.00% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46851760489) State EXCHANGE_MEDIA 2024-12-09 17:02:41.202466 80.00% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA 2024-12-09 17:02:41.222468 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218752@46.246.2.119 entering state [ready][200] 2024-12-09 17:02:41.242475 80.00% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:41.242475 80.00% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:41.302465 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46791400609@46.246.2.119 entering state [terminated][487] 2024-12-09 17:02:41.302465 80.00% [NOTICE] sofia.c:8735 Hangup sofia/default/+46791400609@46.246.2.119 [CS_EXECUTE] [ORIGINATOR_CANCEL] 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_ivr_bridge.c:825 sofia/default/+46791400609@46.246.2.119 ending bridge by request from read function 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46791400609@46.246.2.119] 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46791400609@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46739492020] 2024-12-09 17:02:41.302465 80.00% [NOTICE] switch_ivr_bridge.c:1023 Hangup sofia/default/+46739492020 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL] 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46739492020) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46739492020) Running State Change CS_HANGUP (Cur 138 Tot 134742) 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46739492020) Callstate Change RINGING -> HANGUP 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46739492020) State HANGUP 2024-12-09 17:02:41.302465 80.00% [DEBUG] mod_sofia.c:463 sofia/default/+46739492020 Overriding SIP cause 487 with 487 from the other leg 2024-12-09 17:02:41.302465 80.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46739492020 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46739492020 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:41.302465 80.00% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/default/+46739492020 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46739492020 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46739492020) State HANGUP going to sleep 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46739492020) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46739492020) Running State Change CS_REPORTING (Cur 138 Tot 134742) 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46739492020) State REPORTING 2024-12-09 17:02:41.302465 80.00% [INFO] mod_json_cdr.c:272 Process [c71be975-7ff8-4843-a46b-2f3968004062.cdr.json] 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46791400609@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_session.c:2979 sofia/default/+46791400609@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46791400609@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400609@46.246.2.119) Running State Change CS_HANGUP (Cur 138 Tot 134742) 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46791400609@46.246.2.119) Callstate Change EARLY -> HANGUP 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46791400609@46.246.2.119) State HANGUP 2024-12-09 17:02:41.302465 80.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46791400609@46.246.2.119 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46791400609@46.246.2.119 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46791400609@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46791400609@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400609@46.246.2.119) Running State Change CS_REPORTING (Cur 138 Tot 134742) 2024-12-09 17:02:41.302465 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46791400609@46.246.2.119) State REPORTING 2024-12-09 17:02:41.302465 80.00% [INFO] mod_json_cdr.c:272 Process [86a56420-4bea-45aa-8897-558a7b7cd826.cdr.json] 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46739492020 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46739492020) State REPORTING going to sleep 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46739492020) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_session.c:1744 Session 134606 (sofia/default/+46739492020) Locked, Waiting on external entities 2024-12-09 17:02:41.322466 80.00% [NOTICE] switch_core_session.c:1762 Session 134606 (sofia/default/+46739492020) Ended 2024-12-09 17:02:41.322466 80.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46739492020 [CS_DESTROY] 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46739492020) Running State Change CS_DESTROY (Cur 137 Tot 134742) 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46739492020) State DESTROY 2024-12-09 17:02:41.322466 80.00% [DEBUG] mod_sofia.c:380 sofia/default/+46739492020 SOFIA DESTROY 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46739492020 Standard DESTROY 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46739492020) State DESTROY going to sleep 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46791400609@46.246.2.119 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46791400609@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46791400609@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_session.c:1744 Session 134605 (sofia/default/+46791400609@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:41.322466 80.00% [NOTICE] switch_core_session.c:1762 Session 134605 (sofia/default/+46791400609@46.246.2.119) Ended 2024-12-09 17:02:41.322466 80.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46791400609@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46791400609@46.246.2.119) Running State Change CS_DESTROY (Cur 136 Tot 134742) 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46791400609@46.246.2.119) State DESTROY 2024-12-09 17:02:41.322466 80.00% [DEBUG] mod_sofia.c:380 sofia/default/+46791400609@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46791400609@46.246.2.119 Standard DESTROY 2024-12-09 17:02:41.322466 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46791400609@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:41.322466 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46707688734 entering state [completing][200] 2024-12-09 17:02:41.322466 80.00% [DEBUG] sofia.c:7500 Duplicate SDP v=0 o=Sonus_UAC 551164 909405 IN IP4 216.155.239.86 s=SIP Media Capabilities c=IN IP4 216.155.239.84 t=0 0 m=audio 49534 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=maxptime:20 2024-12-09 17:02:41.342470 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46707688734 entering state [ready][200] 2024-12-09 17:02:41.342470 80.00% [NOTICE] sofia.c:8622 Channel [sofia/default/+46707688734] has been answered 2024-12-09 17:02:41.342470 80.00% [DEBUG] switch_channel.c:3912 (sofia/default/+46707688734) Callstate Change RINGING -> ACTIVE 2024-12-09 17:02:41.362470 80.00% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/default/+46731218962@46.246.2.119. 2024-12-09 17:02:41.362470 80.00% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/+46731218962@46.246.2.119: v=0 o=FreeSWITCH 1733745807 1733745809 IN IP4 172.232.155.199 s=FreeSWITCH c=IN IP4 172.232.155.199 t=0 0 m=audio 17944 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2024-12-09 17:02:41.362470 80.00% [NOTICE] switch_ivr_bridge.c:730 Channel [sofia/default/+46731218962@46.246.2.119] has been answered EXECUTE [depth=0] sofia/default/+46731218962@46.246.2.119 sched_hangup(+6000 normal_clearing) 2024-12-09 17:02:41.362470 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218962@46.246.2.119 entering state [completed][200] 2024-12-09 17:02:41.362470 80.00% [DEBUG] switch_scheduler.c:263 Added task 39932 switch_ivr_schedule_hangup (62e2f76d-5343-4065-8d46-ab3912b5376c) to run at 1733769761 2024-12-09 17:02:41.362470 80.00% [DEBUG] switch_channel.c:3912 (sofia/default/+46731218962@46.246.2.119) Callstate Change EARLY -> ACTIVE 2024-12-09 17:02:41.382470 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218962@46.246.2.119 entering state [ready][200] 2024-12-09 17:02:41.402465 80.00% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:41.422470 80.00% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:41.442471 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+4686922557@46.246.2.119 entering state [terminated][487] 2024-12-09 17:02:41.442471 80.00% [NOTICE] sofia.c:8735 Hangup sofia/default/+4686922557@46.246.2.119 [CS_EXECUTE] [ORIGINATOR_CANCEL] 2024-12-09 17:02:41.462469 80.00% [NOTICE] switch_ivr_originate.c:3816 Hangup sofia/default/+46734066142 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL] 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_ivr_originate.c:4041 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL] 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46734066142) Running State Change CS_HANGUP (Cur 136 Tot 134742) 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46734066142) Callstate Change RINGING -> HANGUP 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46734066142) State HANGUP 2024-12-09 17:02:41.462469 80.00% [DEBUG] mod_sofia.c:463 sofia/default/+46734066142 Overriding SIP cause 487 with 487 from the other leg 2024-12-09 17:02:41.462469 80.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46734066142 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.462469 80.00% [INFO] mod_dptools.c:3635 Originate Failed. Cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_session.c:2979 sofia/default/+4686922557@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+4686922557@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+4686922557@46.246.2.119) Running State Change CS_HANGUP (Cur 136 Tot 134742) 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+4686922557@46.246.2.119) Callstate Change RINGING -> HANGUP 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+4686922557@46.246.2.119) State HANGUP 2024-12-09 17:02:41.462469 80.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/+4686922557@46.246.2.119 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.462469 80.00% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/default/+46734066142 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46734066142 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46734066142) State HANGUP going to sleep 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46734066142) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46734066142) Running State Change CS_REPORTING (Cur 136 Tot 134742) 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46734066142) State REPORTING 2024-12-09 17:02:41.462469 80.00% [INFO] mod_json_cdr.c:272 Process [2d024302-e877-4097-b800-01cd084bb473.cdr.json] 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/+4686922557@46.246.2.119 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+4686922557@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+4686922557@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+4686922557@46.246.2.119) Running State Change CS_REPORTING (Cur 136 Tot 134742) 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+4686922557@46.246.2.119) State REPORTING 2024-12-09 17:02:41.462469 80.00% [INFO] mod_json_cdr.c:272 Process [07dcec95-49e8-42b1-af13-63c4e0503b66.cdr.json] 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46734066142 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46734066142) State REPORTING going to sleep 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46734066142) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_session.c:1744 Session 134578 (sofia/default/+46734066142) Locked, Waiting on external entities 2024-12-09 17:02:41.462469 80.00% [NOTICE] switch_core_session.c:1762 Session 134578 (sofia/default/+46734066142) Ended 2024-12-09 17:02:41.462469 80.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46734066142 [CS_DESTROY] 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46734066142) Running State Change CS_DESTROY (Cur 135 Tot 134742) 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46734066142) State DESTROY 2024-12-09 17:02:41.462469 80.00% [DEBUG] mod_sofia.c:380 sofia/default/+46734066142 SOFIA DESTROY 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46734066142 Standard DESTROY 2024-12-09 17:02:41.462469 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46734066142) State DESTROY going to sleep 2024-12-09 17:02:41.482469 80.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/+4686922557@46.246.2.119 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:41.482469 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+4686922557@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:41.482469 80.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+4686922557@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:41.482469 80.00% [DEBUG] switch_core_session.c:1744 Session 134577 (sofia/default/+4686922557@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:41.482469 80.00% [NOTICE] switch_core_session.c:1762 Session 134577 (sofia/default/+4686922557@46.246.2.119) Ended 2024-12-09 17:02:41.482469 80.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+4686922557@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:41.482469 80.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+4686922557@46.246.2.119) Running State Change CS_DESTROY (Cur 134 Tot 134742) 2024-12-09 17:02:41.482469 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+4686922557@46.246.2.119) State DESTROY 2024-12-09 17:02:41.482469 80.00% [DEBUG] mod_sofia.c:380 sofia/default/+4686922557@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:41.482469 80.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/+4686922557@46.246.2.119 Standard DESTROY 2024-12-09 17:02:41.482469 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+4686922557@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:41.582464 80.00% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:41.602485 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46703585379 entering state [completing][200] 2024-12-09 17:02:41.602485 80.00% [DEBUG] sofia.c:7500 Duplicate SDP v=0 o=Sonus_UAC 157110 692637 IN IP4 216.155.239.86 s=SIP Media Capabilities c=IN IP4 216.155.239.84 t=0 0 m=audio 52182 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=maxptime:20 2024-12-09 17:02:41.602485 80.00% [NOTICE] sofia.c:1065 Hangup sofia/default/+46730822965 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2024-12-09 17:02:41.602485 80.00% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46730822965] 2024-12-09 17:02:41.602485 80.00% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46730822965) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:41.602485 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46730822965) Running State Change CS_HANGUP (Cur 134 Tot 134742) 2024-12-09 17:02:41.602485 80.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46730822965) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:41.602485 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46730822965) State HANGUP 2024-12-09 17:02:41.602485 80.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46730822965 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46730822965 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46730822965) State HANGUP going to sleep 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46730822965) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46730822965) Running State Change CS_REPORTING (Cur 134 Tot 134742) 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46730822965) State REPORTING 2024-12-09 17:02:41.622474 80.00% [INFO] mod_json_cdr.c:272 Process [240b2f4e-f2a5-4ac8-82dd-f69b4a87c1dc.cdr.json] 2024-12-09 17:02:41.622474 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46703585379 entering state [ready][200] 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:41.622474 80.00% [NOTICE] sofia.c:8622 Channel [sofia/default/+46703585379] has been answered 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_channel.c:3912 (sofia/default/+46703585379) Callstate Change RINGING -> ACTIVE 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46730822965 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46730822965) State REPORTING going to sleep 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46730822965) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_session.c:1744 Session 134182 (sofia/default/+46730822965) Locked, Waiting on external entities 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46730822965 ending bridge by request from write function 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46910217808@46.246.2.119] 2024-12-09 17:02:41.622474 80.00% [NOTICE] switch_ivr_bridge.c:1942 Hangup sofia/default/+46910217808@46.246.2.119 [CS_EXECUTE] [NORMAL_CLEARING] 2024-12-09 17:02:41.622474 80.00% [NOTICE] switch_core_session.c:1762 Session 134182 (sofia/default/+46730822965) Ended 2024-12-09 17:02:41.622474 80.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46730822965 [CS_DESTROY] 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_session.c:2979 sofia/default/+46910217808@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46910217808@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46910217808@46.246.2.119) Running State Change CS_HANGUP (Cur 133 Tot 134742) 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46730822965) Running State Change CS_DESTROY (Cur 133 Tot 134742) 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46730822965) State DESTROY 2024-12-09 17:02:41.622474 80.00% [DEBUG] mod_sofia.c:380 sofia/default/+46730822965 SOFIA DESTROY 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46910217808@46.246.2.119) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46730822965 Standard DESTROY 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46730822965) State DESTROY going to sleep 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46910217808@46.246.2.119) State HANGUP 2024-12-09 17:02:41.622474 80.00% [DEBUG] mod_sofia.c:463 sofia/default/+46910217808@46.246.2.119 Overriding SIP cause 480 with 200 from the other leg 2024-12-09 17:02:41.622474 80.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46910217808@46.246.2.119 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:41.622474 80.00% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/default/+46910217808@46.246.2.119 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46910217808@46.246.2.119 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46910217808@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46910217808@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46910217808@46.246.2.119) Running State Change CS_REPORTING (Cur 133 Tot 134742) 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46910217808@46.246.2.119) State REPORTING 2024-12-09 17:02:41.622474 80.00% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/default/+46731218752@46.246.2.119. 2024-12-09 17:02:41.622474 80.00% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/+46731218752@46.246.2.119: v=0 o=FreeSWITCH 1733734601 1733734603 IN IP4 172.232.155.199 s=FreeSWITCH c=IN IP4 172.232.155.199 t=0 0 m=audio 29154 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2024-12-09 17:02:41.622474 80.00% [NOTICE] switch_ivr_bridge.c:730 Channel [sofia/default/+46731218752@46.246.2.119] has been answered 2024-12-09 17:02:41.642481 80.00% [INFO] mod_json_cdr.c:272 Process [adf0f2b6-b251-414b-b22b-444ac2dda96f.cdr.json] EXECUTE [depth=0] sofia/default/+46731218752@46.246.2.119 sched_hangup(+6000 normal_clearing) 2024-12-09 17:02:41.642481 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218752@46.246.2.119 entering state [completed][200] 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_scheduler.c:263 Added task 39933 switch_ivr_schedule_hangup (439fe0fa-04d0-40e6-a38f-e834e4b07690) to run at 1733769761 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_channel.c:3912 (sofia/default/+46731218752@46.246.2.119) Callstate Change EARLY -> ACTIVE 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46910217808@46.246.2.119 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46910217808@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46910217808@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_core_session.c:1744 Session 134181 (sofia/default/+46910217808@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:41.642481 80.00% [NOTICE] switch_core_session.c:1762 Session 134181 (sofia/default/+46910217808@46.246.2.119) Ended 2024-12-09 17:02:41.642481 80.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46910217808@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46910217808@46.246.2.119) Running State Change CS_DESTROY (Cur 132 Tot 134742) 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46910217808@46.246.2.119) State DESTROY 2024-12-09 17:02:41.642481 80.00% [DEBUG] mod_sofia.c:380 sofia/default/+46910217808@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46910217808@46.246.2.119 Standard DESTROY 2024-12-09 17:02:41.642481 80.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46910217808@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:41.642481 80.00% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218752@46.246.2.119 entering state [ready][200] 2024-12-09 17:02:41.682482 80.00% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:42.022471 79.90% [NOTICE] sofia.c:1065 Hangup sofia/default/+46731218962@46.246.2.119 [CS_EXECUTE] [NORMAL_CLEARING] 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46731218962@46.246.2.119] 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46731218962@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46761899251] 2024-12-09 17:02:42.022471 79.90% [NOTICE] switch_ivr_bridge.c:1027 Hangup sofia/default/+46761899251 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46761899251) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46761899251) Running State Change CS_HANGUP (Cur 132 Tot 134742) 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46761899251 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46731218962@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46761899251) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_session.c:2979 sofia/default/+46731218962@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46761899251) State HANGUP 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46731218962@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_HANGUP (Cur 132 Tot 134742) 2024-12-09 17:02:42.022471 79.90% [DEBUG] mod_sofia.c:463 sofia/default/+46761899251 Overriding SIP cause 480 with 200 from the other leg 2024-12-09 17:02:42.022471 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46761899251 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46731218962@46.246.2.119) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP 2024-12-09 17:02:42.022471 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46731218962@46.246.2.119 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:42.022471 79.90% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/default/+46761899251 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46761899251 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46761899251) State HANGUP going to sleep 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46761899251) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46761899251) Running State Change CS_REPORTING (Cur 132 Tot 134742) 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46761899251) State REPORTING 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46731218962@46.246.2.119 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46731218962@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.022471 79.90% [INFO] mod_json_cdr.c:272 Process [2a75b9dd-9257-4a42-8bc7-cc736dfa5c54.cdr.json] 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_REPORTING (Cur 132 Tot 134742) 2024-12-09 17:02:42.022471 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING 2024-12-09 17:02:42.022471 79.90% [INFO] mod_json_cdr.c:272 Process [c40c546e-8990-4143-bdaa-207e59e1c06c.cdr.json] 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46761899251 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46761899251) State REPORTING going to sleep 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46761899251) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_session.c:1744 Session 134649 (sofia/default/+46761899251) Locked, Waiting on external entities 2024-12-09 17:02:42.042489 79.90% [NOTICE] switch_core_session.c:1762 Session 134649 (sofia/default/+46761899251) Ended 2024-12-09 17:02:42.042489 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46761899251 [CS_DESTROY] 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46761899251) Running State Change CS_DESTROY (Cur 131 Tot 134742) 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46761899251) State DESTROY 2024-12-09 17:02:42.042489 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46761899251 SOFIA DESTROY 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46761899251 Standard DESTROY 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46761899251) State DESTROY going to sleep 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46731218962@46.246.2.119 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46731218962@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_session.c:1744 Session 134648 (sofia/default/+46731218962@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:42.042489 79.90% [NOTICE] switch_core_session.c:1762 Session 134648 (sofia/default/+46731218962@46.246.2.119) Ended 2024-12-09 17:02:42.042489 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46731218962@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_DESTROY (Cur 130 Tot 134742) 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY 2024-12-09 17:02:42.042489 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46731218962@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46731218962@46.246.2.119 Standard DESTROY 2024-12-09 17:02:42.042489 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_scheduler.c:147 Deleting task 39842 switch_ivr_schedule_hangup (adf0f2b6-b251-414b-b22b-444ac2dda96f) 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_scheduler.c:147 Deleting task 39921 switch_ivr_schedule_hangup (c40c546e-8990-4143-bdaa-207e59e1c06c) 2024-12-09 17:02:42.142466 79.90% [DEBUG] sofia.c:7493 Channel sofia/default/+46704399539 entering state [proceeding][183] 2024-12-09 17:02:42.142466 79.90% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=Sonus_UAC 262779 609918 IN IP4 216.155.239.86 s=SIP Media Capabilities c=IN IP4 216.155.239.84 t=0 0 m=audio 58622 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=maxptime:20 2024-12-09 17:02:42.142466 79.90% [NOTICE] sofia.c:7506 Pre-Answer sofia/default/+46704399539! 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_channel.c:3585 (sofia/default/+46704399539) Callstate Change DOWN -> EARLY 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/+46704399539 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_codec.c:111 sofia/default/+46704399539 Original read codec set to PCMA:8 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:5856 sofia/default/+46704399539 Set 2833 dtmf send payload to 101 recv payload to 101 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+46704399539] 172.232.155.199 port 28804 -> 216.155.239.84 port 58622 codec: 8 ms: 20 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:8972 sofia/default/+46704399539 Set 2833 dtmf send payload to 101 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:8979 sofia/default/+46704399539 Set 2833 dtmf receive payload to 101 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:9002 sofia/default/+46704399539 Set rtp dtmf delay to 40 2024-12-09 17:02:42.142466 79.90% [INFO] switch_ivr_originate.c:3858 Sending early media 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+46791400478@46.246.2.119] 172.232.155.199 port 24850 -> 46.246.2.119 port 12284 codec: 8 ms: 20 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:8972 sofia/default/+46791400478@46.246.2.119 Set 2833 dtmf send payload to 101 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:8979 sofia/default/+46791400478@46.246.2.119 Set 2833 dtmf receive payload to 101 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_media.c:9002 sofia/default/+46791400478@46.246.2.119 Set rtp dtmf delay to 40 2024-12-09 17:02:42.142466 79.90% [DEBUG] mod_sofia.c:2607 Ring SDP: v=0 o=FreeSWITCH 1733738912 1733738913 IN IP4 172.232.155.199 s=FreeSWITCH c=IN IP4 172.232.155.199 t=0 0 m=audio 24850 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2024-12-09 17:02:42.142466 79.90% [NOTICE] mod_sofia.c:2610 Pre-Answer sofia/default/+46791400478@46.246.2.119! 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_channel.c:3585 (sofia/default/+46791400478@46.246.2.119) Callstate Change RINGING -> EARLY 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_ivr_originate.c:3909 Originate Resulted in Success: [sofia/default/+46704399539] Peer UUID: b75bf056-3d39-4333-aa4f-48e0312e59e8 2024-12-09 17:02:42.142466 79.90% [DEBUG] sofia.c:7493 Channel sofia/default/+46791400478@46.246.2.119 entering state [early][183] 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_ivr_bridge.c:1791 (sofia/default/+46704399539) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46704399539) Running State Change CS_EXCHANGE_MEDIA (Cur 130 Tot 134742) 2024-12-09 17:02:42.142466 79.90% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46704399539) State EXCHANGE_MEDIA 2024-12-09 17:02:42.142466 79.90% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA 2024-12-09 17:02:42.142466 79.90% [NOTICE] sofia.c:1065 Hangup sofia/default/+46731218752@46.246.2.119 [CS_EXECUTE] [NORMAL_CLEARING] 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46731218752@46.246.2.119] 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46731218752@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46703414795] 2024-12-09 17:02:42.162488 79.90% [NOTICE] switch_ivr_bridge.c:1027 Hangup sofia/default/+46703414795 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46703414795) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46703414795) Running State Change CS_HANGUP (Cur 130 Tot 134742) 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46703414795) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46703414795) State HANGUP 2024-12-09 17:02:42.162488 79.90% [DEBUG] mod_sofia.c:463 sofia/default/+46703414795 Overriding SIP cause 480 with 200 from the other leg 2024-12-09 17:02:42.162488 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46703414795 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46703414795 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46731218752@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_session.c:2979 sofia/default/+46731218752@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46731218752@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218752@46.246.2.119) Running State Change CS_HANGUP (Cur 130 Tot 134742) 2024-12-09 17:02:42.162488 79.90% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/default/+46703414795 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46703414795 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46703414795) State HANGUP going to sleep 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46731218752@46.246.2.119) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46703414795) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218752@46.246.2.119) State HANGUP 2024-12-09 17:02:42.162488 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46731218752@46.246.2.119 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46703414795) Running State Change CS_REPORTING (Cur 130 Tot 134742) 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46703414795) State REPORTING 2024-12-09 17:02:42.162488 79.90% [INFO] mod_json_cdr.c:272 Process [bee2b664-4cfb-4641-a10e-c85e8c0e0e36.cdr.json] 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46731218752@46.246.2.119 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218752@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46731218752@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218752@46.246.2.119) Running State Change CS_REPORTING (Cur 130 Tot 134742) 2024-12-09 17:02:42.162488 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218752@46.246.2.119) State REPORTING 2024-12-09 17:02:42.162488 79.90% [INFO] mod_json_cdr.c:272 Process [a5237b0d-aa69-4930-9e12-6359cdf8882d.cdr.json] 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46703414795 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46703414795) State REPORTING going to sleep 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46703414795) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_session.c:1744 Session 134588 (sofia/default/+46703414795) Locked, Waiting on external entities 2024-12-09 17:02:42.182474 79.90% [NOTICE] switch_core_session.c:1762 Session 134588 (sofia/default/+46703414795) Ended 2024-12-09 17:02:42.182474 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46703414795 [CS_DESTROY] 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46703414795) Running State Change CS_DESTROY (Cur 129 Tot 134742) 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46703414795) State DESTROY 2024-12-09 17:02:42.182474 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46703414795 SOFIA DESTROY 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46703414795 Standard DESTROY 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46703414795) State DESTROY going to sleep 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46731218752@46.246.2.119 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218752@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46731218752@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_session.c:1744 Session 134586 (sofia/default/+46731218752@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:42.182474 79.90% [NOTICE] switch_core_session.c:1762 Session 134586 (sofia/default/+46731218752@46.246.2.119) Ended 2024-12-09 17:02:42.182474 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46731218752@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46731218752@46.246.2.119) Running State Change CS_DESTROY (Cur 128 Tot 134742) 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218752@46.246.2.119) State DESTROY 2024-12-09 17:02:42.182474 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46731218752@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46731218752@46.246.2.119 Standard DESTROY 2024-12-09 17:02:42.182474 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218752@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:42.202475 79.90% [NOTICE] switch_channel.c:1142 New Channel sofia/default/+46791400609@46.246.2.119 [1fed5909-9aef-4363-a611-69c6494936f2] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400609@46.246.2.119) Running State Change CS_NEW (Cur 129 Tot 134743) 2024-12-09 17:02:42.202475 79.90% [INFO] sofia.c:10459 sofia/default/+46791400609@46.246.2.119 receiving invite from 46.246.2.119:5060 version: 1.10.10-release 64bit call-id: 0cf5daee77085186337224b64e0e6ac9@46.246.2.119:5060 2024-12-09 17:02:42.202475 79.90% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 46.246.2.119:0. 2024-12-09 17:02:42.202475 79.90% [DEBUG] sofia.c:10582 IP 46.246.2.119 Approved by acl "default[]". Access Granted. 2024-12-09 17:02:42.202475 79.90% [DEBUG] sofia.c:7493 Channel sofia/default/+46791400609@46.246.2.119 entering state [received][100] 2024-12-09 17:02:42.202475 79.90% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=root 427258605 427258605 IN IP4 46.246.2.119 s=Asterisk PBX 16.29.0 c=IN IP4 46.246.2.119 t=0 0 m=audio 62010 RTP/AVP 8 0 3 111 112 5 10 122 118 123 124 125 126 127 96 7 110 117 119 97 9 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:5 DVI4/8000 a=rtpmap:10 L16/8000 a=rtpmap:122 L16/12000 a=rtpmap:118 L16/16000 a=rtpmap:123 L16/24000 a=rtpmap:124 L16/32000 a=rtpmap:125 L16/44000 a=rtpmap:126 L16/48000 a=rtpmap:127 L16/96000 a=rtpmap:96 L16/192000 a=rtpmap:7 LPC/8000 a=rtpmap:110 speex/8000 a=rtpmap:117 speex/16000 a=rtpmap:119 speex/32000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=20 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:20 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G726-32:111:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G726-32:111:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:10:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:10:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:122:12000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:122:12000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:118:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:118:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:123:24000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:123:24000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:124:32000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:124:32000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:125:44000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:125:44000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:126:48000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:126:48000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:127:96000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:127:96000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:96:192000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [L16:96:192000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [LPC:7:8000:20:2400:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [LPC:7:8000:20:2400:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:117:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:117:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:119:32000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:119:32000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:97:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/+46791400609@46.246.2.119 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_codec.c:111 sofia/default/+46791400609@46.246.2.119 Original read codec set to PCMA:8 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_media.c:5856 sofia/default/+46791400609@46.246.2.119 Set 2833 dtmf send payload to 101 recv payload to 101 2024-12-09 17:02:42.202475 79.90% [DEBUG] sofia.c:7927 (sofia/default/+46791400609@46.246.2.119) State Change CS_NEW -> CS_INIT 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/+46791400609@46.246.2.119) State NEW 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400609@46.246.2.119) Running State Change CS_INIT (Cur 129 Tot 134743) 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/+46791400609@46.246.2.119) State INIT 2024-12-09 17:02:42.202475 79.90% [DEBUG] mod_sofia.c:97 sofia/default/+46791400609@46.246.2.119 SOFIA INIT 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/+46791400609@46.246.2.119 Standard INIT 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/+46791400609@46.246.2.119) State Change CS_INIT -> CS_ROUTING 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/+46791400609@46.246.2.119) State INIT going to sleep 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400609@46.246.2.119) Running State Change CS_ROUTING (Cur 129 Tot 134743) 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_channel.c:2399 (sofia/default/+46791400609@46.246.2.119) Callstate Change DOWN -> RINGING 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/+46791400609@46.246.2.119) State ROUTING 2024-12-09 17:02:42.202475 79.90% [DEBUG] mod_sofia.c:158 sofia/default/+46791400609@46.246.2.119 SOFIA ROUTING 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/+46791400609@46.246.2.119 Standard ROUTING 2024-12-09 17:02:42.202475 79.90% [INFO] mod_dialplan_xml.c:639 Processing +46791400609 <+46791400609>->+46760182569 in context default 2024-12-09 17:02:42.202475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code','vanity_number') 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-12-09 17:02:42.202475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : +46760182569 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '+46760182569' AND status=0 limit 1 2024-12-09 17:02:42.202475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER+46791400609 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '+46760182569' limit 1 2024-12-09 17:02:42.202475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : +46791400609 / +46791400609 2024-12-09 17:02:42.202475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 2 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 2 AND status=0 limit 1 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+46/46/*/*".....+46760182569 2024-12-09 17:02:42.202475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : +46760182569 2024-12-09 17:02:42.202475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 46760182569 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number = 46760182569 ORDER BY LENGTH(A.number) DESC LIMIT 1 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-12-09 17:02:42.202475 79.90% [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,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="+46760182569" limit 1 2024-12-09 17:02:42.202475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("46.246.2.119") 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 "+46760182569" LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1 2024-12-09 17:02:42.202475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 3261792583] 2024-12-09 17:02:42.202475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "3261792583" OR id = "3261792583") AND deleted = 0 limit 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^+46760182569.*' OR blocked_patterns = '^+4676018256.*' OR blocked_patterns = '^+467601825.*' OR blocked_patterns = '^+46760182.*' OR blocked_patterns = '^+4676018.*' OR blocked_patterns = '^+467601.*' OR blocked_patterns = '^+46760.*' OR blocked_patterns = '^+4676.*' OR blocked_patterns = '^+467.*' OR blocked_patterns = '^+46.*' OR blocked_patterns = '^+4.*' OR blocked_patterns = '^+.*' OR blocked_patterns ='--') AND accountid = 7 limit 1 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 2 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 2 AND status=0 limit 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+46/46/*/*".....+46760182569 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : +46760182569 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 46760182569 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number = 46760182569 AND (accountid = 0 OR status = 1) LIMIT 1 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 62 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 62 AND status=0 limit 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+46/46/*/*,0/46/*/*".....+46760182569 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+46/46/*/*,0/46/*/*".....+46760182569 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : +46760182569 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 46760182569 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+46/46/*/*,0/46/*/*".....46760182569 2024-12-09 17:02:42.222475 79.90% [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 P.is_terminated = 0 AND (patterns = '^46760182569.*' OR patterns = '^4676018256.*' OR patterns = '^467601825.*' OR patterns = '^46760182.*' OR patterns = '^4676018.*' OR patterns = '^467601.*' OR patterns = '^46760.*' OR patterns = '^4676.*' OR patterns = '^467.*' OR patterns = '^46.*' OR patterns = '^4.*' OR patterns ='--') AND accountid = 7 ORDER BY LENGTH(PKGPTR.patterns) DESC 2024-12-09 17:02:42.222475 79.90% [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 ="46760182569" AND A.accountid = '7' limit 1 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 46760182569 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 2 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 2 AND status=0 limit 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+46/46/*/*".....46760182569 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number = 46760182569 ORDER BY LENGTH(A.number) DESC LIMIT 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-12-09 17:02:42.222475 79.90% [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,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="46760182569" limit 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '46760182569' OR number = '4676018256' OR number = '467601825' OR number = '46760182' OR number = '4676018' OR number = '467601' OR number = '46760' OR number = '4676' OR number = '467' OR number = '46' OR number = '4' OR number ='--') OR (number = '46760182569*' OR number = '4676018256*' OR number = '467601825*' OR number = '46760182*' OR number = '4676018*' OR number = '467601*' OR number = '46760*' OR number = '4676*' OR number = '467*' OR number = '46*' OR number = '4*' OR number ='--') OR (number = '+46791400609' OR number = '+4679140060' OR number = '+467914006' OR number = '+46791400' OR number = '+4679140' OR number = '+467914' OR number = '+46791' OR number = '+4679' OR number = '+467' OR number = '+46' OR number = '+4' OR number = '+' OR number ='--') OR (number = '+46791400609*' OR number = '+4679140060*' OR number = '+467914006*' OR number = '+46791400*' OR number = '+4679140*' OR number = '+467914*' OR number = '+46791*' OR number = '+4679*' OR number = '+467*' OR number = '+46*' OR number = '+4*' OR number = '+*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '46760182569' OR routing_prefix = '4676018256' OR routing_prefix = '467601825' OR routing_prefix = '46760182' OR routing_prefix = '4676018' OR routing_prefix = '467601' OR routing_prefix = '46760' OR routing_prefix = '4676' OR routing_prefix = '467' OR routing_prefix = '46' OR routing_prefix = '4' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information =================== 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 7 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 3261792583 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 997.334 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid] 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 7 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ======================================================== 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/+/*/*,46/+46/*/*,0/+46/*/*".....+46791400609 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 7 AND status = 0 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 7 AND status = 0 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query HP :SELECT * FROM routes WHERE (pattern = '^46760182569.*' OR pattern = '^4676018256.*' OR pattern = '^467601825.*' OR pattern = '^46760182.*' OR pattern = '^4676018.*' OR pattern = '^467601.*' OR pattern = '^46760.*' OR pattern = '^4676.*' OR pattern = '^467.*' OR pattern = '^46.*' OR pattern = '^4.*' OR pattern ='--') AND (caller_id_pattern = '^+46791400609.*' OR caller_id_pattern = '^+4679140060.*' OR caller_id_pattern = '^+467914006.*' OR caller_id_pattern = '^+46791400.*' OR caller_id_pattern = '^+4679140.*' OR caller_id_pattern = '^+467914.*' OR caller_id_pattern = '^+46791.*' OR caller_id_pattern = '^+4679.*' OR caller_id_pattern = '^+467.*' OR caller_id_pattern = '^+46.*' OR caller_id_pattern = '^+4.*' OR caller_id_pattern = '^+.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 7 OR accountid=7) ORDER BY accountid DESC,cost ASC,effective_date DESC LIMIT 1 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Direction : outbound 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information =================== 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 146523 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.0000000000 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.0000000000 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : SWEDEN 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 184 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 7 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID: 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type: 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================ 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your997.334 balance Accountid 7 !!! 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length :: 100 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes 2024-12-09 17:02:42.222475 79.90% [WARNING] switch_cpp.cpp:1465 [ASTPP] [DIALPLAN] rate_carrier_id: 2024-12-09 17:02:42.222475 79.90% [WARNING] switch_cpp.cpp:1465 [ASTPP] [DIALPLAN] User Rate country_id: 184 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id : 2024-12-09 17:02:42.222475 79.90% [WARNING] switch_cpp.cpp:1465 [ASTPP] or_country_id :184 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS from routing] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=7 ORDER by id asc 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.sip_cid_type,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 = '^46760182569.*' OR pattern = '^4676018256.*' OR pattern = '^467601825.*' OR pattern = '^46760182.*' OR pattern = '^4676018.*' OR pattern = '^467601.*' OR pattern = '^46760.*' OR pattern = '^4676.*' OR pattern = '^467.*' OR pattern = '^46.*' OR pattern = '^4.*' OR pattern ='--') AND TR.status = 0 AND TR.trunk_id IN (5) AND TR.effective_date <= UTC_TIMESTAMP() ORDER by TR.cost ASC,TR.precedence ASC, TK.precedence,TR.effective_date DESC 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information =================== 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 5 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^46.* 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : sweden 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.0000000000 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^46.* 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip : 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend : 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 5 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : GC1 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable : 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway : 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 763 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES======================= 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 7 AND status=0 LIMIT 1 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Maxlength : 100 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_sip_device_info_for_did_pstn_call] Query :SELECT username FROM sip_devices WHERE username = '+46791400609' and status = 0 limit 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-12-09 17:02:42.222475 79.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 7 AND status = 0 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 763) AND status=0 limit 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"*/+/*/*".....46760182569 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 46760182569 2024-12-09 17:02:42.222475 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : +46760182569 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 5) AND status=0 limit 1 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-12-09 17:02:42.222475 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [sip_codec_for_outbound] Query :SELECT sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="+46791400609" limit 1 2024-12-09 17:02:42.242474 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 2 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 2 AND status=0 limit 1 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+46/46/*/*".....+46760182569 2024-12-09 17:02:42.242474 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : +46760182569 2024-12-09 17:02:42.242474 79.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 46760182569 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number = 46760182569 ORDER BY LENGTH(A.number) DESC LIMIT 1 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
Dialplan: sofia/default/+46791400609@46.246.2.119 parsing [default->46760182569] continue=false Dialplan: sofia/default/+46791400609@46.246.2.119 Regex (PASS) [46760182569] destination_number(+46760182569) =~ /46760182569/ break=on-false Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(effective_destination_number=46760182569) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(execute_on_answer_1=sched_hangup +6000 normal_clearing) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(notify_flag=0) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(notify_credit_limit=5.00000) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(type=3) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(callstart=2024-12-09 17:02:42) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(hangup_after_bridge=true) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(continue_on_fail=TRUE) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(account_id=7) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(parent_id=0) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(entity_id=3) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(call_processed=internal) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(call_direction=outbound) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(accountname=MINITEL_CC) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(origination_rates=ID:146523|CODE:^46.*|DESTINATION:SWEDEN|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0000000000|INC:1|INITIALBLOCK:1|RATEGROUP:7|MARKUP:0|CI:184|ACCID:7) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(original_caller_id_name=+46791400609) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(original_caller_id_number=+46791400609) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(effective_caller_id_name=+46791400609) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(effective_caller_id_number=+46791400609) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(calltype=STANDARD) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(termination_rates=ID:5|CODE:^46.*|DESTINATION:sweden|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0000000000|INC:0|INITIALBLOCK:0|TRUNK:5|PROVIDER:763) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(trunk_id=5) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(provider_id=763) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(rate_flag=1) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(daily_call_credit_limit=0.00000) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(monthly_call_credit_limit=0.00000) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(daily_call_used_limit=0.00000) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(monthly_call_used_limit=0.00000) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(quality_trunk_seq=5) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(force_trunk_flag=0) Dialplan: sofia/default/+46791400609@46.246.2.119 Action export(presence_data=trunk_id=5) Dialplan: sofia/default/+46791400609@46.246.2.119 Action set(intcall=0) Dialplan: sofia/default/+46791400609@46.246.2.119 Action export(presence_id=+46760182569) Dialplan: sofia/default/+46791400609@46.246.2.119 Action export(presence_data=x|||miniTel Incoming(3261792583)|||^46.* // SWEDEN // 0.0000000000|||GC // ^46.* // sweden // 0.0000000000 // trunk_id=5|||STD|||46.246.2.119) Dialplan: sofia/default/+46791400609@46.246.2.119 Action bridge({sip_cid_type=none}[leg_timeout=0]sofia/gateway/GC1/+46760182569) 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:281 (sofia/default/+46791400609@46.246.2.119) State Change CS_ROUTING -> CS_EXECUTE 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/+46791400609@46.246.2.119) State ROUTING going to sleep 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400609@46.246.2.119) Running State Change CS_EXECUTE (Cur 129 Tot 134743) 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46791400609@46.246.2.119) State EXECUTE 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_sofia.c:213 sofia/default/+46791400609@46.246.2.119 SOFIA EXECUTE 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:323 sofia/default/+46791400609@46.246.2.119 Standard EXECUTE EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(effective_destination_number=46760182569) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [effective_destination_number]=[46760182569] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(execute_on_answer_1=sched_hangup +6000 normal_clearing) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [execute_on_answer_1]=[sched_hangup +6000 normal_clearing] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(notify_flag=0) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [notify_flag]=[0] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(notify_credit_limit=5.00000) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [notify_credit_limit]=[5.00000] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(type=3) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [type]=[3] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(callstart=2024-12-09 17:02:42) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [callstart]=[2024-12-09 17:02:42] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(hangup_after_bridge=true) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [hangup_after_bridge]=[true] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(continue_on_fail=TRUE) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [continue_on_fail]=[TRUE] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(account_id=7) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [account_id]=[7] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(parent_id=0) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [parent_id]=[0] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(entity_id=3) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [entity_id]=[3] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(call_processed=internal) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [call_processed]=[internal] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(call_direction=outbound) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [call_direction]=[outbound] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(accountname=MINITEL_CC) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [accountname]=[MINITEL_CC] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(origination_rates=ID:146523|CODE:^46.*|DESTINATION:SWEDEN|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0000000000|INC:1|INITIALBLOCK:1|RATEGROUP:7|MARKUP:0|CI:184|ACCID:7) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [origination_rates]=[ID:146523|CODE:^46.*|DESTINATION:SWEDEN|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0000000000|INC:1|INITIALBLOCK:1|RATEGROUP:7|MARKUP:0|CI:184|ACCID:7] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(original_caller_id_name=+46791400609) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [original_caller_id_name]=[+46791400609] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(original_caller_id_number=+46791400609) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [original_caller_id_number]=[+46791400609] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(effective_caller_id_name=+46791400609) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [effective_caller_id_name]=[+46791400609] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(effective_caller_id_number=+46791400609) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [effective_caller_id_number]=[+46791400609] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(calltype=STANDARD) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [calltype]=[STANDARD] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(termination_rates=ID:5|CODE:^46.*|DESTINATION:sweden|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0000000000|INC:0|INITIALBLOCK:0|TRUNK:5|PROVIDER:763) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [termination_rates]=[ID:5|CODE:^46.*|DESTINATION:sweden|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0000000000|INC:0|INITIALBLOCK:0|TRUNK:5|PROVIDER:763] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(trunk_id=5) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [trunk_id]=[5] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(provider_id=763) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [provider_id]=[763] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(rate_flag=1) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [rate_flag]=[1] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(daily_call_credit_limit=0.00000) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [daily_call_credit_limit]=[0.00000] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(monthly_call_credit_limit=0.00000) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [monthly_call_credit_limit]=[0.00000] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(daily_call_used_limit=0.00000) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [daily_call_used_limit]=[0.00000] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(monthly_call_used_limit=0.00000) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [monthly_call_used_limit]=[0.00000] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(quality_trunk_seq=5) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [quality_trunk_seq]=[5] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(force_trunk_flag=0) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [force_trunk_flag]=[0] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 export(presence_data=trunk_id=5) 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[trunk_id=5] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 set(intcall=0) 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/+46791400609@46.246.2.119 [intcall]=[0] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 export(presence_id=+46760182569) 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_id]=[+46760182569] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 export(presence_data=x|||miniTel Incoming(3261792583)|||^46.* // SWEDEN // 0.0000000000|||GC // ^46.* // sweden // 0.0000000000 // trunk_id=5|||STD|||46.246.2.119) 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[x|||miniTel Incoming(3261792583)|||^46.* // SWEDEN // 0.0000000000|||GC // ^46.* // sweden // 0.0000000000 // trunk_id=5|||STD|||46.246.2.119] EXECUTE [depth=0] sofia/default/+46791400609@46.246.2.119 bridge({sip_cid_type=none}[leg_timeout=0]sofia/gateway/GC1/+46760182569) 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_channel.c:1288 sofia/default/+46791400609@46.246.2.119 EXPORTING[export_vars] [presence_data]=[x|||miniTel Incoming(3261792583)|||^46.* // SWEDEN // 0.0000000000|||GC // ^46.* // sweden // 0.0000000000 // trunk_id=5|||STD|||46.246.2.119] to event 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_channel.c:1288 sofia/default/+46791400609@46.246.2.119 EXPORTING[export_vars] [presence_id]=[+46760182569] to event 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_channel.c:1288 sofia/default/+46791400609@46.246.2.119 EXPORTING[export_vars] [presence_data]=[x|||miniTel Incoming(3261792583)|||^46.* // SWEDEN // 0.0000000000|||GC // ^46.* // sweden // 0.0000000000 // trunk_id=5|||STD|||46.246.2.119] to event 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_ivr_originate.c:2859 Parsing session specific variables 2024-12-09 17:02:42.242474 79.90% [NOTICE] switch_channel.c:1142 New Channel sofia/default/+46760182569 [db56e155-3397-43ec-b555-01744907c31f] 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_sofia.c:5110 (sofia/default/+46760182569) State Change CS_NEW -> CS_INIT 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46760182569) Running State Change CS_INIT (Cur 130 Tot 134744) 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/+46760182569) State INIT 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_sofia.c:97 sofia/default/+46760182569 SOFIA INIT 2024-12-09 17:02:42.242474 79.90% [INFO] sofia_glue.c:1659 sofia/default/+46760182569 sending invite call-id: (null) 2024-12-09 17:02:42.242474 79.90% [DEBUG] sofia_glue.c:1662 sofia/default/+46760182569 sending invite version: 1.10.10-release 64bit Local SDP: v=0 o=FreeSWITCH 1733738624 1733738625 IN IP4 172.232.155.199 s=FreeSWITCH c=IN IP4 172.232.155.199 t=0 0 m=audio 25138 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/+46760182569 Standard INIT 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/+46760182569) State Change CS_INIT -> CS_ROUTING 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/+46760182569) State INIT going to sleep 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46760182569) Running State Change CS_ROUTING (Cur 130 Tot 134744) 2024-12-09 17:02:42.242474 79.90% [DEBUG] sofia.c:7493 Channel sofia/default/+46760182569 entering state [calling][0] 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/+46760182569) State ROUTING 2024-12-09 17:02:42.242474 79.90% [DEBUG] mod_sofia.c:158 sofia/default/+46760182569 SOFIA ROUTING 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_ivr_originate.c:67 (sofia/default/+46760182569) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/+46760182569) State ROUTING going to sleep 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46760182569) Running State Change CS_CONSUME_MEDIA (Cur 130 Tot 134744) 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/+46760182569) State CONSUME_MEDIA 2024-12-09 17:02:42.242474 79.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/+46760182569) State CONSUME_MEDIA going to sleep 2024-12-09 17:02:42.262469 79.90% [DEBUG] sofia.c:7493 Channel sofia/default/+46704399539 entering state [proceeding][180] 2024-12-09 17:02:42.262469 79.90% [NOTICE] sofia.c:7604 Ring-Ready sofia/default/+46704399539! 2024-12-09 17:02:42.262469 79.90% [DEBUG] switch_channel.c:3513 (sofia/default/+46704399539) Callstate Change EARLY -> RINGING 2024-12-09 17:02:42.402472 79.90% [DEBUG] sofia.c:7493 Channel sofia/default/+46791400478@46.246.2.119 entering state [terminated][487] 2024-12-09 17:02:42.402472 79.90% [NOTICE] sofia.c:8735 Hangup sofia/default/+46791400478@46.246.2.119 [CS_EXECUTE] [ORIGINATOR_CANCEL] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:825 sofia/default/+46791400478@46.246.2.119 ending bridge by request from read function 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46791400478@46.246.2.119] 2024-12-09 17:02:42.402472 79.90% [DEBUG] sofia.c:7493 Channel sofia/default/+46791400478@46.246.2.119 entering state [terminated][487] 2024-12-09 17:02:42.402472 79.90% [NOTICE] sofia.c:8735 Hangup sofia/default/+46791400478@46.246.2.119 [CS_EXECUTE] [ORIGINATOR_CANCEL] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:825 sofia/default/+46791400478@46.246.2.119 ending bridge by request from read function 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46791400478@46.246.2.119] 2024-12-09 17:02:42.402472 79.90% [DEBUG] sofia.c:7493 Channel sofia/default/+46791400478@46.246.2.119 entering state [terminated][487] 2024-12-09 17:02:42.402472 79.90% [NOTICE] sofia.c:8735 Hangup sofia/default/+46791400478@46.246.2.119 [CS_EXECUTE] [ORIGINATOR_CANCEL] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:825 sofia/default/+46791400478@46.246.2.119 ending bridge by request from read function 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46791400478@46.246.2.119] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46791400478@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46735404473] 2024-12-09 17:02:42.402472 79.90% [NOTICE] switch_ivr_bridge.c:1023 Hangup sofia/default/+46735404473 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46735404473) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46735404473) Running State Change CS_HANGUP (Cur 130 Tot 134744) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46735404473) Callstate Change RINGING -> HANGUP 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46735404473) State HANGUP 2024-12-09 17:02:42.402472 79.90% [DEBUG] mod_sofia.c:463 sofia/default/+46735404473 Overriding SIP cause 487 with 487 from the other leg 2024-12-09 17:02:42.402472 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46735404473 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46791400478@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46735404473 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46791400478@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_session.c:2979 sofia/default/+46791400478@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46791400478@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400478@46.246.2.119) Running State Change CS_HANGUP (Cur 130 Tot 134744) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46706168148] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46791400478@46.246.2.119) Callstate Change EARLY -> HANGUP 2024-12-09 17:02:42.402472 79.90% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/default/+46735404473 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46791400478@46.246.2.119) State HANGUP 2024-12-09 17:02:42.402472 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46791400478@46.246.2.119 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46735404473 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46735404473) State HANGUP going to sleep 2024-12-09 17:02:42.402472 79.90% [NOTICE] switch_ivr_bridge.c:1023 Hangup sofia/default/+46706168148 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46735404473) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46735404473) Running State Change CS_REPORTING (Cur 130 Tot 134744) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46706168148) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46706168148) Running State Change CS_HANGUP (Cur 130 Tot 134744) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46735404473) State REPORTING 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46706168148) Callstate Change RINGING -> HANGUP 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46706168148) State HANGUP 2024-12-09 17:02:42.402472 79.90% [DEBUG] mod_sofia.c:463 sofia/default/+46706168148 Overriding SIP cause 487 with 487 from the other leg 2024-12-09 17:02:42.402472 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46706168148 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.402472 79.90% [INFO] mod_json_cdr.c:272 Process [f7dc3855-09b4-4025-81be-f78b2eee2ea0.cdr.json] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46791400478@46.246.2.119 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46791400478@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46791400478@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400478@46.246.2.119) Running State Change CS_REPORTING (Cur 130 Tot 134744) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46791400478@46.246.2.119) State REPORTING 2024-12-09 17:02:42.402472 79.90% [INFO] mod_json_cdr.c:272 Process [0352432c-be82-4d41-9538-69fd62ba3974.cdr.json] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46706168148 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46791400478@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.402472 79.90% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/default/+46706168148 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46706168148 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46706168148) State HANGUP going to sleep 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46706168148) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_session.c:2979 sofia/default/+46791400478@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46791400478@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400478@46.246.2.119) Running State Change CS_HANGUP (Cur 130 Tot 134744) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46706168148) Running State Change CS_REPORTING (Cur 130 Tot 134744) 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46706168148) State REPORTING 2024-12-09 17:02:42.402472 79.90% [INFO] mod_json_cdr.c:272 Process [000b1439-3674-4cb0-840f-bfe3f12fcbce.cdr.json] 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46791400478@46.246.2.119) Callstate Change EARLY -> HANGUP 2024-12-09 17:02:42.402472 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46791400478@46.246.2.119) State HANGUP 2024-12-09 17:02:42.402472 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46791400478@46.246.2.119 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46791400478@46.246.2.119 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46791400478@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46791400478@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400478@46.246.2.119) Running State Change CS_REPORTING (Cur 130 Tot 134744) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46791400478@46.246.2.119) State REPORTING 2024-12-09 17:02:42.422473 79.90% [INFO] mod_json_cdr.c:272 Process [228b495f-9fe0-41b7-b908-b5c75e021d5f.cdr.json] 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46791400478@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46703697975] 2024-12-09 17:02:42.422473 79.90% [NOTICE] switch_ivr_bridge.c:1023 Hangup sofia/default/+46703697975 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL] 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46703697975) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46703697975) Running State Change CS_HANGUP (Cur 130 Tot 134744) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46703697975) Callstate Change RINGING -> HANGUP 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46703697975) State HANGUP 2024-12-09 17:02:42.422473 79.90% [DEBUG] mod_sofia.c:463 sofia/default/+46703697975 Overriding SIP cause 487 with 487 from the other leg 2024-12-09 17:02:42.422473 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46703697975 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46703697975 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46791400478@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_session.c:2979 sofia/default/+46791400478@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46791400478@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400478@46.246.2.119) Running State Change CS_HANGUP (Cur 130 Tot 134744) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46791400478@46.246.2.119) Callstate Change EARLY -> HANGUP 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46791400478@46.246.2.119) State HANGUP 2024-12-09 17:02:42.422473 79.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46791400478@46.246.2.119 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.422473 79.90% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/default/+46703697975 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46703697975 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46703697975) State HANGUP going to sleep 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46703697975) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46703697975) Running State Change CS_REPORTING (Cur 130 Tot 134744) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46703697975) State REPORTING 2024-12-09 17:02:42.422473 79.90% [INFO] mod_json_cdr.c:272 Process [3613ff64-4924-4012-9c70-8fabe3229345.cdr.json] 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46791400478@46.246.2.119 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46791400478@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46791400478@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46791400478@46.246.2.119) Running State Change CS_REPORTING (Cur 130 Tot 134744) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46791400478@46.246.2.119) State REPORTING 2024-12-09 17:02:42.422473 79.90% [INFO] mod_json_cdr.c:272 Process [3f8d1bca-b3f8-4f65-988f-8db5bc0201ab.cdr.json] 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46706168148 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46706168148) State REPORTING going to sleep 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46706168148) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_session.c:1744 Session 134622 (sofia/default/+46706168148) Locked, Waiting on external entities 2024-12-09 17:02:42.422473 79.90% [NOTICE] switch_core_session.c:1762 Session 134622 (sofia/default/+46706168148) Ended 2024-12-09 17:02:42.422473 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46706168148 [CS_DESTROY] 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46706168148) Running State Change CS_DESTROY (Cur 129 Tot 134744) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46706168148) State DESTROY 2024-12-09 17:02:42.422473 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46706168148 SOFIA DESTROY 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46706168148 Standard DESTROY 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46706168148) State DESTROY going to sleep 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46703697975 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46703697975) State REPORTING going to sleep 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46703697975) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_session.c:1744 Session 134624 (sofia/default/+46703697975) Locked, Waiting on external entities 2024-12-09 17:02:42.422473 79.90% [NOTICE] switch_core_session.c:1762 Session 134624 (sofia/default/+46703697975) Ended 2024-12-09 17:02:42.422473 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46703697975 [CS_DESTROY] 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46703697975) Running State Change CS_DESTROY (Cur 128 Tot 134744) 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46703697975) State DESTROY 2024-12-09 17:02:42.422473 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46703697975 SOFIA DESTROY 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46703697975 Standard DESTROY 2024-12-09 17:02:42.422473 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46703697975) State DESTROY going to sleep 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46735404473 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46735404473) State REPORTING going to sleep 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46735404473) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_session.c:1744 Session 134626 (sofia/default/+46735404473) Locked, Waiting on external entities 2024-12-09 17:02:42.442470 79.90% [NOTICE] switch_core_session.c:1762 Session 134626 (sofia/default/+46735404473) Ended 2024-12-09 17:02:42.442470 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46735404473 [CS_DESTROY] 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46735404473) Running State Change CS_DESTROY (Cur 127 Tot 134744) 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46735404473) State DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46735404473 SOFIA DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46735404473 Standard DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46735404473) State DESTROY going to sleep 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46791400478@46.246.2.119 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46791400478@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46791400478@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_session.c:1744 Session 134625 (sofia/default/+46791400478@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:42.442470 79.90% [NOTICE] switch_core_session.c:1762 Session 134625 (sofia/default/+46791400478@46.246.2.119) Ended 2024-12-09 17:02:42.442470 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46791400478@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46791400478@46.246.2.119) Running State Change CS_DESTROY (Cur 126 Tot 134744) 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46791400478@46.246.2.119) State DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46791400478@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46791400478@46.246.2.119 Standard DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46791400478@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46791400478@46.246.2.119 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46791400478@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46791400478@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_session.c:1744 Session 134623 (sofia/default/+46791400478@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:42.442470 79.90% [NOTICE] switch_core_session.c:1762 Session 134623 (sofia/default/+46791400478@46.246.2.119) Ended 2024-12-09 17:02:42.442470 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46791400478@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46791400478@46.246.2.119) Running State Change CS_DESTROY (Cur 125 Tot 134744) 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46791400478@46.246.2.119) State DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46791400478@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46791400478@46.246.2.119 Standard DESTROY 2024-12-09 17:02:42.442470 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46791400478@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:42.462485 79.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46791400478@46.246.2.119 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.462485 79.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46791400478@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:42.462485 79.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46791400478@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.462485 79.90% [DEBUG] switch_core_session.c:1744 Session 134621 (sofia/default/+46791400478@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:42.462485 79.90% [NOTICE] switch_core_session.c:1762 Session 134621 (sofia/default/+46791400478@46.246.2.119) Ended 2024-12-09 17:02:42.462485 79.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46791400478@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:42.462485 79.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46791400478@46.246.2.119) Running State Change CS_DESTROY (Cur 124 Tot 134744) 2024-12-09 17:02:42.462485 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46791400478@46.246.2.119) State DESTROY 2024-12-09 17:02:42.462485 79.90% [DEBUG] mod_sofia.c:380 sofia/default/+46791400478@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:42.462485 79.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46791400478@46.246.2.119 Standard DESTROY 2024-12-09 17:02:42.462485 79.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46791400478@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:42.642472 79.90% [DEBUG] switch_scheduler.c:147 Deleting task 39923 switch_ivr_schedule_hangup (a5237b0d-aa69-4930-9e12-6359cdf8882d) 2024-12-09 17:02:42.822471 79.73% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218962@46.246.2.119 entering state [terminated][487] 2024-12-09 17:02:42.822471 79.73% [NOTICE] sofia.c:8735 Hangup sofia/default/+46731218962@46.246.2.119 [CS_EXECUTE] [ORIGINATOR_CANCEL] 2024-12-09 17:02:42.842470 79.73% [NOTICE] switch_ivr_originate.c:3816 Hangup sofia/default/+46767786628 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL] 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_ivr_originate.c:4041 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL] 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46767786628) Running State Change CS_HANGUP (Cur 124 Tot 134744) 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46767786628) Callstate Change RINGING -> HANGUP 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46767786628) State HANGUP 2024-12-09 17:02:42.842470 79.73% [DEBUG] mod_sofia.c:463 sofia/default/+46767786628 Overriding SIP cause 487 with 487 from the other leg 2024-12-09 17:02:42.842470 79.73% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46767786628 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.842470 79.73% [INFO] mod_dptools.c:3635 Originate Failed. Cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_session.c:2979 sofia/default/+46731218962@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46731218962@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_HANGUP (Cur 124 Tot 134744) 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46731218962@46.246.2.119) Callstate Change RINGING -> HANGUP 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP 2024-12-09 17:02:42.842470 79.73% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46731218962@46.246.2.119 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.842470 79.73% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/default/+46767786628 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46767786628 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46767786628) State HANGUP going to sleep 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46767786628) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46767786628) Running State Change CS_REPORTING (Cur 124 Tot 134744) 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46767786628) State REPORTING 2024-12-09 17:02:42.842470 79.73% [INFO] mod_json_cdr.c:272 Process [81469a82-ff73-4068-b008-6ff176007e92.cdr.json] 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46731218962@46.246.2.119 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46731218962@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_REPORTING (Cur 124 Tot 134744) 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING 2024-12-09 17:02:42.842470 79.73% [INFO] mod_json_cdr.c:272 Process [786a5ab5-99e4-4a13-8794-d5df834addae.cdr.json] 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46767786628 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46767786628) State REPORTING going to sleep 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46767786628) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_session.c:1744 Session 134612 (sofia/default/+46767786628) Locked, Waiting on external entities 2024-12-09 17:02:42.842470 79.73% [NOTICE] switch_core_session.c:1762 Session 134612 (sofia/default/+46767786628) Ended 2024-12-09 17:02:42.842470 79.73% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46767786628 [CS_DESTROY] 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46767786628) Running State Change CS_DESTROY (Cur 123 Tot 134744) 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46767786628) State DESTROY 2024-12-09 17:02:42.842470 79.73% [DEBUG] mod_sofia.c:380 sofia/default/+46767786628 SOFIA DESTROY 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46767786628 Standard DESTROY 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46767786628) State DESTROY going to sleep 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46731218962@46.246.2.119 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46731218962@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_session.c:1744 Session 134609 (sofia/default/+46731218962@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:42.842470 79.73% [NOTICE] switch_core_session.c:1762 Session 134609 (sofia/default/+46731218962@46.246.2.119) Ended 2024-12-09 17:02:42.842470 79.73% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46731218962@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_DESTROY (Cur 122 Tot 134744) 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY 2024-12-09 17:02:42.842470 79.73% [DEBUG] mod_sofia.c:380 sofia/default/+46731218962@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46731218962@46.246.2.119 Standard DESTROY 2024-12-09 17:02:42.842470 79.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:42.862470 79.73% [NOTICE] sofia.c:1065 Hangup sofia/default/+46731218962@46.246.2.119 [CS_EXECUTE] [NORMAL_CLEARING] 2024-12-09 17:02:42.862470 79.73% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46731218962@46.246.2.119] 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46731218962@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46705359757] 2024-12-09 17:02:42.882470 79.73% [NOTICE] switch_ivr_bridge.c:1027 Hangup sofia/default/+46705359757 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46705359757) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46705359757) Running State Change CS_HANGUP (Cur 122 Tot 134744) 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46705359757) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46705359757) State HANGUP 2024-12-09 17:02:42.882470 79.73% [DEBUG] mod_sofia.c:463 sofia/default/+46705359757 Overriding SIP cause 480 with 200 from the other leg 2024-12-09 17:02:42.882470 79.73% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46705359757 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:42.882470 79.73% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/default/+46705359757 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46705359757 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46705359757) State HANGUP going to sleep 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46705359757 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46731218962@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46705359757) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46705359757) Running State Change CS_REPORTING (Cur 122 Tot 134744) 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_session.c:2979 sofia/default/+46731218962@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46731218962@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_HANGUP (Cur 122 Tot 134744) 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46731218962@46.246.2.119) Callstate Change ACTIVE -> HANGUP 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46705359757) State REPORTING 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP 2024-12-09 17:02:42.882470 79.73% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46731218962@46.246.2.119 hanging up, cause: NORMAL_CLEARING 2024-12-09 17:02:42.882470 79.73% [INFO] mod_json_cdr.c:272 Process [acc93987-9d30-4d3d-8804-0a9b0e9ac004.cdr.json] 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46731218962@46.246.2.119 Standard HANGUP, cause: NORMAL_CLEARING 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46731218962@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_REPORTING (Cur 122 Tot 134744) 2024-12-09 17:02:42.882470 79.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING 2024-12-09 17:02:42.882470 79.73% [INFO] mod_json_cdr.c:272 Process [fa7dc7da-7d72-46ad-98cb-12156ad4c132.cdr.json] 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46705359757 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46705359757) State REPORTING going to sleep 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46705359757) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_session.c:1744 Session 134644 (sofia/default/+46705359757) Locked, Waiting on external entities 2024-12-09 17:02:42.902470 79.73% [NOTICE] switch_core_session.c:1762 Session 134644 (sofia/default/+46705359757) Ended 2024-12-09 17:02:42.902470 79.73% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46705359757 [CS_DESTROY] 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46705359757) Running State Change CS_DESTROY (Cur 121 Tot 134744) 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46705359757) State DESTROY 2024-12-09 17:02:42.902470 79.73% [DEBUG] mod_sofia.c:380 sofia/default/+46705359757 SOFIA DESTROY 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46705359757 Standard DESTROY 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46705359757) State DESTROY going to sleep 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46731218962@46.246.2.119 Standard REPORTING, cause: NORMAL_CLEARING 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46731218962@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_session.c:1744 Session 134643 (sofia/default/+46731218962@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:42.902470 79.73% [NOTICE] switch_core_session.c:1762 Session 134643 (sofia/default/+46731218962@46.246.2.119) Ended 2024-12-09 17:02:42.902470 79.73% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46731218962@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_DESTROY (Cur 120 Tot 134744) 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY 2024-12-09 17:02:42.902470 79.73% [DEBUG] mod_sofia.c:380 sofia/default/+46731218962@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46731218962@46.246.2.119 Standard DESTROY 2024-12-09 17:02:42.902470 79.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY going to sleep 2024-12-09 17:02:42.942470 79.73% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-12-09 17:02:43.142472 79.73% [DEBUG] switch_scheduler.c:147 Deleting task 39929 switch_ivr_schedule_hangup (fa7dc7da-7d72-46ad-98cb-12156ad4c132) 2024-12-09 17:02:43.862470 79.93% [DEBUG] sofia.c:7493 Channel sofia/default/+46731218962@46.246.2.119 entering state [terminated][487] 2024-12-09 17:02:43.862470 79.93% [NOTICE] sofia.c:8735 Hangup sofia/default/+46731218962@46.246.2.119 [CS_EXECUTE] [ORIGINATOR_CANCEL] 2024-12-09 17:02:43.862470 79.93% [DEBUG] switch_ivr_bridge.c:825 sofia/default/+46731218962@46.246.2.119 ending bridge by request from read function 2024-12-09 17:02:43.862470 79.93% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46731218962@46.246.2.119] 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_ivr_bridge.c:819 sofia/default/+46731218962@46.246.2.119 ending bridge by request from write function 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/+46735527281] 2024-12-09 17:02:43.882470 79.93% [NOTICE] switch_ivr_bridge.c:1023 Hangup sofia/default/+46735527281 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL] 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:650 (sofia/default/+46735527281) State EXCHANGE_MEDIA going to sleep 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46735527281) Running State Change CS_HANGUP (Cur 120 Tot 134744) 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46735527281) Callstate Change RINGING -> HANGUP 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46735527281) State HANGUP 2024-12-09 17:02:43.882470 79.93% [DEBUG] mod_sofia.c:463 sofia/default/+46735527281 Overriding SIP cause 487 with 487 from the other leg 2024-12-09 17:02:43.882470 79.93% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46735527281 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/+46735527281 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/+46731218962@46.246.2.119 skip receive message [UNBRIDGE] (channel is hungup already) 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_session.c:2979 sofia/default/+46731218962@46.246.2.119 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+46731218962@46.246.2.119) State EXECUTE going to sleep 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_HANGUP (Cur 120 Tot 134744) 2024-12-09 17:02:43.882470 79.93% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/default/+46735527281 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46735527281 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46735527281) State HANGUP going to sleep 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+46731218962@46.246.2.119) Callstate Change EARLY -> HANGUP 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46735527281) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP 2024-12-09 17:02:43.882470 79.93% [DEBUG] mod_sofia.c:469 Channel sofia/default/+46731218962@46.246.2.119 hanging up, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46735527281) Running State Change CS_REPORTING (Cur 120 Tot 134744) 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46735527281) State REPORTING 2024-12-09 17:02:43.882470 79.93% [INFO] mod_json_cdr.c:272 Process [81485bea-ecb5-44de-ab40-86d9ea7852b4.cdr.json] 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:59 sofia/default/+46731218962@46.246.2.119 Standard HANGUP, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+46731218962@46.246.2.119) State HANGUP going to sleep 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+46731218962@46.246.2.119) State Change CS_HANGUP -> CS_REPORTING 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_REPORTING (Cur 120 Tot 134744) 2024-12-09 17:02:43.882470 79.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING 2024-12-09 17:02:43.902466 79.93% [INFO] mod_json_cdr.c:272 Process [3623f00e-551a-4829-a669-f30237ca8223.cdr.json] 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46735527281 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46735527281) State REPORTING going to sleep 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46735527281) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_session.c:1744 Session 134662 (sofia/default/+46735527281) Locked, Waiting on external entities 2024-12-09 17:02:43.902466 79.93% [NOTICE] switch_core_session.c:1762 Session 134662 (sofia/default/+46735527281) Ended 2024-12-09 17:02:43.902466 79.93% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46735527281 [CS_DESTROY] 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46735527281) Running State Change CS_DESTROY (Cur 119 Tot 134744) 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46735527281) State DESTROY 2024-12-09 17:02:43.902466 79.93% [DEBUG] mod_sofia.c:380 sofia/default/+46735527281 SOFIA DESTROY 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46735527281 Standard DESTROY 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46735527281) State DESTROY going to sleep 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/+46731218962@46.246.2.119 Standard REPORTING, cause: ORIGINATOR_CANCEL 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+46731218962@46.246.2.119) State REPORTING going to sleep 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+46731218962@46.246.2.119) State Change CS_REPORTING -> CS_DESTROY 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_session.c:1744 Session 134657 (sofia/default/+46731218962@46.246.2.119) Locked, Waiting on external entities 2024-12-09 17:02:43.902466 79.93% [NOTICE] switch_core_session.c:1762 Session 134657 (sofia/default/+46731218962@46.246.2.119) Ended 2024-12-09 17:02:43.902466 79.93% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/+46731218962@46.246.2.119 [CS_DESTROY] 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+46731218962@46.246.2.119) Running State Change CS_DESTROY (Cur 118 Tot 134744) 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY 2024-12-09 17:02:43.902466 79.93% [DEBUG] mod_sofia.c:380 sofia/default/+46731218962@46.246.2.119 SOFIA DESTROY 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/+46731218962@46.246.2.119 Standard DESTROY 2024-12-09 17:02:43.902466 79.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+46731218962@46.246.2.119) State DESTROY going to sleep