Hi Thanks alot for the help. The call is not able to ing the remote end. However, from the webrtc side, I can hear audio, but the remote end, there is no audio. My scenario is WebRTC client -> Freeswitch -> Voip gateway -> mobile phone WebRTC client can hear audio but mobile phone hear nothing. Is it because of some decryption not set correct? Here is my log: ------------------------------------------------------------------------ recv 3546 bytes from tcp/[163.125.130.79]:26869 at 16:38:06.597828: ------------------------------------------------------------------------ INVITE sip:1002@x.x.x.x:5060 SIP/2.0 Via: SIP/2.0/TCP 192.168.1.100:5060 ;branch=z9hG4bK-524287-1---e4f7b616f75a9478;rport Max-Forwards: 70 Contact: <sip:1001@192.168.1.100:5060 ;transport=tcp>;+sip.instance="1001@x.x.x.x:5060" To: <sip:1002@x.x.x.x:5060> From: <sip:1001@x.x.x.x:5060>;tag=e090b871 Call-ID: YEXqB4WY4KZUOfmDabppqg.. CSeq: 2 INVITE Session-Expires: 1800 Min-SE: 90 Accept-Language: en Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, NOTIFY, SUBSCRIBE, INFO, MESSAGE, PRACK Content-Type: application/sdp Proxy-Authorization: Digest username="1001",realm="x.x.x.x",nonce="54896b80-49b5-11e5-ba06-3bd1ff35cbfe",uri="sip:1002@x.x.x.x :5060",response="0afd4be94eedc61fadc74712a1376486",cnonce="55b6411c482ea7487d4a9c4496371213",nc=00000001,qop=auth,algorithm=MD5 Supported: timer, 100rel Content-Length: 2681 v=0 o=- 7694721001483168063 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS media_stream m=audio 9 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126 c=IN IP4 0.0.0.0 a=rtcp:9 IN IP4 0.0.0.0 a=ice-ufrag:PfQS5mTk8DdU0wfL a=ice-pwd:/LwkxiZgwC7MiwSbG1Ypx5hJ a=fingerprint:sha-1 0F:6D:76:5D:16:F5:37:8F:F9:80:1C:CC:54:F0:3E:61:13:A0:87:17 a=setup:actpass a=mid:audio a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time a=sendrecv a=rtcp-mux a=rtpmap:111 opus/48000/2 a=fmtp:111 minptime=10; useinbandfec=1 a=rtpmap:103 ISAC/16000 a=rtpmap:9 G722/8000 a=rtpmap:102 ILBC/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:127 red/8000 a=rtpmap:126 telephone-event/8000 a=maxptime:60 a=ssrc:718599481 cname:DOUxo2z76LkE5KZX a=ssrc:718599481 msid:media_stream audio_track a=ssrc:718599481 mslabel:media_stream a=ssrc:718599481 label:audio_track a=candidate:3013953624 1 udp 2122260223 192.168.1.100 55457 typ host generation 0 a=candidate:2117845908 1 udp 2122194687 10.162.22.234 50345 typ host generation 0 a=candidate:3013953624 2 udp 2122260222 192.168.1.100 52690 typ host generation 0 a=candidate:2117845908 2 udp 2122194686 10.162.22.234 53413 typ host generation 0 a=candidate:4247172264 1 tcp 1518280447 192.168.1.100 49472 typ host tcptype passive generation 0 a=candidate:817381220 1 tcp 1518214911 10.162.22.234 49473 typ host tcptype passive generation 0 a=candidate:4247172264 2 tcp 1518280446 192.168.1.100 49474 typ host tcptype passive generation 0 a=candidate:817381220 2 tcp 1518214910 10.162.22.234 49475 typ host tcptype passive generation 0 a=candidate:854413036 1 udp 1686052607 163.125.130.79 26990 typ srflx raddr 192.168.1.100 rport 55457 generation 0 a=candidate:854413036 2 udp 1686052606 163.125.130.79 26991 typ srflx raddr 192.168.1.100 rport 52690 generation 0 a=candidate:4285777184 1 udp 1685987071 112.97.55.70 59693 typ srflx raddr 10.162.22.234 rport 50345 generation 0 a=candidate:4285777184 2 udp 1685987070 112.97.55.70 59694 typ srflx raddr 10.162.22.234 rport 53413 generation 0 a=candidate:2922827951 1 udp 41885439 146.185.149.11 60858 typ relay raddr 163.125.130.79 rport 26990 generation 0 a=candidate:2922827951 2 udp 41885438 146.185.149.11 62660 typ relay raddr 163.125.130.79 rport 26991 generation 0 a=candidate:2922827951 2 udp 41819902 146.185.149.11 61723 typ relay raddr 112.97.51.70 rport 42163 generation 0 a=candidate:2922827951 1 udp 41819903 146.185.149.11 63902 typ relay raddr 112.97.51.70 rport 42162 generation 0 ------------------------------------------------------------------------ send 371 bytes to tcp/[163.125.130.79]:26869 at 16:38:06.598163: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/TCP 192.168.1.100:5060 ;branch=z9hG4bK-524287-1---e4f7b616f75a9478;rport=26869;received=163.125.130.79 From: <sip:1001@x.x.x.x:5060>;tag=e090b871 To: <sip:1002@x.x.x.x:5060> Call-ID: YEXqB4WY4KZUOfmDabppqg.. CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit Content-Length: 0 ------------------------------------------------------------------------ 2015-08-23 16:38:06.590319 [DEBUG] sofia.c:2255 Re-attaching to session 54895f1e-49b5-11e5-ba05-3bd1ff35cbfe 2015-08-23 16:38:06.590319 [DEBUG] sofia.c:9093 sofia/internal/1001@x.x.x.x:5060 receiving invite from 163.125.130.79:26869 version: 1.7.0 git 58f1272 2015-08-14 17:51:12Z 64bit 2015-08-23 16:38:06.590319 [DEBUG] sofia.c:9260 IP 163.125.130.79 Rejected by acl "domains". Falling back to Digest auth. 2015-08-23 16:38:06.590319 [DEBUG] sofia.c:10392 Setting NAT mode based on nat.auto 2015-08-23 16:38:06.590319 [DEBUG] sofia.c:6715 Channel sofia/internal/1001@x.x.x.x:5060 entering state [received][100] 2015-08-23 16:38:06.590319 [DEBUG] sofia.c:6725 Remote SDP: v=0 o=- 7694721001483168063 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS media_stream m=audio 9 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126 c=IN IP4 0.0.0.0 a=rtpmap:111 opus/48000/2 a=fmtp:111 minptime=10; useinbandfec=1 a=rtpmap:103 ISAC/16000 a=rtpmap:9 G722/8000 a=rtpmap:102 ILBC/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:127 red/8000 a=rtpmap:126 telephone-event/8000 a=rtcp:9 IN IP4 0.0.0.0 a=ice-ufrag:PfQS5mTk8DdU0wfL a=ice-pwd:/LwkxiZgwC7MiwSbG1Ypx5hJ a=fingerprint:sha-1 0F:6D:76:5D:16:F5:37:8F:F9:80:1C:CC:54:F0:3E:61:13:A0:87:17 a=setup:actpass a=mid:audio a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time a=rtcp-mux a=maxptime:60 a=ssrc:718599481 cname:DOUxo2z76LkE5KZX a=ssrc:718599481 msid:media_stream audio_track a=ssrc:718599481 mslabel:media_stream a=ssrc:718599481 label:audio_track a=candidate:3013953624 1 udp 2122260223 192.168.1.100 55457 typ host generation 0 a=candidate:2117845908 1 udp 2122194687 10.162.22.234 50345 typ host generation 0 a=candidate:3013953624 2 udp 2122260222 192.168.1.100 52690 typ host generation 0 a=candidate:2117845908 2 udp 2122194686 10.162.22.234 53413 typ host generation 0 a=candidate:4247172264 1 tcp 1518280447 192.168.1.100 49472 typ host tcptype passive generation 0 a=candidate:817381220 1 tcp 1518214911 10.162.22.234 49473 typ host tcptype passive generation 0 a=candidate:4247172264 2 tcp 1518280446 192.168.1.100 49474 typ host tcptype passive generation 0 a=candidate:817381220 2 tcp 1518214910 10.162.22.234 49475 typ host tcptype passive generation 0 a=candidate:854413036 1 udp 1686052607 163.125.130.79 26990 typ srflx raddr 192.168.1.100 rport 55457 generation 0 a=candidate:854413036 2 udp 1686052606 163.125.130.79 26991 typ srflx raddr 192.168.1.100 rport 52690 generation 0 a=candidate:4285777184 1 udp 1685987071 112.97.55.70 59693 typ srflx raddr 10.162.22.234 rport 50345 generation 0 a=candidate:4285777184 2 udp 1685987070 112.97.55.70 59694 typ srflx raddr 10.162.22.234 rport 53413 generation 0 a=candidate:2922827951 1 udp 41885439 146.185.149.11 60858 typ relay raddr 163.125.130.79 rport 26990 generation 0 a=candidate:2922827951 2 udp 41885438 146.185.149.11 62660 typ relay raddr 163.125.130.79 rport 26991 generation 0 a=candidate:2922827951 2 udp 41819902 146.185.149.11 61723 typ relay raddr 112.97.51.70 rport 42163 generation 0 a=candidate:2922827951 1 udp 41819903 146.185.149.11 63902 typ relay raddr 112.97.51.70 rport 42162 generation 0 2015-08-23 16:38:06.590319 [DEBUG] sofia.c:6991 (sofia/internal/1001@x.x.x.x:5060) State Change CS_NEW -> CS_INIT 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@x.x.x.x:5060) Running State Change CS_INIT 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1001@x.x.x.x:5060) State INIT 2015-08-23 16:38:06.590319 [DEBUG] mod_sofia.c:88 sofia/internal/1001@x.x.x.x:5060 SOFIA INIT 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@x.x.x.x:5060 Standard INIT 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@x.x.x.x:5060) State Change CS_INIT -> CS_ROUTING 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1001@x.x.x.x:5060) State INIT going to sleep 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@x.x.x.x:5060) Running State Change CS_ROUTING 2015-08-23 16:38:06.590319 [DEBUG] switch_channel.c:2239 (sofia/internal/1001@x.x.x.x:5060) Callstate Change DOWN -> RINGING 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1001@x.x.x.x:5060) State ROUTING 2015-08-23 16:38:06.590319 [DEBUG] mod_sofia.c:141 sofia/internal/1001@x.x.x.x:5060 SOFIA ROUTING 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1001@x.x.x.x:5060 Standard ROUTING 2015-08-23 16:38:06.590319 [INFO] mod_dialplan_xml.c:637 Processing 1001 <1001>->1002 in context default Dialplan: sofia/internal/1001@x.x.x.x:5060 parsing [default->incoming_calls] continue=false Dialplan: sofia/internal/1001@x.x.x.x:5060 Regex (PASS) [incoming_calls] ${destination_number}(1002) =~ /1002/ break=on-false Dialplan: sofia/internal/1001@x.x.x.x:5060 Action bridge({rtp_secure_media=false,sip_secure_mediai_outbound=false}[,sip_secure_media_outbound=false,sdp_secure_savp_only=false,origination_caller_id_number= 17185345433]sofia/internal/754617702573728@y.y.y.y) 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1001@x.x.x.x:5060) State Change CS_ROUTING -> CS_EXECUTE 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1001@x.x.x.x:5060) State ROUTING going to sleep 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@x.x.x.x:5060) Running State Change CS_EXECUTE 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1001@x.x.x.x:5060) State EXECUTE 2015-08-23 16:38:06.590319 [DEBUG] mod_sofia.c:196 sofia/internal/1001@x.x.x.x:5060 SOFIA EXECUTE 2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1001@x.x.x.x:5060 Standard EXECUTE EXECUTE sofia/internal/1001@x.x.x.x:5060 bridge({rtp_secure_media=false,sip_secure_mediai_outbound=false}[,sip_secure_media_outbound=false,sdp_secure_savp_only=false,origination_caller_id_number= 17185345433]sofia/internal/754617702573728@y.y.y.y) 2015-08-23 16:38:06.610302 [DEBUG] switch_ivr_originate.c:2104 Parsing global variables 2015-08-23 16:38:06.610302 [DEBUG] switch_ivr_originate.c:2557 Parsing session specific variables 2015-08-23 16:38:06.610302 [NOTICE] switch_channel.c:1089 New Channel sofia/internal/754617702573728@y.y.y.y [54d94e84-49b5-11e5-ba0c-3bd1ff35cbfe] 2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:4735 (sofia/internal/754617702573728@y.y.y.y) State Change CS_NEW -> CS_INIT 2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:4805 [zrtp_passthru] Setting a-leg inherit_codec=true 2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:4808 [zrtp_passthru] Setting b-leg absolute_codec_string='mod_spandsp.G722@8000h@20i@64000b ,CORE_PCM_MODULE.PCMU@8000h@20i@64000b,CORE_PCM_MODULE.PCMA@8000h@20i@64000b ' 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/754617702573728@y.y.y.y) Running State Change CS_INIT 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/754617702573728@y.y.y.y) State INIT 2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:88 sofia/internal/754617702573728@y.y.y.y SOFIA INIT 2015-08-23 16:38:06.610302 [DEBUG] sofia_glue.c:1257 sofia/internal/754617702573728@y.y.y.y sending invite version: 1.7.0 git 58f1272 2015-08-14 17:51:12Z 64bit Local SDP: v=0 o=FreeSWITCH 1440321260 1440321261 IN IP4 x.x.x.x s=FreeSWITCH c=IN IP4 x.x.x.x t=0 0 m=audio 26626 RTP/AVP 9 0 8 101 13 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 a=sendrecv 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:40 sofia/internal/754617702573728@y.y.y.y Standard INIT 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/754617702573728@y.y.y.y) State Change CS_INIT -> CS_ROUTING 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/754617702573728@y.y.y.y) State INIT going to sleep 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/754617702573728@y.y.y.y) Running State Change CS_ROUTING 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/754617702573728@y.y.y.y) State ROUTING 2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:141 sofia/internal/754617702573728@y.y.y.y SOFIA ROUTING 2015-08-23 16:38:06.610302 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/754617702573728@y.y.y.y) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/754617702573728@y.y.y.y) State ROUTING going to sleep 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/754617702573728@y.y.y.y) Running State Change CS_CONSUME_MEDIA send 1281 bytes to udp/[y.y.y.y]:5060 at 16:38:06.611312: ------------------------------------------------------------------------ INVITE sip:754617702573728@y.y.y.y SIP/2.0 Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKevaDa1tKZ99Uc 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/754617702573728@y.y.y.y) State CONSUME_MEDIA Max-Forwards: 69 From: "Extension 1001" <sip:17185345433@x.x.x.x>;tag=j0D5DB23gpjve To: <sip:754617702573728@y.y.y.y> 2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/754617702573728@y.y.y.y) State CONSUME_MEDIA going to sleep Call-ID: 2c37ae78-c458-1233-8384-60eb698211be CSeq: 79837879 INVITE Contact: <sip:mod_sofia@x.x.x.x:5060> User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 294 X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 1001" <sip:17185345433 @x.x.x.x>;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1440321260 1440321261 IN IP4 x.x.x.x s=FreeSWITCH c=IN IP4 x.x.x.x t=0 0 m=audio 26626 RTP/AVP 9 0 8 101 13 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 ------------------------------------------------------------------------ 2015-08-23 16:38:06.610302 [DEBUG] sofia.c:6715 Channel sofia/internal/754617702573728@y.y.y.y entering state [calling][0] recv 317 bytes from udp/[y.y.y.y]:5060 at 16:38:06.612398: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKevaDa1tKZ99Uc From: "Extension 1001" <sip:17185345433@x.x.x.x>;tag=j0D5DB23gpjve To: <sip:754617702573728@y.y.y.y> Call-ID: 2c37ae78-c458-1233-8384-60eb698211be CSeq: 79837879 INVITE User-Agent: DNL-Switch Content-Length: 0 ------------------------------------------------------------------------ recv 674 bytes from udp/[y.y.y.y]:5060 at 16:38:07.210825: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKevaDa1tKZ99Uc From: "Extension 1001" <sip:17185345433@x.x.x.x>;tag=j0D5DB23gpjve To: <sip:754617702573728@y.y.y.y>;tag=1Xmp97vgtp22K Call-ID: 2c37ae78-c458-1233-8384-60eb698211be CSeq: 79837879 INVITE Contact: <sip:754617702573728@y.y.y.y:5060;transport=udp> User-Agent: DNL-Switch Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO Supported: timer Content-Type: application/sdp Content-Length: 139 v=0 o=- 1440347904 1440347904 IN IP4 218.16.64.24 s=VOS2009 c=IN IP4 218.16.64.24 t=0 0 m=audio 8722 RTP/AVP 8 a=rtpmap:8 PCMA/8000 ------------------------------------------------------------------------ 2015-08-23 16:38:07.210310 [DEBUG] sofia.c:6715 Channel sofia/internal/754617702573728@y.y.y.y entering state [proceeding][183] 2015-08-23 16:38:07.210310 [DEBUG] sofia.c:6725 Remote SDP: v=0 o=- 1440347904 1440347904 IN IP4 218.16.64.24 s=VOS2009 c=IN IP4 218.16.64.24 t=0 0 m=audio 8722 RTP/AVP 8 a=rtpmap:8 PCMA/8000 2015-08-23 16:38:07.210310 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2015-08-23 16:38:07.210310 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2015-08-23 16:38:07.210310 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.210310 [DEBUG] switch_core_media.c:4142 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2015-08-23 16:38:07.210310 [DEBUG] switch_core_media.c:2871 Set Codec sofia/internal/754617702573728@y.y.y.y PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2015-08-23 16:38:07.210310 [DEBUG] switch_core_codec.c:111 sofia/internal/754617702573728@y.y.y.y Original read codec set to PCMA:8 2015-08-23 16:38:07.210310 [DEBUG] switch_core_media.c:4407 No 2833 in SDP. Disable 2833 dtmf and switch to INFO 2015-08-23 16:38:07.210310 [DEBUG] switch_core_media.c:5947 AUDIO RTP [sofia/internal/754617702573728@y.y.y.y] x.x.x.x port 26626 -> 218.16.64.24 port 8722 codec: 8 ms: 20 2015-08-23 16:38:07.210310 [DEBUG] switch_rtp.c:3694 Starting timer [soft] 160 bytes per 20ms 2015-08-23 16:38:07.210310 [DEBUG] switch_core_media.c:6274 sofia/internal/754617702573728@y.y.y.y Set rtp dtmf delay to 40 2015-08-23 16:38:07.210310 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/754617702573728@y.y.y.y! 2015-08-23 16:38:07.210310 [DEBUG] switch_channel.c:3460 (sofia/internal/754617702573728@y.y.y.y) Callstate Change DOWN -> EARLY 2015-08-23 16:38:07.230314 [DEBUG] switch_ivr_originate.c:413 Setting codec string on sofia/internal/1001@x.x.x.x:5060 to PCMA@8000h@20i 2015-08-23 16:38:07.230314 [INFO] switch_ivr_originate.c:3533 Sending early media 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [opus:111:48000:60:0:2]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [ILBC:102:8000:30:13330:1]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4142 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [CN:105:16000:60:0:1]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [CN:13:8000:60:0:1]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4087 Audio Codec Compare [red:127:8000:60:0:1]/[PCMA:8:8000:20:64000:1] 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4003 Set telephone-event payload to 126@8000 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:2871 Set Codec sofia/internal/1001@x.x.x.x:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2015-08-23 16:38:07.230314 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@x.x.x.x:5060 Original read codec set to PCMA:8 2015-08-23 16:38:07.230314 [WARNING] switch_core_media.c:3207 NO candidate ACL defined, Defaulting to wan.auto 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.1.100:55457 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 1 proto: udp type: host addr: 10.162.22.234:50345 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.1.100:52690 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 2 proto: udp type: host addr: 10.162.22.234:53413 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 1 proto: udp type: srflx addr: 163.125.130.79:26990 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 2 proto: udp type: srflx addr: 163.125.130.79:26991 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 1 proto: udp type: srflx addr: 112.97.55.70:59693 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 2 proto: udp type: srflx addr: 112.97.55.70:59694 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 1 proto: udp type: relay addr: 146.185.149.11:60858 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 2 proto: udp type: relay addr: 146.185.149.11:62660 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 2 proto: udp type: relay addr: 146.185.149.11:61723 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3236 Save audio Candidate cid: 1 proto: udp type: relay addr: 146.185.149.11:63902 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3276 Searching for rtp candidate. 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3281 Choose rtp candidate, index 2, 163.125.130.79:26990 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3099 sofia/internal/1001@x.x.x.x:5060 choosing family v4 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3292 Choose same candidate, index 6, for rtcp based on rtcp-mux attribute 163.125.130.79:26990 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3337 setting remote audio ice addr to index 2 163.125.130.79:26990 based on candidate 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:3358 Setting remote rtcp audio addr to 163.125.130.79:26990 based on candidate 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4346 Set telephone-event payload to 126@8000 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:4394 Set 2833 dtmf send/recv payload to 126 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:5947 AUDIO RTP [sofia/internal/1001@x.x.x.x:5060] x.x.x.x port 21834 -> 163.125.130.79 port 26990 codec: 8 ms: 20 2015-08-23 16:38:07.230314 [DEBUG] switch_rtp.c:3694 Starting timer [soft] 160 bytes per 20ms 2015-08-23 16:38:07.230314 [INFO] switch_core_media.c:6122 Activating Audio ICE 2015-08-23 16:38:07.230314 [NOTICE] switch_rtp.c:4213 Activating RTP audio ICE: PfQS5mTk8DdU0wfL:lxhtm2fsdIETo71R 163.125.130.79:26990 2015-08-23 16:38:07.230314 [INFO] switch_core_media.c:6165 Activating RTCP PORT 26990 2015-08-23 16:38:07.230314 [DEBUG] switch_rtp.c:4110 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 26990 2015-08-23 16:38:07.230314 [INFO] switch_core_media.c:6173 Skipping RTCP ICE (Same as RTP) 2015-08-23 16:38:07.230314 [INFO] switch_rtp.c:3214 Activate RTP/RTCP audio DTLS client 2015-08-23 16:38:07.230314 [INFO] switch_rtp.c:3343 Changing audio DTLS state from OFF to HANDSHAKE 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:6246 Set 2833 dtmf send payload to 126 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:6252 Set 2833 dtmf receive payload to 126 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:6274 sofia/internal/1001@x.x.x.x:5060 Set rtp dtmf delay to 40 2015-08-23 16:38:07.230314 [DEBUG] switch_core_media.c:6280 Set comfort noise payload to 13 2015-08-23 16:38:07.230314 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1001@x.x.x.x:5060! 2015-08-23 16:38:07.230314 [DEBUG] switch_channel.c:3460 (sofia/internal/1001@x.x.x.x:5060) Callstate Change RINGING -> EARLY 2015-08-23 16:38:07.230314 [DEBUG] mod_sofia.c:2293 Ring SDP: v=0 o=FreeSWITCH 1440326053 1440326054 IN IP4 x.x.x.x s=FreeSWITCH c=IN IP4 x.x.x.x t=0 0 a=msid-semantic: WMS CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi m=audio 21834 RTP/SAVPF 8 126 13 a=rtpmap:8 PCMA/8000 a=rtpmap:126 telephone-event/8000 a=rtpmap:13 CN/8000 a=ptime:20 a=sendonly a=fingerprint:sha-1 51:B3:65:D7:5A:E5:EF:90:24:DA:D6:07:BB:2F:AF:A4:73:57:AE:0A a=setup:active a=rtcp-mux a=rtcp:21834 IN IP4 x.x.x.x a=ice-ufrag:lxhtm2fsdIETo71R a=ice-pwd:pITF92vzI9QFEuRPSZ94SemW a=candidate:4917377022 1 udp 659136 x.x.x.x 21834 typ host generation 0 a=ssrc:1306378974 cname:DsRcqAiZAQOr6ENm a=ssrc:1306378974 msid:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a0 a=ssrc:1306378974 mslabel:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a=ssrc:1306378974 label:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDia0 2015-08-23 16:38:07.230314 [DEBUG] switch_ivr_originate.c:3584 Originate Resulted in Success: [sofia/internal/754617702573728@y.y.y.y] send 1773 bytes to tcp/[163.125.130.79]:26869 at 16:38:07.236989: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/TCP 192.168.1.100:5060 ;branch=z9hG4bK-524287-1---e4f7b616f75a9478;rport=26869;received=163.125.130.79 From: <sip:1001@x.x.x.x:5060>;tag=e090b871 To: <sip:1002@x.x.x.x:5060>;tag=HQmccgH0KDv9j Call-ID: YEXqB4WY4KZUOfmDabppqg.. CSeq: 2 INVITE Contact: <sip:1002@x.x.x.x:5060;transport=tcp> User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 809 Remote-Party-ID: "Outbound Call" <sip:754617702573728@x.x.x.x
;party=calling;privacy=off;screen=no
v=0 o=FreeSWITCH 1440326053 1440326054 IN IP4 x.x.x.x s=FreeSWITCH c=IN IP4 x.x.x.x t=0 0 a=msid-semantic: WMS CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi m=audio 21834 RTP/SAVPF 8 126 13 a=rtpmap:8 PCMA/8000 a=rtpmap:126 telephone-event/8000 a=rtpmap:13 CN/8000 a=sendonly a=ptime:20 a=fingerprint:sha-1 51:B3:65:D7:5A:E5:EF:90:24:DA:D6:07:BB:2F:AF:A4:73:57:AE:0A a=setup:active a=rtcp-mux a=rtcp:21834 IN IP4 x.x.x.x a=ice-ufrag:lxhtm2fsdIETo71R a=ice-pwd:pITF92vzI9QFEuRPSZ94SemW a=candidate:4917377022 1 udp 659136 x.x.x.x 21834 typ host generation 0 a=ssrc:1306378974 cname:DsRcqAiZAQOr6ENm a=ssrc:1306378974 msid:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a0 a=ssrc:1306378974 mslabel:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a=ssrc:1306378974 label:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDia0 ------------------------------------------------------------------------ 2015-08-23 16:38:07.230314 [DEBUG] sofia.c:6715 Channel sofia/internal/1001@x.x.x.x:5060 entering state [early][183] 2015-08-23 16:38:07.230314 [DEBUG] switch_ivr_bridge.c:1584 (sofia/internal/754617702573728@y.y.y.y) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2015-08-23 16:38:07.230314 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/754617702573728@y.y.y.y) Running State Change CS_EXCHANGE_MEDIA 2015-08-23 16:38:07.230314 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/754617702573728@y.y.y.y) State EXCHANGE_MEDIA 2015-08-23 16:38:07.230314 [DEBUG] mod_sofia.c:613 SOFIA EXCHANGE_MEDIA 2015-08-23 16:38:09.290278 [DEBUG] switch_rtp.c:6462 Correct audio ip/port confirmed. recv 635 bytes from udp/[y.y.y.y]:5060 at 16:38:16.729400: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKevaDa1tKZ99Uc From: "Extension 1001" <sip:17185345433@x.x.x.x>;tag=j0D5DB23gpjve To: <sip:754617702573728@y.y.y.y>;tag=1Xmp97vgtp22K Call-ID: 2c37ae78-c458-1233-8384-60eb698211be CSeq: 79837879 INVITE Contact: <sip:754617702573728@y.y.y.y:5060;transport=udp> User-Agent: DNL-Switch Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO Supported: timer Content-Type: application/sdp Content-Length: 139 v=0 o=- 1440347904 1440347904 IN IP4 218.16.64.24 s=VOS2009 c=IN IP4 218.16.64.24 t=0 0 m=audio 8722 RTP/AVP 8 a=rtpmap:8 PCMA/8000 ------------------------------------------------------------------------ 2015-08-23 16:38:16.710530 [DEBUG] sofia.c:6715 Channel sofia/internal/754617702573728@y.y.y.y entering state [completing][200] 2015-08-23 16:38:16.710530 [DEBUG] sofia.c:6722 Duplicate SDP v=0 o=- 1440347904 1440347904 IN IP4 218.16.64.24 s=VOS2009 c=IN IP4 218.16.64.24 t=0 0 m=audio 8722 RTP/AVP 8 a=rtpmap:8 PCMA/8000 send 418 bytes to udp/[y.y.y.y]:5060 at 16:38:16.733500: ------------------------------------------------------------------------ ACK sip:754617702573728@y.y.y.y:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKF535BvBQvj0er Max-Forwards: 70 From: "Extension 1001" <sip:17185345433@x.x.x.x>;tag=j0D5DB23gpjve To: <sip:754617702573728@y.y.y.y>;tag=1Xmp97vgtp22K Call-ID: 2c37ae78-c458-1233-8384-60eb698211be CSeq: 79837879 ACK Contact: <sip:mod_sofia@x.x.x.x:5060> Content-Length: 0 ------------------------------------------------------------------------ 2015-08-23 16:38:16.750300 [DEBUG] sofia.c:6715 Channel sofia/internal/754617702573728@y.y.y.y entering state [ready][200] 2015-08-23 16:38:16.750300 [NOTICE] sofia.c:7517 Channel [sofia/internal/754617702573728@y.y.y.y] has been answered 2015-08-23 16:38:16.750300 [DEBUG] switch_channel.c:3759 (sofia/internal/754617702573728@y.y.y.y) Callstate Change EARLY -> ACTIVE 2015-08-23 16:38:16.750300 [DEBUG] mod_sofia.c:799 Local SDP sofia/internal/1001@x.x.x.x:5060: v=0 o=FreeSWITCH 1440326053 1440326055 IN IP4 x.x.x.x s=FreeSWITCH c=IN IP4 x.x.x.x t=0 0 a=msid-semantic: WMS CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi m=audio 21834 RTP/SAVPF 8 126 13 a=rtpmap:8 PCMA/8000 a=rtpmap:126 telephone-event/8000 a=rtpmap:13 CN/8000 a=ptime:20 a=sendonly a=fingerprint:sha-1 51:B3:65:D7:5A:E5:EF:90:24:DA:D6:07:BB:2F:AF:A4:73:57:AE:0A a=setup:active a=rtcp-mux a=rtcp:21834 IN IP4 x.x.x.x a=ice-ufrag:lxhtm2fsdIETo71R a=ice-pwd:pITF92vzI9QFEuRPSZ94SemW a=candidate:9014549722 1 udp 659136 x.x.x.x 21834 typ host generation 0 a=ssrc:1306378974 cname:DsRcqAiZAQOr6ENm a=ssrc:1306378974 msid:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a0 a=ssrc:1306378974 mslabel:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a=ssrc:1306378974 label:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDia0 send 1786 bytes to tcp/[163.125.130.79]:26869 at 16:38:16.754777: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TCP 192.168.1.100:5060 ;branch=z9hG4bK-524287-1---e4f7b616f75a9478;rport=26869;received=163.125.130.79 From: <sip:1001@x.x.x.x:5060>;tag=e090b871 To: <sip:1002@x.x.x.x:5060>;tag=HQmccgH0KDv9j Call-ID: YEXqB4WY4KZUOfmDabppqg.. CSeq: 2 INVITE Contact: <sip:1002@x.x.x.x:5060;transport=tcp> User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Require: timer Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Session-Expires: 120;refresher=uac Content-Type: application/sdp Content-Disposition: session Content-Length: 809 Remote-Party-ID: "Outbound Call" <sip:754617702573728@x.x.x.x
;party=calling;privacy=off;screen=no
v=0 o=FreeSWITCH 1440326053 1440326054 IN IP4 x.x.x.x s=FreeSWITCH c=IN IP4 x.x.x.x t=0 0 a=msid-semantic: WMS CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi m=audio 21834 RTP/SAVPF 8 126 13 a=rtpmap:8 PCMA/8000 a=rtpmap:126 telephone-event/8000 a=rtpmap:13 CN/8000 a=sendonly a=ptime:20 a=fingerprint:sha-1 51:B3:65:D7:5A:E5:EF:90:24:DA:D6:07:BB:2F:AF:A4:73:57:AE:0A a=setup:active a=rtcp-mux a=rtcp:21834 IN IP4 x.x.x.x a=ice-ufrag:lxhtm2fsdIETo71R a=ice-pwd:pITF92vzI9QFEuRPSZ94SemW a=candidate:4917377022 1 udp 659136 x.x.x.x 21834 typ host generation 0 a=ssrc:1306378974 cname:DsRcqAiZAQOr6ENm a=ssrc:1306378974 msid:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a0 a=ssrc:1306378974 mslabel:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a=ssrc:1306378974 label:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDia0 ------------------------------------------------------------------------ 2015-08-23 16:38:16.770314 [DEBUG] sofia.c:6715 Channel sofia/internal/1001@x.x.x.x:5060 entering state [completed][200] 2015-08-23 16:38:16.810331 [DEBUG] switch_rtp.c:6462 Correct audio ip/port confirmed. send 1786 bytes to tcp/[163.125.130.79]:26869 at 16:38:17.254967: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TCP 192.168.1.100:5060 ;branch=z9hG4bK-524287-1---e4f7b616f75a9478;rport=26869;received=163.125.130.79 From: <sip:1001@x.x.x.x:5060>;tag=e090b871 To: <sip:1002@x.x.x.x:5060>;tag=HQmccgH0KDv9j Call-ID: YEXqB4WY4KZUOfmDabppqg.. CSeq: 2 INVITE Contact: <sip:1002@x.x.x.x:5060;transport=tcp> User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Require: timer Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Session-Expires: 120;refresher=uac Content-Type: application/sdp Content-Disposition: session Content-Length: 809 Remote-Party-ID: "Outbound Call" <sip:754617702573728@x.x.x.x
;party=calling;privacy=off;screen=no
v=0 o=FreeSWITCH 1440326053 1440326054 IN IP4 x.x.x.x s=FreeSWITCH c=IN IP4 x.x.x.x t=0 0 a=msid-semantic: WMS CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi m=audio 21834 RTP/SAVPF 8 126 13 a=rtpmap:8 PCMA/8000 a=rtpmap:126 telephone-event/8000 a=rtpmap:13 CN/8000 a=sendonly a=ptime:20 a=fingerprint:sha-1 51:B3:65:D7:5A:E5:EF:90:24:DA:D6:07:BB:2F:AF:A4:73:57:AE:0A a=setup:active a=rtcp-mux a=rtcp:21834 IN IP4 x.x.x.x a=ice-ufrag:lxhtm2fsdIETo71R a=ice-pwd:pITF92vzI9QFEuRPSZ94SemW a=candidate:4917377022 1 udp 659136 x.x.x.x 21834 typ host generation 0 a=ssrc:1306378974 cname:DsRcqAiZAQOr6ENm a=ssrc:1306378974 msid:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a0 a=ssrc:1306378974 mslabel:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDi a=ssrc:1306378974 label:CWZJJ7SfJaIY0q0wpQU5iYUVflDmPQDia0 ------------------------------------------------------------------------ recv 425 bytes from tcp/[163.125.130.79]:26869 at 16:38:17.263429: ------------------------------------------------------------------------ ACK sip:1002@x.x.x.x:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 192.168.1.100:5060 ;branch=z9hG4bK-524287-1---c562871456b95e64;rport Max-Forwards: 70 Contact: <sip:1001@192.168.1.100:5060 ;transport=tcp>;+sip.instance="1001@x.x.x.x:5060" To: <sip:1002@x.x.x.x:5060>;tag=HQmccgH0KDv9j From: <sip:1001@x.x.x.x:5060>;tag=e090b871 Call-ID: YEXqB4WY4KZUOfmDabppqg.. CSeq: 2 ACK Content-Length: 0 ------------------------------------------------------------------------ 2015-08-23 16:38:17.270321 [DEBUG] sofia.c:6715 Channel sofia/internal/1001@x.x.x.x:5060 entering state [ready][200] 2015-08-23 16:38:17.670308 [NOTICE] switch_rtp.c:1175 Auto Changing audio stun/rtp/dtls port from 163.125.130.79:26990 to 112.97.55.70:59693 recv 425 bytes from tcp/[163.125.130.79]:26869 at 16:38:17.692357: ------------------------------------------------------------------------ ACK sip:1002@x.x.x.x:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 192.168.1.100:5060 ;branch=z9hG4bK-524287-1---c562871456b95e64;rport Max-Forwards: 70 Contact: <sip:1001@192.168.1.100:5060 ;transport=tcp>;+sip.instance="1001@x.x.x.x:5060" To: <sip:1002@x.x.x.x:5060>;tag=HQmccgH0KDv9j From: <sip:1001@x.x.x.x:5060>;tag=e090b871 Call-ID: YEXqB4WY4KZUOfmDabppqg.. CSeq: 2 ACK Content-Length: 0 ------------------------------------------------------------------------ 2015-08-23 16:38:19.510341 [INFO] switch_rtp.c:3007 Changing audio DTLS state from HANDSHAKE to SETUP 2015-08-23 16:38:19.510341 [INFO] switch_rtp.c:2916 audio Fingerprint Verified. 2015-08-23 16:38:19.510341 [INFO] switch_rtp.c:3506 Activating Audio Secure RTP SEND 2015-08-23 16:38:19.510341 [INFO] switch_rtp.c:3484 Activating Audio Secure RTP RECV 2015-08-23 16:38:19.510341 [INFO] switch_rtp.c:2956 Changing audio DTLS state from SETUP to READY 2015-08-23 16:38:19.510341 [DEBUG] switch_core_sqldb.c:2607 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 2015-08-23 16:38:19.510341 [DEBUG] switch_core_sqldb.c:2607 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 2015-08-23 16:38:19.510341 [NOTICE] switch_ivr_bridge.c:609 Channel [sofia/internal/1001@x.x.x.x:5060] has been answered 2015-08-23 16:38:19.510341 [DEBUG] switch_channel.c:3759 (sofia/internal/1001@x.x.x.x:5060) Callstate Change EARLY -> ACTIVE freeswitch@switch3> freeswitch@switch3> freeswitch@switch3> freeswitch@switch3> freeswitch@switch3> recv 737 bytes from tcp/[163.125.130.79]:26869 at 16:38:29.607614: ------------------------------------------------------------------------ BYE sip:1002@x.x.x.x:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 192.168.1.100:5060 ;branch=z9hG4bK-524287-1---c2b8e25850437d1d;rport Max-Forwards: 70 Contact: <sip:1001@192.168.1.100:5060 ;transport=tcp>;+sip.instance="1001@x.x.x.x:5060" To: <sip:1002@x.x.x.x:5060>;tag=HQmccgH0KDv9j From: <sip:1001@x.x.x.x:5060>;tag=e090b871 Call-ID: YEXqB4WY4KZUOfmDabppqg.. CSeq: 3 BYE Proxy-Authorization: Digest username="1001",realm="x.x.x.x",nonce="54896b80-49b5-11e5-ba06-3bd1ff35cbfe",uri="sip:1002@x.x.x.x :5060;transport=tcp",response="e01d7fbc84b3375e271c041aac302718",cnonce="061dd974e10e224a6d5cad294f14cf21",nc=00000002,qop=auth,algorithm=MD5 Reason: SIP;text=User Hung Up Content-Length: 0 ------------------------------------------------------------------------ 2015-08-23 16:38:29.610343 [NOTICE] sofia.c:952 Hangup sofia/internal/1001@x.x.x.x:5060 [CS_EXECUTE] [NORMAL_CLEARING] send 526 bytes to tcp/[163.125.130.79]:26869 at 16:38:29.615254: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TCP 192.168.1.100:5060 ;branch=z9hG4bK-524287-1---c2b8e25850437d1d;rport=26869;received=163.125.130.79 From: <sip:1001@x.x.x.x:5060>;tag=e090b871 To: <sip:1002@x.x.x.x:5060>;tag=HQmccgH0KDv9j Call-ID: YEXqB4WY4KZUOfmDabppqg.. CSeq: 3 BYE User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2015-08-23 16:38:29.610343 [DEBUG] switch_ivr_bridge.c:771 BRIDGE THREAD DONE [sofia/internal/1001@x.x.x.x:5060] 2015-08-23 16:38:29.630370 [DEBUG] switch_ivr_bridge.c:771 BRIDGE THREAD DONE [sofia/internal/754617702573728@y.y.y.y] 2015-08-23 16:38:29.630370 [NOTICE] switch_ivr_bridge.c:874 Hangup sofia/internal/754617702573728@y.y.y.y [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/754617702573728@y.y.y.y) State EXCHANGE_MEDIA going to sleep 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/754617702573728@y.y.y.y) Running State Change CS_HANGUP 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/754617702573728@y.y.y.y) Callstate Change ACTIVE -> HANGUP 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/754617702573728@y.y.y.y) State HANGUP 2015-08-23 16:38:29.630370 [DEBUG] mod_sofia.c:425 sofia/internal/754617702573728@y.y.y.y Overriding SIP cause 480 with 200 from the other leg 2015-08-23 16:38:29.630370 [DEBUG] mod_sofia.c:431 Channel sofia/internal/754617702573728@y.y.y.y hanging up, cause: NORMAL_CLEARING 2015-08-23 16:38:29.630370 [DEBUG] switch_ivr_bridge.c:1682 sofia/internal/754617702573728@y.y.y.y skip receive message [UNBRIDGE] (channel is hungup already) 2015-08-23 16:38:29.630370 [DEBUG] switch_ivr_bridge.c:1685 sofia/internal/1001@x.x.x.x:5060 skip receive message [UNBRIDGE] (channel is hungup already) 2015-08-23 16:38:29.630370 [DEBUG] switch_core_session.c:2926 sofia/internal/1001@x.x.x.x:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1001@x.x.x.x:5060) State EXECUTE going to sleep 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@x.x.x.x:5060) Running State Change CS_HANGUP 2015-08-23 16:38:29.630370 [DEBUG] mod_sofia.c:484 Sending BYE to sofia/internal/754617702573728@y.y.y.y 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:60 sofia/internal/754617702573728@y.y.y.y Standard HANGUP, cause: NORMAL_CLEARING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/754617702573728@y.y.y.y) State HANGUP going to sleep 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/754617702573728@y.y.y.y) State Change CS_HANGUP -> CS_REPORTING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/754617702573728@y.y.y.y) Running State Change CS_REPORTING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1001@x.x.x.x:5060) Callstate Change ACTIVE -> HANGUP 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/754617702573728@y.y.y.y) State REPORTING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:104 sofia/internal/754617702573728@y.y.y.y Standard REPORTING, cause: NORMAL_CLEARING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/754617702573728@y.y.y.y) State REPORTING going to sleep 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1001@x.x.x.x:5060) State HANGUP 2015-08-23 16:38:29.630370 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1001@x.x.x.x:5060 hanging up, cause: NORMAL_CLEARING send 642 bytes to udp/[y.y.y.y]:5060 at 16:38:29.633670: ------------------------------------------------------------------------ BYE sip:754617702573728@y.y.y.y:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKgeXyDQvtSUp1K Max-Forwards: 70 From: "Extension 1001" <sip:17185345433@x.x.x.x>;tag=j0D5DB23gpjve To: <sip:754617702573728@y.y.y.y>;tag=1Xmp97vgtp22K Call-ID: 2c37ae78-c458-1233-8384-60eb698211be CSeq: 79837880 BYE User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/754617702573728@y.y.y.y) State Change CS_REPORTING -> CS_DESTROY 2015-08-23 16:38:29.630370 [DEBUG] switch_core_session.c:1639 Session 5 (sofia/internal/754617702573728@y.y.y.y) Locked, Waiting on external entities 2015-08-23 16:38:29.630370 [NOTICE] switch_core_session.c:1657 Session 5 (sofia/internal/754617702573728@y.y.y.y) Ended 2015-08-23 16:38:29.630370 [NOTICE] switch_core_session.c:1661 Close Channel sofia/internal/754617702573728@y.y.y.y [CS_DESTROY] 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@x.x.x.x:5060 Standard HANGUP, cause: NORMAL_CLEARING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1001@x.x.x.x:5060) State HANGUP going to sleep 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1001@x.x.x.x:5060) State Change CS_HANGUP -> CS_REPORTING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/754617702573728@y.y.y.y) Running State Change CS_DESTROY 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@x.x.x.x:5060) Running State Change CS_REPORTING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/754617702573728@y.y.y.y) State DESTROY 2015-08-23 16:38:29.630370 [DEBUG] mod_sofia.c:341 sofia/internal/754617702573728@y.y.y.y SOFIA DESTROY 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1001@x.x.x.x:5060) State REPORTING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:111 sofia/internal/754617702573728@y.y.y.y Standard DESTROY 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/754617702573728@y.y.y.y) State DESTROY going to sleep 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1001@x.x.x.x:5060 Standard REPORTING, cause: NORMAL_CLEARING 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1001@x.x.x.x:5060) State REPORTING going to sleep 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1001@x.x.x.x:5060) State Change CS_REPORTING -> CS_DESTROY 2015-08-23 16:38:29.630370 [DEBUG] switch_core_session.c:1639 Session 4 (sofia/internal/1001@x.x.x.x:5060) Locked, Waiting on external entities 2015-08-23 16:38:29.630370 [NOTICE] switch_core_session.c:1657 Session 4 (sofia/internal/1001@x.x.x.x:5060) Ended 2015-08-23 16:38:29.630370 [NOTICE] switch_core_session.c:1661 Close Channel sofia/internal/1001@x.x.x.x:5060 [CS_DESTROY] recv 394 bytes from udp/[y.y.y.y]:5060 at 16:38:29.634664: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKgeXyDQvtSUp1K From: "Extension 1001" <sip:17185345433@x.x.x.x>;tag=j0D5DB23gpjve To: <sip:754617702573728@y.y.y.y>;tag=1Xmp97vgtp22K Call-ID: 2c37ae78-c458-1233-8384-60eb698211be CSeq: 79837880 BYE User-Agent: DNL-Switch Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO Supported: timer Content-Length: 0 ------------------------------------------------------------------------ 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1001@x.x.x.x:5060) Running State Change CS_DESTROY 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@x.x.x.x:5060) State DESTROY 2015-08-23 16:38:29.630370 [DEBUG] mod_sofia.c:341 sofia/internal/1001@x.x.x.x:5060 SOFIA DESTROY 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1001@x.x.x.x:5060 Standard DESTROY 2015-08-23 16:38:29.630370 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@x.x.x.x:5060) State DESTROY going to sleep On Sun, Aug 23, 2015 at 9:56 AM, Anthony Minessale < anthony.minessale@gmail.com> wrote:
Try reverting your config to defaults. You are clearly lost in some config changes.
On Sun, Aug 23, 2015 at 10:52 AM, Ken Rice <krice@freeswitch.org> wrote:
Don’t set proxy media or bypass media, these are special modes that do not fix up the RTP at all… just use the default modes
*From:* freeswitch-users-bounces@lists.freeswitch.org [mailto: freeswitch-users-bounces@lists.freeswitch.org] *On Behalf Of *Pete Kay *Sent:* Sunday, August 23, 2015 10:41 AM *To:* FreeSWITCH Users Help <freeswitch-users@lists.freeswitch.org> *Subject:* Re: [Freeswitch-users] Need help to convert RTP/SAVP to RTP/AVP
Hi
I changed the internal profile to proxy media now, and the o= line in the sdp is now showing FREESWITCH, but it is still using SAVPF...
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1001@x.x.x.x:5060 Standard ROUTING
2015-08-23 15:34:14.714657 [INFO] mod_dialplan_xml.c:637 Processing 1001 <1001>->1002 in context default
Dialplan: sofia/internal/1001@x.x.x.x:5060 parsing [default->incoming_calls] continue=false
Dialplan: sofia/internal/1001@x.x.x.x:5060 Regex (PASS) [incoming_calls] ${destination_number}(1002) =~ /1002/ break=on-false
Dialplan: sofia/internal/1001@x.x.x.x:5060 Action bridge({rtp_allow_crypto_in_avp=false,rtp_secure_media=false,sip_secure_mediai_outbound=false}[rtp_allow_crypto_in_avp=false,sip_secure_media_outbound=false,sdp_secure_savp_only=false] sofia/internal/754617162742492@y.y.y.y)
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:216 ( sofia/internal/1001@x.x.x.x:5060) State Change CS_ROUTING -> CS_EXECUTE
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:532 ( sofia/internal/1001@x.x.x.x:5060) State ROUTING going to sleep
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/1001@x.x.x.x:5060) Running State Change CS_EXECUTE
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:539 ( sofia/internal/1001@x.x.x.x:5060) State EXECUTE
2015-08-23 15:34:14.714657 [DEBUG] mod_sofia.c:196 sofia/internal/1001@x.x.x.x:5060 SOFIA EXECUTE
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1001@x.x.x.x:5060 Standard EXECUTE
EXECUTE sofia/internal/1001@x.x.x.x:5060 bridge({rtp_allow_crypto_in_avp=false,rtp_secure_media=false,sip_secure_mediai_outbound=false}[rtp_allow_crypto_in_avp=false,sip_secure_media_outbound=false,sdp_secure_savp_only=false] sofia/internal/754617162742492@y.y.y.y)
2015-08-23 15:34:14.714657 [DEBUG] switch_ivr_originate.c:2104 Parsing global variables
2015-08-23 15:34:14.714657 [DEBUG] switch_ivr_originate.c:2557 Parsing session specific variables
2015-08-23 15:34:14.714657 [NOTICE] switch_channel.c:1089 New Channel sofia/internal/754617162742492@y.y.y.y [68de50a4-49ac-11e5-b118-6fcd62a30179]
2015-08-23 15:34:14.714657 [DEBUG] mod_sofia.c:4735 ( sofia/internal/754617162742492@y.y.y.y) State Change CS_NEW -> CS_INIT
2015-08-23 15:34:14.714657 [DEBUG] mod_sofia.c:4805 [zrtp_passthru] Setting a-leg inherit_codec=true
2015-08-23 15:34:14.714657 [DEBUG] mod_sofia.c:4808 [zrtp_passthru] Setting b-leg absolute_codec_string='mod_spandsp.G722@8000h@20i@64000b ,CORE_PCM_MODULE.PCMU@8000h@20i@64000b,CORE_PCM_MODULE.PCMA@8000h @20i@64000b'
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/754617162742492@y.y.y.y) Running State Change CS_INIT
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:516 ( sofia/internal/754617162742492@y.y.y.y) State INIT
2015-08-23 15:34:14.714657 [DEBUG] mod_sofia.c:88 sofia/internal/754617162742492@y.y.y.y SOFIA INIT
2015-08-23 15:34:14.714657 [DEBUG] switch_core_media.c:8667 sofia/internal/754617162742492@y.y.y.y Patched SDP
---
v=0
o=- 3653435483206582716 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 9 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 0.0.0.0
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:duN/nld3Ywx4sKCI
a=ice-pwd:yiG5QkmXTg1NNO5qIUtVLKRk
a=fingerprint:sha-1 84:83:7B:9E:8F:B5:A6:F9:F1:80:F3:DC:60:6E:0A:78:49:46:49:AA
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:3490757364 cname:j5lQWpsyZUv+UlSb
a=ssrc:3490757364 msid:media_stream audio_track
a=ssrc:3490757364 mslabel:media_stream
a=ssrc:3490757364 label:audio_track
a=candidate:3013953624 1 udp 2122260223 192.168.1.100 54468 typ host generation 0
a=candidate:2117845908 1 udp 2122194687 10.162.22.234 65466 typ host generation 0
a=candidate:3013953624 2 udp 2122260222 192.168.1.100 64051 typ host generation 0
a=candidate:2117845908 2 udp 2122194686 10.162.22.234 49167 typ host generation 0
a=candidate:4247172264 1 tcp 1518280447 192.168.1.100 49288 typ host tcptype passive generation 0
a=candidate:817381220 1 tcp 1518214911 10.162.22.234 49289 typ host tcptype passive generation 0
a=candidate:4247172264 2 tcp 1518280446 192.168.1.100 49290 typ host tcptype passive generation 0
a=candidate:817381220 2 tcp 1518214910 10.162.22.234 49291 typ host tcptype passive generation 0
a=candidate:4285777184 1 udp 1685987071 112.97.55.70 59590 typ srflx raddr 10.162.22.234 rport 65466 generation 0
a=candidate:4285777184 2 udp 1685987070 112.97.55.70 59591 typ srflx raddr 10.162.22.234 rport 49167 generation 0
a=candidate:854413036 1 udp 1686052607 163.125.130.79 21981 typ srflx raddr 192.168.1.100 rport 54468 generation 0
a=candidate:854413036 2 udp 1686052606 163.125.130.79 21982 typ srflx raddr 192.168.1.100 rport 64051 generation 0
a=candidate:2922827951 2 udp 41819902 146.185.149.11 63674 typ relay raddr 112.97.51.70 rport 41768 generation 0
a=candidate:2922827951 1 udp 41885439 146.185.149.11 54428 typ relay raddr 163.125.130.79 rport 21981 generation 0
a=candidate:2922827951 1 udp 41819903 146.185.149.11 49815 typ relay raddr 112.97.51.70 rport 41769 generation 0
a=candidate:2922827951 2 udp 41885438 146.185.149.11 64960 typ relay raddr 163.125.130.79 rport 21982 generation 0
+++
v=0
o=FreeSWITCH 1592140567 1592140568 IN IP4 x.x.x.x
s=FreeSWITCH
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 25336 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 x.x.x.x
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:duN/nld3Ywx4sKCI
a=ice-pwd:yiG5QkmXTg1NNO5qIUtVLKRk
a=fingerprint:sha-1 84:83:7B:9E:8F:B5:A6:F9:F1:80:F3:DC:60:6E:0A:78:49:46:49:AA
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:3490757364 cname:j5lQWpsyZUv+UlSb
a=ssrc:3490757364 msid:media_stream audio_track
a=ssrc:3490757364 mslabel:media_stream
a=ssrc:3490757364 label:audio_track
a=candidate:3013953624 1 udp 2122260223 192.168.1.100 54468 typ host generation 0
a=candidate:2117845908 1 udp 2122194687 10.162.22.234 65466 typ host generation 0
a=candidate:3013953624 2 udp 2122260222 192.168.1.100 64051 typ host generation 0
a=candidate:2117845908 2 udp 2122194686 10.162.22.234 49167 typ host generation 0
a=candidate:4247172264 1 tcp 1518280447 192.168.1.100 49288 typ host tcptype passive generation 0
a=candidate:817381220 1 tcp 1518214911 10.162.22.234 49289 typ host tcptype passive generation 0
a=candidate:4247172264 2 tcp 1518280446 192.168.1.100 49290 typ host tcptype passive generation 0
a=candidate:817381220 2 tcp 1518214910 10.162.22.234 49291 typ host tcptype passive generation 0
a=candidate:4285777184 1 udp 1685987071 112.97.55.70 59590 typ srflx raddr 10.162.22.234 rport 65466 generation 0
a=candidate:4285777184 2 udp 1685987070 112.97.55.70 59591 typ srflx raddr 10.162.22.234 rport 49167 generation 0
a=candidate:854413036 1 udp 1686052607 163.125.130.79 21981 typ srflx raddr 192.168.1.100 rport 54468 generation 0
a=candidate:854413036 2 udp 1686052606 163.125.130.79 21982 typ srflx raddr 192.168.1.100 rport 64051 generation 0
a=candidate:2922827951 2 udp 41819902 146.185.149.11 63674 typ relay raddr 112.97.51.70 rport 41768 generation 0
a=candidate:2922827951 1 udp 41885439 146.185.149.11 54428 typ relay raddr 163.125.130.79 rport 21981 generation 0
a=candidate:2922827951 1 udp 41819903 146.185.149.11 49815 typ relay raddr 112.97.51.70 rport 41769 generation 0
a=candidate:2922827951 2 udp 41885438 146.185.149.11 64960 typ relay raddr 163.125.130.79 rport 21982 generation 0
2015-08-23 15:34:14.714657 [DEBUG] sofia_glue.c:1257 sofia/internal/754617162742492@y.y.y.y sending invite version: 1.7.0 git 58f1272 2015-08-14 17:51:12Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1592140567 1592140568 IN IP4 x.x.x.x
s=FreeSWITCH
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 25336 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 x.x.x.x
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:duN/nld3Ywx4sKCI
a=ice-pwd:yiG5QkmXTg1NNO5qIUtVLKRk
a=fingerprint:sha-1 84:83:7B:9E:8F:B5:A6:F9:F1:80:F3:DC:60:6E:0A:78:49:46:49:AA
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:3490757364 cname:j5lQWpsyZUv+UlSb
a=ssrc:3490757364 msid:media_stream audio_track
a=ssrc:3490757364 mslabel:media_stream
a=ssrc:3490757364 label:audio_track
a=candidate:3013953624 1 udp 2122260223 192.168.1.100 54468 typ host generation 0
a=candidate:2117845908 1 udp 2122194687 10.162.22.234 65466 typ host generation 0
a=candidate:3013953624 2 udp 2122260222 192.168.1.100 64051 typ host generation 0
a=candidate:2117845908 2 udp 2122194686 10.162.22.234 49167 typ host generation 0
a=candidate:4247172264 1 tcp 1518280447 192.168.1.100 49288 typ host tcptype passive generation 0
a=candidate:817381220 1 tcp 1518214911 10.162.22.234 49289 typ host tcptype passive generation 0
a=candidate:4247172264 2 tcp 1518280446 192.168.1.100 49290 typ host tcptype passive generation 0
a=candidate:817381220 2 tcp 1518214910 10.162.22.234 49291 typ host tcptype passive generation 0
a=candidate:4285777184 1 udp 1685987071 112.97.55.70 59590 typ srflx raddr 10.162.22.234 rport 65466 generation 0
a=candidate:4285777184 2 udp 1685987070 112.97.55.70 59591 typ srflx raddr 10.162.22.234 rport 49167 generation 0
a=candidate:854413036 1 udp 1686052607 163.125.130.79 21981 typ srflx raddr 192.168.1.100 rport 54468 generation 0
a=candidate:854413036 2 udp 1686052606 163.125.130.79 21982 typ srflx raddr 192.168.1.100 rport 64051 generation 0
a=candidate:2922827951 2 udp 41819902 146.185.149.11 63674 typ relay raddr 112.97.51.70 rport 41768 generation 0
a=candidate:2922827951 1 udp 41885439 146.185.149.11 54428 typ relay raddr 163.125.130.79 rport 21981 generation 0
a=candidate:2922827951 1 udp 41819903 146.185.149.11 49815 typ relay raddr 112.97.51.70 rport 41769 generation 0
a=candidate:2922827951 2 udp 41885438 146.185.149.11 64960 typ relay raddr 163.125.130.79 rport 21982 generation 0
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:40 sofia/internal/754617162742492@y.y.y.y Standard INIT
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:48 ( sofia/internal/754617162742492@y.y.y.y) State Change CS_INIT -> CS_ROUTING
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:516 ( sofia/internal/754617162742492@y.y.y.y) State INIT going to sleep
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/754617162742492@y.y.y.y) Running State Change CS_ROUTING
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:532 ( sofia/internal/754617162742492@y.y.y.y) State ROUTING
2015-08-23 15:34:14.714657 [DEBUG] mod_sofia.c:141 sofia/internal/754617162742492@y.y.y.y SOFIA ROUTING
2015-08-23 15:34:14.714657 [DEBUG] switch_ivr_originate.c:67 ( sofia/internal/754617162742492@y.y.y.y) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:532 ( sofia/internal/754617162742492@y.y.y.y) State ROUTING going to sleep
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/754617162742492@y.y.y.y) Running State Change CS_CONSUME_MEDIA
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:551 ( sofia/internal/754617162742492@y.y.y.y) State CONSUME_MEDIA
2015-08-23 15:34:14.714657 [DEBUG] switch_core_state_machine.c:551 ( sofia/internal/754617162742492@y.y.y.y) State CONSUME_MEDIA going to sleep
send 3675 bytes to udp/[y.y.y.y]:5060 at 15:34:14.728794:
------------------------------------------------------------------------
INVITE sip:754617162742492@y.y.y.y SIP/2.0
Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKKQvQ32p77mX3K
Max-Forwards: 69
From: "Extension 1001" <sip:1001@x.x.x.x>;tag=Q3F772K7rmctc
To: <sip:754617162742492@y.y.y.y>
Call-ID: 403cbc44-c44f-1233-ba8f-60eb698211be
CSeq: 79835963 INVITE
Contact: <sip:mod_sofia@x.x.x.x:5060>
User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 2705
X-FS-Support: update_display,send_info
Remote-Party-ID: "Extension 1001" <sip:1001@x.x.x.x
;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1592140567 1592140568 IN IP4 x.x.x.x
s=FreeSWITCH
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 25336 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 x.x.x.x
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:duN/nld3Ywx4sKCI
a=ice-pwd:yiG5QkmXTg1NNO5qIUtVLKRk
a=fingerprint:sha-1 84:83:7B:9E:8F:B5:A6:F9:F1:80:F3:DC:60:6E:0A:78:49:46:49:AA
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:3490757364 cname:j5lQWpsyZUv+UlSb
a=ssrc:3490757364 msid:media_stream audio_track
a=ssrc:3490757364 mslabel:media_stream
a=ssrc:3490757364 label:audio_track
a=candidate:3013953624 1 udp 2122260223 192.168.1.100 54468 typ host generation 0
a=candidate:2117845908 1 udp 2122194687 10.162.22.234 65466 typ host generation 0
a=candidate:3013953624 2 udp 2122260222 192.168.1.100 64051 typ host generation 0
a=candidate:2117845908 2 udp 2122194686 10.162.22.234 49167 typ host generation 0
a=candidate:4247172264 1 tcp 1518280447 192.168.1.100 49288 typ host tcptype passive generation 0
a=candidate:817381220 1 tcp 1518214911 10.162.22.234 49289 typ host tcptype passive generation 0
a=candidate:4247172264 2 tcp 1518280446 192.168.1.100 49290 typ host tcptype passive generation 0
a=candidate:817381220 2 tcp 1518214910 10.162.22.234 49291 typ host tcptype passive generation 0
a=candidate:4285777184 1 udp 1685987071 112.97.55.70 59590 typ srflx raddr 10.162.22.234 rport 65466 generation 0
a=candidate:4285777184 2 udp 1685987070 112.97.55.70 59591 typ srflx raddr 10.162.22.234 rport 49167 generation 0
a=candidate:854413036 1 udp 1686052607 163.125.130.79 21981 typ srflx raddr 192.168.1.100 rport 54468 generation 0
a=candidate:854413036 2 udp 1686052606 163.125.130.79 21982 typ srflx raddr 192.168.1.100 rport 64051 generation 0
a=candidate:2922827951 2 udp 41819902 146.185.149.11 63674 typ relay raddr 112.97.51.70 rport 41768 generation 0
a=candidate:2922827951 1 udp 41885439 146.185.149.11 54428 typ relay raddr 163.125.130.79 rport 21981 generation 0
a=candidate:2922827951 1 udp 41819903 146.185.149.11 49815 typ relay raddr 112.97.51.70 rport 41769 generation 0
a=candidate:2922827951 2 udp 41885438 146.185.149.11 64960 typ relay raddr 163.125.130.79 rport 21982 generation 0
------------------------------------------------------------------------
2015-08-23 15:34:14.714657 [DEBUG] sofia.c:6715 Channel sofia/internal/754617162742492@y.y.y.y entering state [calling][0]
recv 308 bytes from udp/[y.y.y.y]:5060 at 15:34:14.730001:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKKQvQ32p77mX3K
From: "Extension 1001" <sip:1001@x.x.x.x>;tag=Q3F772K7rmctc
To: <sip:754617162742492@y.y.y.y>
Call-ID: 403cbc44-c44f-1233-ba8f-60eb698211be
CSeq: 79835963 INVITE
User-Agent: DNL-Switch
Content-Length: 0
------------------------------------------------------------------------
recv 433 bytes from udp/[y.y.y.y]:5060 at 15:34:14.740478:
------------------------------------------------------------------------
SIP/2.0 415 Unsupported Media Type
Via: SIP/2.0/UDP x.x.x.x;rport;branch=z9hG4bKKQvQ32p77mX3K
From: "Extension 1001" <sip:1001@x.x.x.x>;tag=Q3F772K7rmctc
To: <sip:754617162742492@y.y.y.y>;tag=454F3aHa1Fa5e
Call-ID: 403cbc44-c44f-1233-ba8f-60eb698211be
CSeq: 79835963 INVITE
User-Agent: DNL-Switch
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO
Supported: timer
Content-Length: 0
------------------------------------------------------------------------
On Sun, Aug 23, 2015 at 8:05 AM, Anthony Minessale < anthony.minessale@gmail.com> wrote:
You still have bypass set somehow.
Not the local sdp is not a native FS sdp the bundle and the lack of the word freeswitch in the o line.
Maybe you have bypass enabled as a sofia profile param.
On Saturday, August 22, 2015, Pete Kay <petedao@gmail.com> wrote:
Hi
I did anothe test without proxy_media=true, but I am still getting SAVPF in the outgoing INVITE. The problem is my outbound SIP gateway does not accept SAVPF so I am trying to configure Freeswitch to send it out as AVPF.
Here is the log:
2015-08-22 10:11:43.049286 [INFO] mod_dialplan_xml.c:637 Processing 1001 <1001>->1002 in context default
Dialplan: sofia/internal/1001@y.y.y.y:5060 parsing [default->incoming_calls] continue=false
Dialplan: sofia/internal/1001@y.y.y.y:5060 Regex (PASS) [incoming_calls] ${destination_number}(1002) =~ /1002/ break=on-false
Dialplan: sofia/internal/1001@y.y.y.y:5060 Action bridge({rtp_allow_crypto_in_avp=false,rtp_secure_media=false,sip_secure_mediai_outbound=false}[rtp_allow_crypto_in_avp=false,sip_secure_media_outbound=false,sdp_secure_savp_only=false] sofia/external/754617162742492@x.x.x.x)
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:216 ( sofia/internal/1001@y.y.y.y:5060) State Change CS_ROUTING -> CS_EXECUTE
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:532 ( sofia/internal/1001@y.y.y.y:5060) State ROUTING going to sleep
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/1001@y.y.y.y:5060) Running State Change CS_EXECUTE
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:539 ( sofia/internal/1001@y.y.y.y:5060) State EXECUTE
2015-08-22 10:11:43.049286 [DEBUG] mod_sofia.c:196 sofia/internal/1001@y.y.y.y:5060 SOFIA EXECUTE
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1001@y.y.y.y:5060 Standard EXECUTE
EXECUTE sofia/internal/1001@y.y.y.y:5060 bridge({rtp_allow_crypto_in_avp=false,rtp_secure_media=false,sip_secure_mediai_outbound=false}[rtp_allow_crypto_in_avp=false,sip_secure_media_outbound=false,sdp_secure_savp_only=false] sofia/external/754617162742492@x.x.x.x)
2015-08-22 10:11:43.049286 [DEBUG] switch_ivr_originate.c:2104 Parsing global variables
2015-08-22 10:11:43.049286 [DEBUG] switch_ivr_originate.c:2557 Parsing session specific variables
2015-08-22 10:11:43.049286 [NOTICE] switch_channel.c:1089 New Channel sofia/external/754617162742492@x.x.x.x [2ff7c01e-48b6-11e5-ac58-01226f03e80c]
2015-08-22 10:11:43.049286 [DEBUG] mod_sofia.c:4735 ( sofia/external/754617162742492@x.x.x.x) State Change CS_NEW -> CS_INIT
2015-08-22 10:11:43.049286 [DEBUG] mod_sofia.c:4805 [zrtp_passthru] Setting a-leg inherit_codec=true
2015-08-22 10:11:43.049286 [DEBUG] mod_sofia.c:4808 [zrtp_passthru] Setting b-leg absolute_codec_string='mod_spandsp.G722@8000h@20i@64000b ,CORE_PCM_MODULE.PCMU@8000h@20i@64000b,CORE_PCM_MODULE.PCMA@8000h @20i@64000b'
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:473 ( sofia/external/754617162742492@x.x.x.x) Running State Change CS_INIT
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:516 ( sofia/external/754617162742492@x.x.x.x) State INIT
2015-08-22 10:11:43.049286 [DEBUG] mod_sofia.c:88 sofia/external/754617162742492@x.x.x.x SOFIA INIT
2015-08-22 10:11:43.049286 [DEBUG] sofia_glue.c:1257 sofia/external/754617162742492@x.x.x.x sending invite version: 1.7.0 git 58f1272 2015-08-14 17:51:12Z 64bit
Local SDP:
v=0
o=- 327457341635358182 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 9 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 0.0.0.0
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:IQcKT9rkNCETwhGR
a=ice-pwd:chtzwjBRY3ptPbf/IkcKUO1i
a=fingerprint:sha-1 8A:95:DD:4E:18:85:C6:B9:31:1E:04:90:BC:67:EB:C6:DD:58:4F:B5
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:2501014728 cname:dJGa9dJwxrkwhQkE
a=ssrc:2501014728 msid:media_stream audio_track
a=ssrc:2501014728 mslabel:media_stream
a=ssrc:2501014728 label:audio_track
a=candidate:3027063986 1 udp 2122260223 192.168.1.129 55289 typ host generation 0
a=candidate:19046677 1 udp 2122194687 172.20.10.1 62766 typ host generation 0
a=candidate:3059882106 1 udp 2122129151 10.227.60.222 56279 typ host generation 0
a=candidate:3027063986 2 udp 2122260222 192.168.1.129 51792 typ host generation 0
a=candidate:19046677 2 udp 2122194686 172.20.10.1 61231 typ host generation 0
a=candidate:3059882106 2 udp 2122129150 10.227.60.222 56500 typ host generation 0
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:40 sofia/external/754617162742492@x.x.x.x Standard INIT
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:48 ( sofia/external/754617162742492@x.x.x.x) State Change CS_INIT -> CS_ROUTING
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:516 ( sofia/external/754617162742492@x.x.x.x) State INIT going to sleep
2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:473 ( sofia/external/754617162742492@x.x.x.x) Running State Change CS_ROUTING
2015-08-22 10:11:43.069228 [DEBUG] switch_core_state_machine.c:532 ( sofia/external/754617162742492@x.x.x.x) State ROUTING
2015-08-22 10:11:43.069228 [DEBUG] mod_sofia.c:141 sofia/external/754617162742492@x.x.x.x SOFIA ROUTING
2015-08-22 10:11:43.069228 [DEBUG] switch_ivr_originate.c:67 ( sofia/external/754617162742492@x.x.x.x) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2015-08-22 10:11:43.069228 [DEBUG] switch_core_state_machine.c:532 ( sofia/external/754617162742492@x.x.x.x) State ROUTING going to sleep
2015-08-22 10:11:43.069228 [DEBUG] switch_core_state_machine.c:473 ( sofia/external/754617162742492@x.x.x.x) Running State Change CS_CONSUME_MEDIA
2015-08-22 10:11:43.069228 [DEBUG] switch_core_state_machine.c:551 ( sofia/external/754617162742492@x.x.x.x) State CONSUME_MEDIA
2015-08-22 10:11:43.069228 [DEBUG] switch_core_state_machine.c:551 ( sofia/external/754617162742492@x.x.x.x) State CONSUME_MEDIA going to sleep
send 2347 bytes to udp/[x.x.x.x]:5060 at 10:11:43.070474:
------------------------------------------------------------------------
INVITE sip:754617162742492@x.x.x.x SIP/2.0
Via: SIP/2.0/UDP y.y.y.y:5080;rport;branch=z9hG4bK5Q40Q4Ze2mj0K
Max-Forwards: 69
From: "Extension 1001" <sip:1001@y.y.y.y>;tag=51SKgXr23XK9K
To: <sip:754617162742492@x.x.x.x>
Call-ID: 075638a9-c359-1233-81a7-60eb698211be
CSeq: 79783087 INVITE
Contact: <sip:mod_sofia@y.y.y.y:5080>
User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 1518
X-FS-Support: update_display,send_info
Remote-Party-ID: "Extension 1001" <sip:1001@y.y.y.y
;party=calling;screen=yes;privacy=off
v=0
o=- 327457341635358182 2 IN IP4 y.y.y.y
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 9 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 0.0.0.0
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:IQcKT9rkNCETwhGR
a=ice-pwd:chtzwjBRY3ptPbf/IkcKUO1i
a=fingerprint:sha-1 8A:95:DD:4E:18:85:C6:B9:31:1E:04:90:BC:67:EB:C6:DD:58:4F:B5
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:2501014728 cname:dJGa9dJwxrkwhQkE
a=ssrc:2501014728 msid:media_stream audio_track
a=ssrc:2501014728 mslabel:media_stream
a=ssrc:2501014728 label:audio_track
a=candidate:3027063986 1 udp 2122260223 192.168.1.129 55289 typ host generation 0
a=candidate:19046677 1 udp 2122194687 172.20.10.1 62766 typ host generation 0
a=candidate:3059882106 1 udp 2122129151 10.227.60.222 56279 typ host generation 0
a=candidate:3027063986 2 udp 2122260222 192.168.1.129 51792 typ host generation 0
a=candidate:19046677 2 udp 2122194686 172.20.10.1 61231 typ host generation 0
a=candidate:3059882106 2 udp 2122129150 10.227.60.222 56500 typ host generation 0
On Fri, Aug 21, 2015 at 11:06 AM, Anthony Minessale < anthony.minessale@gmail.com> wrote:
Its not the same tey harder.
On Friday, August 21, 2015, Pete Kay <petedao@gmail.com> wrote:
Hi
Without proxy media variable it is still the same. My earlier email in this thread had this problem without media proxy set to true.
On Thu, Aug 20, 2015 at 9:55 PM, Giovanni Maruzzelli <gmaruzz@gmail.com> wrote:
Try without mentioning proxy_media at all, no false, no true, just delete it from both occurrences.
sent from my mobile, Giovanni Maruzzelli cell: +39 347 266 56 18
On Aug 21, 2015 6:43 AM, "Pete Kay" <petedao@gmail.com> wrote:
Hi
I tried to use proxy_media = true but it is still passing RTP/SAVPF instead of RTP/AVPF. Could I ask where is the problem in my configuration?
Dialplan: sofia/internal/1001@y.y.y.y:5060 Action bridge({rtp_allow_crypto_in_avp=false,rtp_secure_media=false,sip_secure_mediai_outbound=false,sdp_secure_savp_only=false,proxy_media=true}[rtp_allow_crypto_in_avp=false,sip_secure_media_outbound=false,sdp_secure_savp_only=false,proxy_media=true] sofia/external/754617162742492@x.x.x.x)
send 2700 bytes to udp/[69.27.168.50]:5060 at 04:01:47.286175:
------------------------------------------------------------------------
INVITE sip:754617162742492@69.27.168.50 SIP/2.0
Via: SIP/2.0/UDP y.y.y.y:5080;rport;branch=z9hG4bKgBt1e63aHX4SD
Max-Forwards: 69
From: "Extension 1001" <sip:1001@y.y.y.y>;tag=grg241BNtgDtN
To: <sip:754617162742492@x.x.x.x>
Call-ID: 2f34abd4-c25c-1233-ffbc-60eb698211be
CSeq: 79728789 INVITE
Contact: <sip:mod_sofia@y.y.y.y:5080>
User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 1871
X-FS-Support: update_display,send_info
Remote-Party-ID: "Extension 1001" <sip:1001@y.y.y.y
;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 3361339272 3361339273 IN IP4 y.y.y.y
s=FreeSWITCH
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 28246 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 108.165.2.110
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:bmbIvHb6NVeLQBcK
a=ice-pwd:KDvxAfWB8jTfbGrL610/0jtk
a=fingerprint:sha-1 F1:CA:8E:C9:58:A5:00:CB:59:A5:19:90:0A:D0:60:76:33:70:C2:F1
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:3858004023 cname:S5akTfRDQvG5IccJ
a=ssrc:3858004023 msid:media_stream audio_track
a=ssrc:3858004023 mslabel:media_stream
a=ssrc:3858004023 label:audio_track
a=candidate:2881221497 1 udp 2122260223 10.227.131.21 55121 typ host generation 0
a=candidate:2881221497 2 udp 2122260222 10.227.131.21 64682 typ host generation 0
a=candidate:3845656457 1 tcp 1518280447 10.227.131.21 57029 typ host tcptype passive generation 0
a=candidate:3845656457 2 tcp 1518280446 10.227.131.21 57030 typ host tcptype passive generation 0
a=candidate:720631245 1 udp 1686052607 112.97.38.2 6734 typ srflx raddr 10.227.131.21 rport 55121 generation 0
a=candidate:720631245 2 udp 1686052606 112.97.38.2 6735 typ srflx raddr 10.227.131.21 rport 64682 generation 0
a=candidate:2922827951 1 udp 41885439 146.185.149.11 52800 typ relay raddr 112.97.38.2 rport 34387 generation 0
a=candidate:2922827951 2 udp 41885438 146.185.149.11 54764 typ relay raddr 112.97.38.2 rport 34386 generation 0
------------------------------------------------------------------------
On Mon, Aug 17, 2015 at 9:28 AM, Brian West <brian@freeswitch.org> wrote:
rtp_secure_media=false is the variable name.
On Mon, Aug 17, 2015 at 11:06 AM, Pete Kay <petedao@gmail.com> wrote:
Hi
I am trying to send call from webrtc to freeswitch and then bridge to an external endpoint that is a normal SIP phone. I tried to use sip_secure_media=false to force the media to be converted from SAVP to AVP.
But the outgoing INVITE is still showing SAVP ( sip trace blow ). Does anyone know where I am doing wrong? any help will be greately appreciated.
2015-08-17 15:29:46.341244 [INFO] mod_dialplan_xml.c:637 Processing 1001 <1001>->1002 in context default
Dialplan: sofia/internal/1001@y.y.y.y:5060 parsing [default->incoming_calls] continue=false
Dialplan: sofia/internal/1001@y.y.y.y:5060 Regex (PASS) [incoming_calls] ${destination_number}(1002) =~ /1002/ break=on-false
Dialplan: sofia/internal/1001@y.y.y.y:5060 Action bridge({sip_secure_media=false}[sip_secure_media=false] sofia/internal/754617162742492@x.x.x.x)
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:216 ( sofia/internal/1001@y.y.y.y:5060) State Change CS_ROUTING -> CS_EXECUTE
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:532 ( sofia/internal/1001@y.y.y.y:5060) State ROUTING going to sleep
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/1001@y.y.y.y:5060) Running State Change CS_EXECUTE
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:539 ( sofia/internal/1001@y.y.y.y:5060) State EXECUTE
2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:196 sofia/internal/1001@y.y.y.y:5060 SOFIA EXECUTE
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1001@y.y.y.y:5060 Standard EXECUTE
EXECUTE sofia/internal/1001@y.y.y.y:5060 bridge({sip_secure_media=false}[sip_secure_media=false] sofia/internal/754617162742492@x.x.x.x)
2015-08-17 15:29:46.341244 [DEBUG] switch_ivr_originate.c:2104 Parsing global variables
2015-08-17 15:29:46.341244 [DEBUG] switch_ivr_originate.c:2557 Parsing session specific variables
2015-08-17 15:29:46.341244 [NOTICE] switch_channel.c:1089 New Channel sofia/internal/754617162742492@x.x.x.x [ca6d5930-44f4-11e5-9cc9-c9574f94e170]
2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:4735 ( sofia/internal/754617162742492@x.x.x.x) State Change CS_NEW -> CS_INIT
2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:4805 [zrtp_passthru] Setting a-leg inherit_codec=true
2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:4808 [zrtp_passthru] Setting b-leg absolute_codec_string='mod_spandsp.G722@8000h@20i@64000b ,CORE_PCM_MODULE.PCMU@8000h@20i@64000b,CORE_PCM_MODULE.PCMA@8000h @20i@64000b'
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/754617162742492@x.x.x.x) Running State Change CS_INIT
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:516 ( sofia/internal/754617162742492@x.x.x.x) State INIT
2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:88 sofia/internal/754617162742492@x.x.x.x SOFIA INIT
2015-08-17 15:29:46.341244 [DEBUG] sofia_glue.c:1257 sofia/internal/754617162742492@x.x.x.x sending invite version: 1.7.0 git 58f1272 2015-08-14 17:51:12Z 64bit
Local SDP:
v=0
o=- 4133111956976529630 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 9 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 0.0.0.0
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:Gn8CX52vaidE1C73
a=ice-pwd:16VDNSZodn+NJp8u3O1mQrMy
a=fingerprint:sha-1 19:57:5D:7A:BD:7B:42:3B:C9:1B:2F:A6:D2:4F:32:FD:C3:23:14:D9
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:3618235845 cname:3hHDnnOtFofiuRz2
a=ssrc:3618235845 msid:media_stream audio_track
a=ssrc:3618235845 mslabel:media_stream
a=ssrc:3618235845 label:audio_track
a=candidate:186199869 1 udp 2122260223 192.168.1.101 60710 typ host generation 0
a=candidate:4049598204 1 udp 2122194687 10.73.229.110 52079 typ host generation 0
a=candidate:186199869 2 udp 2122260222 192.168.1.101 64150 typ host generation 0
a=candidate:4049598204 2 udp 2122194686 10.73.229.110 51909 typ host generation 0
a=candidate:1167774669 1 tcp 1518280447 192.168.1.101 55638 typ host tcptype passive generation 0
a=candidate:3218868748 1 tcp 1518214911 10.73.229.110 55639 typ host tcptype passive generation 0
a=candidate:1167774669 2 tcp 1518280446 192.168.1.101 55640 typ host tcptype passive generation 0
a=candidate:3218868748 2 tcp 1518214910 10.73.229.110 55641 typ host tcptype passive generation 0
a=candidate:2320574857 1 udp 1686052607 27.38.56.3 36782 typ srflx raddr 192.168.1.101 rport 60710 generation 0
a=candidate:2320574857 2 udp 1686052606 27.38.56.3 37553 typ srflx raddr 192.168.1.101 rport 64150 generation 0
a=candidate:1880617032 1 udp 1685987071 112.97.38.179 2308 typ srflx raddr 10.73.229.110 rport 52079 generation 0
a=candidate:1880617032 2 udp 1685987070 112.97.38.179 2307 typ srflx raddr 10.73.229.110 rport 51909 generation 0
a=candidate:2922827951 1 udp 41885439 146.185.149.11 55532 typ relay raddr 27.38.56.3 rport 36782 generation 0
a=candidate:2922827951 2 udp 41885438 146.185.149.11 54104 typ relay raddr 27.38.56.3 rport 37553 generation 0
a=candidate:2922827951 2 udp 41819902 146.185.149.11 63311 typ relay raddr 112.97.38.179 rport 54091 generation 0
a=candidate:2922827951 1 udp 41819903 146.185.149.11 49576 typ relay raddr 112.97.38.179 rport 54092 generation 0
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:40 sofia/internal/754617162742492@x.x.x.x Standard INIT
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:48 ( sofia/internal/754617162742492@x.x.x.x) State Change CS_INIT -> CS_ROUTING
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:516 ( sofia/internal/754617162742492@x.x.x.x) State INIT going to sleep
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/754617162742492@x.x.x.x) Running State Change CS_ROUTING
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:532 ( sofia/internal/754617162742492@x.x.x.x) State ROUTING
2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:141 sofia/internal/754617162742492@x.x.x.x SOFIA ROUTING
2015-08-17 15:29:46.341244 [DEBUG] switch_ivr_originate.c:67 ( sofia/internal/754617162742492@x.x.x.x) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:532 ( sofia/internal/754617162742492@x.x.x.x) State ROUTING going to sleep
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/754617162742492@x.x.x.x) Running State Change CS_CONSUME_MEDIA
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:551 ( sofia/internal/754617162742492@x.x.x.x) State CONSUME_MEDIA
2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:551 ( sofia/internal/754617162742492@x.x.x.x) State CONSUME_MEDIA going to sleep
send 3635 bytes to udp/[69.27.168.50]:5060 at 15:29:46.355875:
------------------------------------------------------------------------
INVITE sip:754617162742492@x.x.x.x SIP/2.0
Via: SIP/2.0/UDP y.y.y.y;rport;branch=z9hG4bKcSe6jHj8QS56e
Max-Forwards: 69
From: "Extension 1001" <sip:1001@y.y.y.y>;tag=H6HrjU4ZH1KjD
To: <sip:754617162742492@x.x.x.x>
Call-ID: a1cbcf13-bf97-1233-84b6-60eb698211be
CSeq: 79576629 INVITE
Contact: <sip:mod_sofia@y.y.y.y:5060>
User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 2665
X-FS-Support: update_display,send_info
Remote-Party-ID: "Extension 1001" <sip:1001@y.y.y.y
;party=calling;screen=yes;privacy=off
v=0
o=- 4133111956976529630 2 IN IP4 y.y.y.y
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 9 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 0.0.0.0
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:Gn8CX52vaidE1C73
a=ice-pwd:16VDNSZodn+NJp8u3O1mQrMy
a=fingerprint:sha-1 19:57:5D:7A:BD:7B:42:3B:C9:1B:2F:A6:D2:4F:32:FD:C3:23:14:D9
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:3618235845 cname:3hHDnnOtFofiuRz2
a=ssrc:3618235845 msid:media_stream audio_track
a=ssrc:3618235845 mslabel:media_stream
a=ssrc:3618235845 label:audio_track
a=candidate:186199869 1 udp 2122260223 192.168.1.101 60710 typ host generation 0
a=candidate:4049598204 1 udp 2122194687 10.73.229.110 52079 typ host generation 0
a=candidate:186199869 2 udp 2122260222 192.168.1.101 64150 typ host generation 0
a=candidate:4049598204 2 udp 2122194686 10.73.229.110 51909 typ host generation 0
a=candidate:1167774669 1 tcp 1518280447 192.168.1.101 55638 typ host tcptype passive generation 0
a=candidate:3218868748 1 tcp 1518214911 10.73.229.110 55639 typ host tcptype passive generation 0
a=candidate:1167774669 2 tcp 1518280446 192.168.1.101 55640 typ host tcptype passive generation 0
a=candidate:3218868748 2 tcp 1518214910 10.73.229.110 55641 typ host tcptype passive generation 0
a=candidate:2320574857 1 udp 1686052607 27.38.56.3 36782 typ srflx raddr 192.168.1.101 rport 60710 generation 0
a=candidate:2320574857 2 udp 1686052606 27.38.56.3 37553 typ srflx raddr 192.168.1.101 rport 64150 generation 0
a=candidate:1880617032 1 udp 1685987071 112.97.38.179 2308 typ srflx raddr 10.73.229.110 rport 52079 generation 0
a=candidate:1880617032 2 udp 1685987070 112.97.38.179 2307 typ srflx raddr 10.73.229.110 rport 51909 generation 0
a=candidate:2922827951 1 udp 41885439 146.185.149.11 55532 typ relay raddr 27.38.56.3 rport 36782 generation 0
a=candidate:2922827951 2 udp 41885438 146.185.149.11 54104 typ relay raddr 27.38.56.3 rport 37553 generation 0
a=candidate:2922827951 2 udp 41819902 146.185.149.11 63311 typ relay raddr 112.97.38.179 rport 54091 generation 0
a=candidate:2922827951 1 udp 41819903 146.185.149.11 49576 typ relay raddr 112.97.38.179 rport 54092 generation 0
------------------------------------------------------------------------
2015-08-17 15:29:46.341244 [DEBUG] sofia.c:6715 Channel sofia/internal/754617162742492@x.x.x.x entering state [calling][0]
recv 308 bytes from udp/[x.x.x.x]:5060 at 15:29:46.357317:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP y.y.y.y;rport;branch=z9hG4bKcSe6jHj8QS56e
From: "Extension 1001" <sip:1001@y.y.y.y>;tag=H6HrjU4ZH1KjD
To: <sip:754617162742492@x.x.x.x>
Call-ID: a1cbcf13-bf97-1233-84b6-60eb698211be
CSeq: 79576629 INVITE
User-Agent: DNL-Switch
Content-Length: 0
------------------------------------------------------------------------
recv 433 bytes from udp/[x.x.x.x]:5060 at 15:29:46.367995:
------------------------------------------------------------------------
SIP/2.0 415 Unsupported Media Type
Via: SIP/2.0/UDP y.y.y.y;rport;branch=z9hG4bKcSe6jHj8QS56e
From: "Extension 1001" <sip:1001@y.y.y.y>;tag=H6HrjU4ZH1KjD
To: <sip:754617162742492@x.x.x.x>;tag=Ut8ye62NUrQeB
Call-ID: a1cbcf13-bf97-1233-84b6-60eb698211be
CSeq: 79576629 INVITE
User-Agent: DNL-Switch
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO
Supported: timer
Content-Length: 0
------------------------------------------------------------------------
send 343 bytes to udp/[x.x.x.x]:5060 at 15:29:46.368134:
------------------------------------------------------------------------
ACK sip:754617162742492@x.x.x.x SIP/2.0
Via: SIP/2.0/UDP y.y.y.y;rport;branch=z9hG4bKcSe6jHj8QS56e
Max-Forwards: 69
From: "Extension 1001" <sip:1001@y.y.y.y>;tag=H6HrjU4ZH1KjD
To: <sip:754617162742492@x.x.x.x>;tag=Ut8ye62NUrQeB
Call-ID: a1cbcf13-bf97-1233-84b6-60eb698211be
CSeq: 79576629 ACK
Content-Length: 0
------------------------------------------------------------------------
2015-08-17 15:29:46.361276 [DEBUG] sofia.c:6715 Channel sofia/internal/754617162742492@x.x.x.x entering state [terminated][415]
2015-08-17 15:29:46.361276 [NOTICE] sofia.c:7631 Hangup sofia/internal/754617162742492@x.x.x.x [CS_CONSUME_MEDIA] [SERVICE_NOT_IMPLEMENTED]
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/754617162742492@x.x.x.x) Running State Change CS_HANGUP
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:739 ( sofia/internal/754617162742492@x.x.x.x) Callstate Change DOWN -> HANGUP
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:741 ( sofia/internal/754617162742492@x.x.x.x) State HANGUP
2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:431 Channel sofia/internal/754617162742492@x.x.x.x hanging up, cause: SERVICE_NOT_IMPLEMENTED
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:60 sofia/internal/754617162742492@x.x.x.x Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:741 ( sofia/internal/754617162742492@x.x.x.x) State HANGUP going to sleep
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:508 ( sofia/internal/754617162742492@x.x.x.x) State Change CS_HANGUP -> CS_REPORTING
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/754617162742492@x.x.x.x) Running State Change CS_REPORTING
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:827 ( sofia/internal/754617162742492@x.x.x.x) State REPORTING
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:104 sofia/internal/754617162742492@x.x.x.x Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:827 ( sofia/internal/754617162742492@x.x.x.x) State REPORTING going to sleep
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:499 ( sofia/internal/754617162742492@x.x.x.x) State Change CS_REPORTING -> CS_DESTROY
2015-08-17 15:29:46.361276 [DEBUG] switch_core_session.c:1639 Session 2 ( sofia/internal/754617162742492@x.x.x.x) Locked, Waiting on external entities
2015-08-17 15:29:46.361276 [DEBUG] switch_ivr_originate.c:3727 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED]
2015-08-17 15:29:46.361276 [NOTICE] switch_core_session.c:1657 Session 2 ( sofia/internal/754617162742492@x.x.x.x) Ended
2015-08-17 15:29:46.361276 [NOTICE] switch_core_session.c:1661 Close Channel sofia/internal/754617162742492@x.x.x.x [CS_DESTROY]
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:630 ( sofia/internal/754617162742492@x.x.x.x) Running State Change CS_DESTROY
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:640 ( sofia/internal/754617162742492@x.x.x.x) State DESTROY
2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:341 sofia/internal/754617162742492@x.x.x.x SOFIA DESTROY
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:111 sofia/internal/754617162742492@x.x.x.x Standard DESTROY
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:640 ( sofia/internal/754617162742492@x.x.x.x) State DESTROY going to sleep
2015-08-17 15:29:46.361276 [INFO] mod_dptools.c:3333 Originate Failed. Cause: SERVICE_NOT_IMPLEMENTED
2015-08-17 15:29:46.361276 [NOTICE] switch_channel.c:4800 Hangup sofia/internal/1001@y.y.y.y:5060 [CS_EXECUTE] [SERVICE_NOT_IMPLEMENTED]
2015-08-17 15:29:46.361276 [DEBUG] switch_core_session.c:2926 sofia/internal/1001@y.y.y.y:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:539 ( sofia/internal/1001@y.y.y.y:5060) State EXECUTE going to sleep
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/1001@y.y.y.y:5060) Running State Change CS_HANGUP
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:739 ( sofia/internal/1001@y.y.y.y:5060) Callstate Change RINGING -> HANGUP
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:741 ( sofia/internal/1001@y.y.y.y:5060) State HANGUP
2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:425 sofia/internal/1001@y.y.y.y:5060 Overriding SIP cause 501 with 415 from the other leg
2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1001@y.y.y.y:5060 hanging up, cause: SERVICE_NOT_IMPLEMENTED
2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:568 Responding to INVITE with: 415
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@y.y.y.y:5060 Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:741 ( sofia/internal/1001@y.y.y.y:5060) State HANGUP going to sleep
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:508 ( sofia/internal/1001@y.y.y.y:5060) State Change CS_HANGUP -> CS_REPORTING
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:473 ( sofia/internal/1001@y.y.y.y:5060) Running State Change CS_REPORTING
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:827 ( sofia/internal/1001@y.y.y.y:5060) State REPORTING
send 900 bytes to tcp/[27.38.56.3]:36982 at 15:29:46.376821:
------------------------------------------------------------------------
SIP/2.0 415 Unsupported Media Type
Via: SIP/2.0/TCP 192.168.1.101:5060 ;branch=z9hG4bK-524287-1---96ba2f7825316328;rport=36982;received=27.38.56.3
Max-Forwards: 70
From: <sip:1001@y.y.y.y:5060>;tag=9de53b6c
To: <sip:1002@y.y.y.y:5060>;tag=gXrZg0KvmrXZH
Call-ID: kVdW6FArCGqcEPe7zR3x4g..
CSeq: 2 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Reason: Q.850;cause=79;text="SERVICE_NOT_IMPLEMENTED"
Content-Length: 0
Remote-Party-ID: "1002" <sip:1002@y.y.y.y
;party=calling;privacy=off;screen=no
------------------------------------------------------------------------
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1001@y.y.y.y:5060 Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:827 ( sofia/internal/1001@y.y.y.y:5060) State REPORTING going to sleep
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:499 ( sofia/internal/1001@y.y.y.y:5060) State Change CS_REPORTING -> CS_DESTROY
2015-08-17 15:29:46.361276 [DEBUG] switch_core_session.c:1639 Session 1 ( sofia/internal/1001@y.y.y.y:5060) Locked, Waiting on external entities
2015-08-17 15:29:46.361276 [NOTICE] switch_core_session.c:1657 Session 1 ( sofia/internal/1001@y.y.y.y:5060) Ended
2015-08-17 15:29:46.361276 [NOTICE] switch_core_session.c:1661 Close Channel sofia/internal/1001@y.y.y.y:5060 [CS_DESTROY]
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:630 ( sofia/internal/1001@y.y.y.y:5060) Running State Change CS_DESTROY
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:640 ( sofia/internal/1001@y.y.y.y:5060) State DESTROY
2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:341 sofia/internal/1001@y.y.y.y:5060 SOFIA DESTROY
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1001@y.y.y.y:5060 Standard DESTROY
2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:640 ( sofia/internal/1001@y.y.y.y:5060) State DESTROY going to sleep
recv 317 bytes from tcp/[27.38.56.3]:36982 at 15:29:46.651249:
------------------------------------------------------------------------
ACK sip:1002@y.y.y.y:5060 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.101:5060 ;branch=z9hG4bK-524287-1---96ba2f7825316328;rport
Max-Forwards: 70
To: <sip:1002@y.y.y.y:5060>;tag=gXrZg0KvmrXZH
From: <sip:1001@y.y.y.y:5060>;tag=9de53b6c
Call-ID: kVdW6FArCGqcEPe7zR3x4g..
CSeq: 2 ACK
Content-Length: 0
------------------------------------------------------------------------
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
--
*Brian West* brian@freeswitch.org
*Twitter: @FreeSWITCH , @briankwest* http://www.freeswitchbook.com http://www.freeswitchcookbook.com
Got Bugs? Report them here <https://freeswitch.org/jira>! | Reddit: /r/freeswitch <https://www.reddit.com/r/freeswitch>
*T:*+19184209001 | *F:*+19184209002 | *M:*+1918424WEST (9378) *iNUM:*+883 5100 1420 9001 | *ISN:*410*543 | *Skype:*briankwest
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
--
Anthony Minessale II ♬ @anthmfs ♬ @FreeSWITCH ♬
☞ http://freeswitch.org/ ☞ http://cluecon.com/ ☞ http://twitter.com/FreeSWITCH
☞ irc.freenode.net #freeswitch ☞ *http://freeswitch.org/g+ <http://freeswitch.org/g+>*
ClueCon Weekly Development Call
☎ sip:888@conference.freeswitch.org ☎ +19193869900
ClueCon Testimonials <https://www.youtube.com/watch?v=9XXgW34t40s>
ClueCon This Summer <https://www.youtube.com/watch?v=NLaDpGQuZDA>
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
--
Anthony Minessale II ♬ @anthmfs ♬ @FreeSWITCH ♬
☞ http://freeswitch.org/ ☞ http://cluecon.com/ ☞ http://twitter.com/FreeSWITCH
☞ irc.freenode.net #freeswitch ☞ *http://freeswitch.org/g+ <http://freeswitch.org/g+>*
ClueCon Weekly Development Call
☎ sip:888@conference.freeswitch.org ☎ +19193869900
https://www.youtube.com/watch?v=9XXgW34t40s
https://www.youtube.com/watch?v=NLaDpGQuZDA
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
-- Anthony Minessale II ♬ @anthmfs ♬ @FreeSWITCH ♬
☞ http://freeswitch.org/ ☞ http://cluecon.com/ ☞ http://twitter.com/FreeSWITCH ☞ irc.freenode.net #freeswitch ☞ *http://freeswitch.org/g+ <http://freeswitch.org/g+>*
ClueCon Weekly Development Call ☎ sip:888@conference.freeswitch.org ☎ +19193869900
https://www.youtube.com/watch?v=9XXgW34t40s https://www.youtube.com/watch?v=NLaDpGQuZDA
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org