[Freeswitch-users] Need help to convert RTP/SAVP to RTP/AVP

Anthony Minessale anthony.minessale at gmail.com
Sun Aug 23 19:05:34 MSD 2015


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 at 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 at y.y.y.y:5060 parsing
> [default->incoming_calls] continue=false
>
> Dialplan: sofia/internal/1001 at y.y.y.y:5060 Regex (PASS) [incoming_calls]
> ${destination_number}(1002) =~ /1002/ break=on-false
>
> Dialplan: sofia/internal/1001 at 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 at x.x.x.x
> )
>
> 2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:216
> (sofia/internal/1001 at 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 at 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 at 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 at y.y.y.y:5060) State EXECUTE
>
> 2015-08-22 10:11:43.049286 [DEBUG] mod_sofia.c:196
> sofia/internal/1001 at y.y.y.y:5060 SOFIA EXECUTE
>
> 2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:258
> sofia/internal/1001 at y.y.y.y:5060 Standard EXECUTE
>
> EXECUTE sofia/internal/1001 at 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 at 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 at x.x.x.x
> [2ff7c01e-48b6-11e5-ac58-01226f03e80c]
>
> 2015-08-22 10:11:43.049286 [DEBUG] mod_sofia.c:4735
> (sofia/external/754617162742492 at 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 at 8000h@20i at 64000b
> ,CORE_PCM_MODULE.PCMU at 8000h@20i at 64000b,CORE_PCM_MODULE.PCMA at 8000h
> @20i at 64000b'
>
> 2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:473
> (sofia/external/754617162742492 at 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 at x.x.x.x) State INIT
>
> 2015-08-22 10:11:43.049286 [DEBUG] mod_sofia.c:88
> sofia/external/754617162742492 at x.x.x.x SOFIA INIT
>
> 2015-08-22 10:11:43.049286 [DEBUG] sofia_glue.c:1257
> sofia/external/754617162742492 at 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 at x.x.x.x Standard INIT
>
> 2015-08-22 10:11:43.049286 [DEBUG] switch_core_state_machine.c:48
> (sofia/external/754617162742492 at 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 at 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 at 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 at x.x.x.x) State ROUTING
>
> 2015-08-22 10:11:43.069228 [DEBUG] mod_sofia.c:141
> sofia/external/754617162742492 at x.x.x.x SOFIA ROUTING
>
> 2015-08-22 10:11:43.069228 [DEBUG] switch_ivr_originate.c:67
> (sofia/external/754617162742492 at 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 at 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 at 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 at x.x.x.x) State CONSUME_MEDIA
>
> 2015-08-22 10:11:43.069228 [DEBUG] switch_core_state_machine.c:551
> (sofia/external/754617162742492 at 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 at 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 at y.y.y.y>;tag=51SKgXr23XK9K
>
>    To: <sip:754617162742492 at x.x.x.x>
>
>    Call-ID: 075638a9-c359-1233-81a7-60eb698211be
>
>    CSeq: 79783087 INVITE
>
>    Contact: <sip:mod_sofia at 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 at 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 at gmail.com
> <javascript:_e(%7B%7D,'cvml','anthony.minessale at gmail.com');>> wrote:
>
>> Its not the same tey harder.
>>
>>
>> On Friday, August 21, 2015, Pete Kay <petedao at gmail.com
>> <javascript:_e(%7B%7D,'cvml','petedao at 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 at 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 at 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 at 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 at x.x.x.x
>>>>> )
>>>>>
>>>>>
>>>>> send 2700 bytes to udp/[69.27.168.50]:5060 at 04:01:47.286175:
>>>>>
>>>>>
>>>>> ------------------------------------------------------------------------
>>>>>
>>>>>    INVITE sip:754617162742492 at 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 at y.y.y.y>;tag=grg241BNtgDtN
>>>>>
>>>>>    To: <sip:754617162742492 at x.x.x.x>
>>>>>
>>>>>    Call-ID: 2f34abd4-c25c-1233-ffbc-60eb698211be
>>>>>
>>>>>    CSeq: 79728789 INVITE
>>>>>
>>>>>    Contact: <sip:mod_sofia at 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 at 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 at freeswitch.org>
>>>>> wrote:
>>>>>
>>>>>> rtp_secure_media=false is the variable name.
>>>>>>
>>>>>> On Mon, Aug 17, 2015 at 11:06 AM, Pete Kay <petedao at 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 at y.y.y.y:5060 parsing
>>>>>>> [default->incoming_calls] continue=false
>>>>>>>
>>>>>>> Dialplan: sofia/internal/1001 at y.y.y.y:5060 Regex (PASS)
>>>>>>> [incoming_calls] ${destination_number}(1002) =~ /1002/ break=on-false
>>>>>>>
>>>>>>> Dialplan: sofia/internal/1001 at y.y.y.y:5060 Action
>>>>>>> bridge({sip_secure_media=false}[sip_secure_media=false]sofia/internal/754617162742492 at x.x.x.x
>>>>>>> )
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:216
>>>>>>> (sofia/internal/1001 at 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 at 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 at 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 at y.y.y.y:5060) State EXECUTE
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:196
>>>>>>> sofia/internal/1001 at y.y.y.y:5060 SOFIA EXECUTE
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:258
>>>>>>> sofia/internal/1001 at y.y.y.y:5060 Standard EXECUTE
>>>>>>>
>>>>>>> EXECUTE sofia/internal/1001 at y.y.y.y:5060
>>>>>>> bridge({sip_secure_media=false}[sip_secure_media=false]sofia/internal/754617162742492 at 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 at x.x.x.x
>>>>>>> [ca6d5930-44f4-11e5-9cc9-c9574f94e170]
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:4735
>>>>>>> (sofia/internal/754617162742492 at 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 at 8000h
>>>>>>> @20i at 64000b,CORE_PCM_MODULE.PCMU at 8000h@20i at 64000b
>>>>>>> ,CORE_PCM_MODULE.PCMA at 8000h@20i at 64000b'
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:473
>>>>>>> (sofia/internal/754617162742492 at 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 at x.x.x.x) State INIT
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:88
>>>>>>> sofia/internal/754617162742492 at x.x.x.x SOFIA INIT
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] sofia_glue.c:1257
>>>>>>> sofia/internal/754617162742492 at 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 at x.x.x.x Standard INIT
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:48
>>>>>>> (sofia/internal/754617162742492 at 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 at 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 at 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 at x.x.x.x) State ROUTING
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] mod_sofia.c:141
>>>>>>> sofia/internal/754617162742492 at x.x.x.x SOFIA ROUTING
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] switch_ivr_originate.c:67
>>>>>>> (sofia/internal/754617162742492 at 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 at 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 at 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 at x.x.x.x) State CONSUME_MEDIA
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.341244 [DEBUG] switch_core_state_machine.c:551
>>>>>>> (sofia/internal/754617162742492 at 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 at 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 at y.y.y.y>;tag=H6HrjU4ZH1KjD
>>>>>>>
>>>>>>>   To: <sip:754617162742492 at x.x.x.x>
>>>>>>>
>>>>>>>    Call-ID: a1cbcf13-bf97-1233-84b6-60eb698211be
>>>>>>>
>>>>>>>    CSeq: 79576629 INVITE
>>>>>>>
>>>>>>>    Contact: <sip:mod_sofia at 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 at 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 at 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 at y.y.y.y>;tag=H6HrjU4ZH1KjD
>>>>>>>
>>>>>>>    To: <sip:754617162742492 at 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 at y.y.y.y>;tag=H6HrjU4ZH1KjD
>>>>>>>
>>>>>>>    To: <sip:754617162742492 at 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 at 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 at y.y.y.y>;tag=H6HrjU4ZH1KjD
>>>>>>>
>>>>>>>    To: <sip:754617162742492 at 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 at x.x.x.x entering state
>>>>>>> [terminated][415]
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [NOTICE] sofia.c:7631 Hangup
>>>>>>> sofia/internal/754617162742492 at 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 at 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 at 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 at x.x.x.x) State HANGUP
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:431 Channel
>>>>>>> sofia/internal/754617162742492 at 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 at 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 at 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 at 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 at 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 at x.x.x.x) State REPORTING
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:104
>>>>>>> sofia/internal/754617162742492 at 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 at 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 at 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 at 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 at x.x.x.x) Ended
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [NOTICE] switch_core_session.c:1661 Close
>>>>>>> Channel sofia/internal/754617162742492 at x.x.x.x [CS_DESTROY]
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:630
>>>>>>> (sofia/internal/754617162742492 at 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 at x.x.x.x) State DESTROY
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:341
>>>>>>> sofia/internal/754617162742492 at x.x.x.x SOFIA DESTROY
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:111
>>>>>>> sofia/internal/754617162742492 at x.x.x.x Standard DESTROY
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:640
>>>>>>> (sofia/internal/754617162742492 at 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 at 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 at 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 at 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 at 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 at 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 at y.y.y.y:5060) State HANGUP
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:425
>>>>>>> sofia/internal/1001 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at y.y.y.y:5060>;tag=9de53b6c
>>>>>>>
>>>>>>>    To: <sip:1002 at 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 at 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 at 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 at 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 at 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 at 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 at y.y.y.y:5060) Ended
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [NOTICE] switch_core_session.c:1661 Close
>>>>>>> Channel sofia/internal/1001 at y.y.y.y:5060 [CS_DESTROY]
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:630
>>>>>>> (sofia/internal/1001 at 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 at y.y.y.y:5060) State DESTROY
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] mod_sofia.c:341
>>>>>>> sofia/internal/1001 at y.y.y.y:5060 SOFIA DESTROY
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:111
>>>>>>> sofia/internal/1001 at y.y.y.y:5060 Standard DESTROY
>>>>>>>
>>>>>>> 2015-08-17 15:29:46.361276 [DEBUG] switch_core_state_machine.c:640
>>>>>>> (sofia/internal/1001 at 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 at 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 at y.y.y.y:5060>;tag=gXrZg0KvmrXZH
>>>>>>>
>>>>>>>    From: <sip:1001 at y.y.y.y:5060>;tag=9de53b6c
>>>>>>>
>>>>>>>    Call-ID: kVdW6FArCGqcEPe7zR3x4g..
>>>>>>>
>>>>>>>    CSeq: 2 ACK
>>>>>>>
>>>>>>>    Content-Length: 0
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> ------------------------------------------------------------------------
>>>>>>>
>>>>>>>
>>>>>>> _________________________________________________________________________
>>>>>>> Professional FreeSWITCH Consulting Services:
>>>>>>> consulting at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at conference.freeswitch.org
>> <javascript:_e(%7B%7D,'cvml','sip:888 at 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 at freeswitch.org
>> <javascript:_e(%7B%7D,'cvml','consulting at 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 at lists.freeswitch.org
>> <javascript:_e(%7B%7D,'cvml','FreeSWITCH-users at 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 at conference.freeswitch.org  ☎ +19193869900

https://www.youtube.com/watch?v=9XXgW34t40s
https://www.youtube.com/watch?v=NLaDpGQuZDA
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20150823/0e9b45a1/attachment-0001.html 


Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users mailing list