freeswitch
freeswitch copied to clipboard
signalwire
proxy_media mode can't receive rtp from cc .
question:
The new situation is Version 1.6.20 ok。
freeswitch version: FreeSWITCH Version 1.10.7-release.13~64bit (-release.13 64bit)
I set B freeswitch proxy_media mode, and the configuration in both places about proxy_media is set correctly。 A can't recevice rtp from C。 there is the sip :
Follow picture is the default mode sip:
I don't know where the problem is, please point it out。thinks!
Here is the proxy_media logs:
8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [NOTICE] switch_channel.c:1123 New Channel sofia/internal/[email protected]:59439 [8b952f41-bb60-4332-9599-186d4a6d5c8a] 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/[email protected]:59439) Running State Change CS_NEW (Cur 1 Tot 3) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [INFO] sofia.c:10462 sofia/internal/[email protected]:59439 receiving invite from 172.16.30.51:59439 version: 1.10.7 -release.13 64bit call-id: 1009-eeb2501a-f9a0-4798-93be-78e2aa2a1244 2022-02-21 16:53:28.516605 86.53% [DEBUG] sofia.c:10556 verifying acl "domains" for ip/port 172.16.30.51:0. 2022-02-21 16:53:28.516605 86.53% [DEBUG] sofia.c:10585 IP 172.16.30.51 Approved by acl "domains[]". Access Granted. 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] sofia.c:7499 Channel sofia/internal/[email protected]:59439 entering state [received][100] 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] sofia.c:7509 Remote SDP: 8b952f41-bb60-4332-9599-186d4a6d5c8a v=0 8b952f41-bb60-4332-9599-186d4a6d5c8a o=- 1818032676 1818032676 IN IP4 172.16.30.51 8b952f41-bb60-4332-9599-186d4a6d5c8a s=my people call themselves dark angels 8b952f41-bb60-4332-9599-186d4a6d5c8a c=IN IP4 172.16.30.51 8b952f41-bb60-4332-9599-186d4a6d5c8a t=0 0 8b952f41-bb60-4332-9599-186d4a6d5c8a m=audio 61366 RTP/AVP 0 8 101 8b952f41-bb60-4332-9599-186d4a6d5c8a a=rtpmap:0 PCMU/8000 8b952f41-bb60-4332-9599-186d4a6d5c8a a=rtpmap:8 PCMA/8000 8b952f41-bb60-4332-9599-186d4a6d5c8a a=rtpmap:101 telephone-event/8000 8b952f41-bb60-4332-9599-186d4a6d5c8a a=fmtp:101 0-16 8b952f41-bb60-4332-9599-186d4a6d5c8a 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] sofia.c:7906 (sofia/internal/[email protected]:59439) State Change CS_NEW -> CS_INIT 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/[email protected]:59439) State NEW 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/[email protected]:59439) Running State Change CS_INIT (Cur 1 Tot 3) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/[email protected]:59439) State INIT 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_sofia.c:97 sofia/internal/[email protected]:59439 SOFIA INIT 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:40 sofia/internal/[email protected]:59439 Standard INIT 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/[email protected]:59439) State Change CS_INIT -> CS_ROUTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/[email protected]:59439) State INIT going to sleep 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/[email protected]:59439) Running State Change CS_ROUTING (Cur 1 Tot 3) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_channel.c:2380 (sofia/internal/[email protected]:59439) Callstate Change DOWN -> RINGING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/[email protected]:59439) State ROUTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_sofia.c:158 sofia/internal/[email protected]:59439 SOFIA ROUTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:230 sofia/internal/[email protected]:59439 Standard ROUTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [INFO] mod_dialplan_xml.c:639 Processing yicall-call-out <1234567>->1009 in context public 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 parsing [public->unloop] continue=false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 parsing [public->outside_call] continue=true 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Absolute Condition [outside_call] 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Action set(outside_call=true) 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 parsing [public->call_debug] continue=true 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 parsing [public->public_extensions] continue=false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Regex (PASS) [public_extensions] destination_number(1009) =~ /^(.*)$/ break=on-false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Action transfer(1009 XML default) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/[email protected]:59439) State Change CS_ROUTING -> CS_EXECUTE 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/[email protected]:59439) State ROUTING going to sleep 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/[email protected]:59439) Running State Change CS_EXECUTE (Cur 1 Tot 3) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/[email protected]:59439) State EXECUTE 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_sofia.c:213 sofia/internal/[email protected]:59439 SOFIA EXECUTE 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:323 sofia/internal/[email protected]:59439 Standard EXECUTE 8b952f41-bb60-4332-9599-186d4a6d5c8a EXECUTE [depth=0] sofia/internal/[email protected]:59439 set(outside_call=true) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_dptools.c:1685 SET sofia/internal/[email protected]:59439 [outside_call]=[true] 8b952f41-bb60-4332-9599-186d4a6d5c8a EXECUTE [depth=0] sofia/internal/[email protected]:59439 export(RFC2822_DATE=Mon, 21 Feb 2022 16:53:28 +0800) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 21 Feb 2022 16:53:28 +0800] 8b952f41-bb60-4332-9599-186d4a6d5c8a EXECUTE [depth=0] sofia/internal/[email protected]:59439 transfer(1009 XML default) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_ivr.c:2289 (sofia/internal/[email protected]:59439) State Change CS_EXECUTE -> CS_ROUTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [NOTICE] switch_ivr.c:2296 Transfer sofia/internal/[email protected]:59439 to XML[1009@default] 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/[email protected]:59439) State EXECUTE going to sleep 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/[email protected]:59439) Running State Change CS_ROUTING (Cur 1 Tot 3) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/[email protected]:59439) State ROUTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_sofia.c:149 Call appears to be already acknowledged 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_sofia.c:158 sofia/internal/[email protected]:59439 SOFIA ROUTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:230 sofia/internal/[email protected]:59439 Standard ROUTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [INFO] mod_dialplan_xml.c:639 Processing yicall-call-out <1234567>->1009 in context default 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 parsing [default->refer request] continue=true 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Regex (FAIL) [refer request] ${sip_refer_to}() =~ /sip:(.*)@(.*)\?User-to-User:(.*)\?(.*)>/ break=on-false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 parsing [default->callout_057128167449] continue=false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Regex (FAIL) [callout_057128167449] caller_id_number(1234567) =~ /^057128167449$/ break=on-false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 parsing [default->callout_1] continue=false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Regex (FAIL) [callout_1] caller_id_number(1234567) =~ /^1$/ break=on-false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 parsing [default->callout_2] continue=false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Regex (FAIL) [callout_2] caller_id_number(1234567) =~ /^2$/ break=on-false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 parsing [default->callout_1234567] continue=false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Regex (PASS) [callout_1234567] caller_id_number(1234567) =~ /^1234567$/ break=on-false 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Action set(proxy_media=true) 8b952f41-bb60-4332-9599-186d4a6d5c8a Dialplan: sofia/internal/[email protected]:59439 Action bridge({originate_timeout=300,ignore_early_media=true}sofia/gateway/gw_1234567/${destination_number}) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/[email protected]:59439) State Change CS_ROUTING -> CS_EXECUTE 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/[email protected]:59439) State ROUTING going to sleep 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/[email protected]:59439) Running State Change CS_EXECUTE (Cur 1 Tot 3) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/[email protected]:59439) State EXECUTE 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_sofia.c:213 sofia/internal/[email protected]:59439 SOFIA EXECUTE 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:323 sofia/internal/[email protected]:59439 Standard EXECUTE 8b952f41-bb60-4332-9599-186d4a6d5c8a EXECUTE [depth=0] sofia/internal/[email protected]:59439 set(proxy_media=true) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_dptools.c:1685 SET sofia/internal/[email protected]:59439 [proxy_media]=[true] 8b952f41-bb60-4332-9599-186d4a6d5c8a EXECUTE [depth=0] sofia/internal/[email protected]:59439 bridge({originate_timeout=300,ignore_early_media=true}sofia/gateway/gw_1234567/1009) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_channel.c:1269 sofia/internal/[email protected]:59439 EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 21 Feb 2022 16:53:28 +0800] to event 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.516605 86.53% [NOTICE] switch_channel.c:1123 New Channel sofia/external/1009 [52c79f86-44a6-4eec-b7c9-16ac308656bf] 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_sofia.c:5121 (sofia/external/1009) State Change CS_NEW -> CS_INIT 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/1009) Running State Change CS_INIT (Cur 2 Tot 4) 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.516605 86.53% [DEBUG] switch_core_state_machine.c:624 (sofia/external/1009) State INIT 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.516605 86.53% [DEBUG] mod_sofia.c:97 sofia/external/1009 SOFIA INIT 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_media.c:12454 sofia/external/1009 Patched SDP 52c79f86-44a6-4eec-b7c9-16ac308656bf --- 52c79f86-44a6-4eec-b7c9-16ac308656bf v=0 52c79f86-44a6-4eec-b7c9-16ac308656bf o=- 1818032676 1818032676 IN IP4 172.16.30.51 52c79f86-44a6-4eec-b7c9-16ac308656bf s=my people call themselves dark angels 52c79f86-44a6-4eec-b7c9-16ac308656bf c=IN IP4 172.16.30.51 52c79f86-44a6-4eec-b7c9-16ac308656bf t=0 0 52c79f86-44a6-4eec-b7c9-16ac308656bf m=audio 61366 RTP/AVP 0 8 101 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:0 PCMU/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:8 PCMA/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:101 telephone-event/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=fmtp:101 0-16 52c79f86-44a6-4eec-b7c9-16ac308656bf 52c79f86-44a6-4eec-b7c9-16ac308656bf +++ 52c79f86-44a6-4eec-b7c9-16ac308656bf v=0 52c79f86-44a6-4eec-b7c9-16ac308656bf o=FreeSWITCH 3691515777 3691515778 IN IP4 172.16.30.131 52c79f86-44a6-4eec-b7c9-16ac308656bf s=FreeSWITCH 52c79f86-44a6-4eec-b7c9-16ac308656bf c=IN IP4 172.16.30.131 52c79f86-44a6-4eec-b7c9-16ac308656bf t=0 0 52c79f86-44a6-4eec-b7c9-16ac308656bf m=audio 24944 RTP/AVP 0 8 101 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:0 PCMU/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:8 PCMA/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:101 telephone-event/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=fmtp:101 0-16 52c79f86-44a6-4eec-b7c9-16ac308656bf 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [INFO] sofia_glue.c:1651 sofia/external/1009 sending invite call-id: (null) 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] sofia_glue.c:1654 sofia/external/1009 sending invite version: 1.10.7 -release.13 64bit 52c79f86-44a6-4eec-b7c9-16ac308656bf Local SDP: 52c79f86-44a6-4eec-b7c9-16ac308656bf v=0 52c79f86-44a6-4eec-b7c9-16ac308656bf o=FreeSWITCH 3691515777 3691515778 IN IP4 172.16.30.131 52c79f86-44a6-4eec-b7c9-16ac308656bf s=FreeSWITCH 52c79f86-44a6-4eec-b7c9-16ac308656bf c=IN IP4 172.16.30.131 52c79f86-44a6-4eec-b7c9-16ac308656bf t=0 0 52c79f86-44a6-4eec-b7c9-16ac308656bf m=audio 24944 RTP/AVP 0 8 101 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:0 PCMU/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:8 PCMA/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:101 telephone-event/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=fmtp:101 0-16 52c79f86-44a6-4eec-b7c9-16ac308656bf 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_state_machine.c:40 sofia/external/1009 Standard INIT 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_state_machine.c:48 (sofia/external/1009) State Change CS_INIT -> CS_ROUTING 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_state_machine.c:624 (sofia/external/1009) State INIT going to sleep 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/1009) Running State Change CS_ROUTING (Cur 2 Tot 4) 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_state_machine.c:640 (sofia/external/1009) State ROUTING 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] mod_sofia.c:158 sofia/external/1009 SOFIA ROUTING 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_ivr_originate.c:67 (sofia/external/1009) State Change CS_ROUTING -> CS_CONSUME_MEDIA 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_state_machine.c:640 (sofia/external/1009) State ROUTING going to sleep 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/1009) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 4) 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_state_machine.c:659 (sofia/external/1009) State CONSUME_MEDIA 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] switch_core_state_machine.c:659 (sofia/external/1009) State CONSUME_MEDIA going to sleep 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:28.536595 86.53% [DEBUG] sofia.c:7499 Channel sofia/external/1009 entering state [calling][0] 2022-02-21 16:53:38.596611 86.47% [INFO] sofia.c:1348 sofia/external/1009 Update Callee ID to "1009" <sip:[email protected]> 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] sofia.c:7499 Channel sofia/external/1009 entering state [proceeding][183] 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] sofia.c:7509 Remote SDP: 52c79f86-44a6-4eec-b7c9-16ac308656bf v=0 52c79f86-44a6-4eec-b7c9-16ac308656bf o=FreeSWITCH 1645376178 1645376179 IN IP4 172.16.20.194 52c79f86-44a6-4eec-b7c9-16ac308656bf s=FreeSWITCH 52c79f86-44a6-4eec-b7c9-16ac308656bf c=IN IP4 172.16.20.194 52c79f86-44a6-4eec-b7c9-16ac308656bf t=0 0 52c79f86-44a6-4eec-b7c9-16ac308656bf m=audio 57440 RTP/AVP 0 101 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:0 PCMU/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:101 telephone-event/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=fmtp:101 0-16 52c79f86-44a6-4eec-b7c9-16ac308656bf a=ptime:20 52c79f86-44a6-4eec-b7c9-16ac308656bf 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [NOTICE] sofia.c:7512 Pre-Answer sofia/external/1009! 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] switch_channel.c:3622 (sofia/external/1009) Callstate Change DOWN -> EARLY 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] switch_core_media.c:12454 sofia/external/1009 Patched SDP 52c79f86-44a6-4eec-b7c9-16ac308656bf --- 52c79f86-44a6-4eec-b7c9-16ac308656bf v=0 52c79f86-44a6-4eec-b7c9-16ac308656bf o=FreeSWITCH 3691515777 3691515778 IN IP4 172.16.30.131 52c79f86-44a6-4eec-b7c9-16ac308656bf s=FreeSWITCH 52c79f86-44a6-4eec-b7c9-16ac308656bf c=IN IP4 172.16.30.131 52c79f86-44a6-4eec-b7c9-16ac308656bf t=0 0 52c79f86-44a6-4eec-b7c9-16ac308656bf m=audio 24944 RTP/AVP 0 8 101 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:0 PCMU/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:8 PCMA/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:101 telephone-event/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=fmtp:101 0-16 52c79f86-44a6-4eec-b7c9-16ac308656bf 52c79f86-44a6-4eec-b7c9-16ac308656bf +++ 52c79f86-44a6-4eec-b7c9-16ac308656bf v=0 52c79f86-44a6-4eec-b7c9-16ac308656bf o=FreeSWITCH 3691515777 3691515779 IN IP4 172.16.30.131 52c79f86-44a6-4eec-b7c9-16ac308656bf s=FreeSWITCH 52c79f86-44a6-4eec-b7c9-16ac308656bf c=IN IP4 172.16.30.131 52c79f86-44a6-4eec-b7c9-16ac308656bf t=0 0 52c79f86-44a6-4eec-b7c9-16ac308656bf m=audio 24944 RTP/AVP 0 8 101 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:0 PCMU/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:8 PCMA/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:101 telephone-event/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=fmtp:101 0-16 52c79f86-44a6-4eec-b7c9-16ac308656bf 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] switch_core_media.c:3870 Set Codec sofia/external/1009 PROXY/0 0 ms 160 samples 0 bits 1 channels 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] switch_core_codec.c:111 sofia/external/1009 Original read codec set to PROXY:0 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] switch_core_media.c:8843 PROXY AUDIO RTP [sofia/external/1009] 172.16.20.194:57440->172.16.20.194:57440 codec: 0 ms: 20 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] switch_rtp.c:4641 Not using a timer 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] switch_core_media.c:9089 sofia/external/1009 Set 2833 dtmf send payload to 101 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] switch_core_media.c:9096 sofia/external/1009 Set 2833 dtmf receive payload to 101 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:38.596611 86.47% [DEBUG] switch_core_media.c:9119 sofia/external/1009 Set rtp dtmf delay to 40 2022-02-21 16:53:49.516582 80.37% [INFO] sofia.c:1348 sofia/external/1009 Update Callee ID to "Outbound Call" <sip:[email protected]> 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.516582 80.37% [DEBUG] sofia.c:7499 Channel sofia/external/1009 entering state [completing][200] 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.516582 80.37% [DEBUG] sofia.c:7506 Duplicate SDP 52c79f86-44a6-4eec-b7c9-16ac308656bf v=0 52c79f86-44a6-4eec-b7c9-16ac308656bf o=FreeSWITCH 1645376178 1645376179 IN IP4 172.16.20.194 52c79f86-44a6-4eec-b7c9-16ac308656bf s=FreeSWITCH 52c79f86-44a6-4eec-b7c9-16ac308656bf c=IN IP4 172.16.20.194 52c79f86-44a6-4eec-b7c9-16ac308656bf t=0 0 52c79f86-44a6-4eec-b7c9-16ac308656bf m=audio 57440 RTP/AVP 0 101 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:0 PCMU/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=rtpmap:101 telephone-event/8000 52c79f86-44a6-4eec-b7c9-16ac308656bf a=fmtp:101 0-16 52c79f86-44a6-4eec-b7c9-16ac308656bf a=ptime:20 52c79f86-44a6-4eec-b7c9-16ac308656bf 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.516582 80.37% [DEBUG] sofia.c:7499 Channel sofia/external/1009 entering state [ready][200] 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.516582 80.37% [NOTICE] sofia.c:8645 Channel [sofia/external/1009] has been answered 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.516582 80.37% [DEBUG] switch_channel.c:3950 (sofia/external/1009) Callstate Change EARLY -> ACTIVE 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.516582 80.37% [DEBUG] switch_core_media.c:8759 Audio params are unchanged for sofia/external/1009. 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_media.c:12454 sofia/internal/[email protected]:59439 Patched SDP 8b952f41-bb60-4332-9599-186d4a6d5c8a --- 8b952f41-bb60-4332-9599-186d4a6d5c8a v=0 8b952f41-bb60-4332-9599-186d4a6d5c8a o=FreeSWITCH 1645376178 1645376179 IN IP4 172.16.20.194 8b952f41-bb60-4332-9599-186d4a6d5c8a s=FreeSWITCH 8b952f41-bb60-4332-9599-186d4a6d5c8a c=IN IP4 172.16.20.194 8b952f41-bb60-4332-9599-186d4a6d5c8a t=0 0 8b952f41-bb60-4332-9599-186d4a6d5c8a m=audio 57440 RTP/AVP 0 101 8b952f41-bb60-4332-9599-186d4a6d5c8a a=rtpmap:0 PCMU/8000 8b952f41-bb60-4332-9599-186d4a6d5c8a a=rtpmap:101 telephone-event/8000 8b952f41-bb60-4332-9599-186d4a6d5c8a a=fmtp:101 0-16 8b952f41-bb60-4332-9599-186d4a6d5c8a a=ptime:20 8b952f41-bb60-4332-9599-186d4a6d5c8a 8b952f41-bb60-4332-9599-186d4a6d5c8a +++ 8b952f41-bb60-4332-9599-186d4a6d5c8a v=0 8b952f41-bb60-4332-9599-186d4a6d5c8a o=FreeSWITCH 3692184018 3692184019 IN IP4 172.16.30.131 8b952f41-bb60-4332-9599-186d4a6d5c8a s=FreeSWITCH 8b952f41-bb60-4332-9599-186d4a6d5c8a c=IN IP4 172.16.30.131 8b952f41-bb60-4332-9599-186d4a6d5c8a t=0 0 8b952f41-bb60-4332-9599-186d4a6d5c8a m=audio 21252 RTP/AVP 0 101 8b952f41-bb60-4332-9599-186d4a6d5c8a a=rtpmap:0 PCMU/8000 8b952f41-bb60-4332-9599-186d4a6d5c8a a=rtpmap:101 telephone-event/8000 8b952f41-bb60-4332-9599-186d4a6d5c8a a=fmtp:101 0-16 8b952f41-bb60-4332-9599-186d4a6d5c8a a=ptime:20 8b952f41-bb60-4332-9599-186d4a6d5c8a 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_media.c:3870 Set Codec sofia/internal/[email protected]:59439 PROXY/0 0 ms 160 samples 0 bits 1 channels 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_codec.c:111 sofia/internal/[email protected]:59439 Original read codec set to PROXY:0 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_media.c:8843 PROXY AUDIO RTP [sofia/internal/[email protected]:59439] 172.16.30.51:61366->172.16.30.51:61366 codec: 0 ms: 20 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_rtp.c:4641 Not using a timer 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_media.c:9089 sofia/internal/[email protected]:59439 Set 2833 dtmf send payload to 101 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_media.c:9096 sofia/internal/[email protected]:59439 Set 2833 dtmf receive payload to 101 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_media.c:9119 sofia/internal/[email protected]:59439 Set rtp dtmf delay to 40 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/internal/[email protected]:59439] has been answered 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_channel.c:3950 (sofia/internal/[email protected]:59439) Callstate Change RINGING -> ACTIVE 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] sofia.c:7499 Channel sofia/internal/[email protected]:59439 entering state [completed][200] 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/external/1009] Peer UUID: 52c79f86-44a6-4eec-b7c9-16ac308656bf 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] sofia.c:7499 Channel sofia/internal/[email protected]:59439 entering state [ready][200] 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_media.c:8759 Audio params are unchanged for sofia/internal/[email protected]:59439. 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/1009) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_state_machine.c:581 (sofia/external/1009) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 4) 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_core_state_machine.c:650 (sofia/external/1009) State EXCHANGE_MEDIA 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.536586 80.37% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:49.536586 80.37% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed. 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:49.556574 80.37% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed. 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.616573 82.63% [NOTICE] sofia.c:1065 Hangup sofia/internal/[email protected]:59439 [CS_EXECUTE] [NORMAL_CLEARING] 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/[email protected]:59439] 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_ivr_bridge.c:820 sofia/internal/[email protected]:59439 ending bridge by request from write function 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/1009] 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/external/1009 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:650 (sofia/external/1009) State EXCHANGE_MEDIA going to sleep 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:581 (sofia/external/1009) Running State Change CS_HANGUP (Cur 2 Tot 4) 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:844 (sofia/external/1009) Callstate Change ACTIVE -> HANGUP 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:846 (sofia/external/1009) State HANGUP 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] mod_sofia.c:462 sofia/external/1009 Overriding SIP cause 480 with 200 from the other leg 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] mod_sofia.c:468 Channel sofia/external/1009 hanging up, cause: NORMAL_CLEARING 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/external/1009 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:59 sofia/external/1009 Standard HANGUP, cause: NORMAL_CLEARING 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:846 (sofia/external/1009) State HANGUP going to sleep 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_ivr_bridge.c:1892 sofia/external/1009 skip receive message [UNBRIDGE] (channel is hungup already) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_ivr_bridge.c:1895 sofia/internal/[email protected]:59439 skip receive message [UNBRIDGE] (channel is hungup already) 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:616 (sofia/external/1009) State Change CS_HANGUP -> CS_REPORTING 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:581 (sofia/external/1009) Running State Change CS_REPORTING (Cur 2 Tot 4) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_session.c:2983 sofia/internal/[email protected]:59439 skip receive message [PHONE_EVENT] (channel is hungup already) 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:932 (sofia/external/1009) State REPORTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/[email protected]:59439) State EXECUTE going to sleep 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:168 sofia/external/1009 Standard REPORTING, cause: NORMAL_CLEARING 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:932 (sofia/external/1009) State REPORTING going to sleep 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/[email protected]:59439) Running State Change CS_HANGUP (Cur 2 Tot 4) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/[email protected]:59439) Callstate Change ACTIVE -> HANGUP 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:607 (sofia/external/1009) State Change CS_REPORTING -> CS_DESTROY 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_session.c:1753 Session 4 (sofia/external/1009) Locked, Waiting on external entities 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [NOTICE] switch_core_session.c:1771 Session 4 (sofia/external/1009) Ended 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [NOTICE] switch_core_session.c:1775 Close Channel sofia/external/1009 [CS_DESTROY] 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/[email protected]:59439) State HANGUP 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.616573 82.63% [DEBUG] mod_sofia.c:468 Channel sofia/internal/[email protected]:59439 hanging up, cause: NORMAL_CLEARING 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:735 (sofia/external/1009) Running State Change CS_DESTROY (Cur 1 Tot 4) 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:745 (sofia/external/1009) State DESTROY 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] mod_sofia.c:379 sofia/external/1009 SOFIA DESTROY 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:175 sofia/external/1009 Standard DESTROY 52c79f86-44a6-4eec-b7c9-16ac308656bf 2022-02-21 16:53:54.616573 82.63% [DEBUG] switch_core_state_machine.c:745 (sofia/external/1009) State DESTROY going to sleep 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:59 sofia/internal/[email protected]:59439 Standard HANGUP, cause: NORMAL_CLEARING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/[email protected]:59439) State HANGUP going to sleep 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/[email protected]:59439) State Change CS_HANGUP -> CS_REPORTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/[email protected]:59439) Running State Change CS_REPORTING (Cur 1 Tot 4) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/[email protected]:59439) State REPORTING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:168 sofia/internal/[email protected]:59439 Standard REPORTING, cause: NORMAL_CLEARING 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/[email protected]:59439) State REPORTING going to sleep 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/[email protected]:59439) State Change CS_REPORTING -> CS_DESTROY 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_session.c:1753 Session 3 (sofia/internal/[email protected]:59439) Locked, Waiting on external entities 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [NOTICE] switch_core_session.c:1771 Session 3 (sofia/internal/[email protected]:59439) Ended 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [NOTICE] switch_core_session.c:1775 Close Channel sofia/internal/[email protected]:59439 [CS_DESTROY] 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/[email protected]:59439) Running State Change CS_DESTROY (Cur 0 Tot 4) 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/[email protected]:59439) State DESTROY 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] mod_sofia.c:379 sofia/internal/[email protected]:59439 SOFIA DESTROY 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:175 sofia/internal/[email protected]:59439 Standard DESTROY 8b952f41-bb60-4332-9599-186d4a6d5c8a 2022-02-21 16:53:54.636573 82.63% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/[email protected]:59439) State DESTROY going to sleep
I have tested v1.10.6, and can confirm the issue was introduced in v1.10.7. I see lots of changes to SRTP & RTP between the 2 versions but I was unable to see anything I could fix.
To your point @dragos-oancea. I checked the wiki, there are a few pages talking to the benefits of using proxy media. It is quite a great feature and would be good to get it fixed.
I did some more searching around and found a thread from 2018 in the mailing list archives. Based on this thread and dragos-oancea's earlier comment, we are currently working on adjusting our dialplans to move away from proxy media. The last line of Ken's comment makes it pretty clear there is no advantage to this feature in our use case.
Given proxy media is no longer a supported feature, it would be worth noting the deprecation somewhere.
Here are places I found mention in the wiki; https://freeswitch.org/confluence/display/FREESWITCH/Proxy+Media https://freeswitch.org/confluence/display/FREESWITCH/Codecs+and+Media#CodecsandMedia-Mediaproxying https://freeswitch.org/confluence/display/FREESWITCH/proxy_media
Mailing list archive thread; https://lists.freeswitch.org/pipermail/freeswitch-users/2018-October/130682.html
Michael Jerris:
I’ll expand on Brian’s comment. Proxy media should NEVER be used. There is one exception to this rule, and that is when you are trying to pass codecs that freeswitch does not know about, and even that case has a better workaround than to use proxy media. If you are using proxy media you should stop, if you are not sure how to do so you should discuss on the mailing list. Proxy media mode is NOT the way to avoid transcoding, that can be done properly without this setting and this setting should never be used.
Ken Rice:
Proxy media mode is a special mode that may or may not work the way you think it does, like bypass media mode, depending on your use cases it may cause more problems than its worth as a large number of things are bypassed disabled… there is no advantage for using proxy media mode unless you are just trying to use a new completely unsupported codec. Proxy media mode is no faster than the default standard media mode when you are not transcoding.
