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

 

   ------------------------------------------------------------------------