<div dir="ltr"><div>I've issue when call comes from PSTN network to Firefox webrtc client, the chrome client is working fine though.<br><br></div><div>The outbound webrtc leg is being rejected with:<br>switch_core_media.c:3073 Declining invite with only SAVP because secure media is administratively disabled<br>
<br></div><div>Any help will be appreciated<br></div><div><br></div>The log is:<br>2014-02-24 04:30:13.749495 [DEBUG] mod_sofia.c:87 sofia/internal/sip:nhjp6lmo@<div>uu7rv2u8nd1k.invalid SOFIA INIT<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_media.c:865 Set Local Key [1 AES_CM_128_HMAC_SHA1_80 inline:6Oas5/0HmIBkrAd7fi0+QFxAicLfB7YJ0CXN4IL4]<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_media.c:865 Set Local Key [1 AES_CM_128_HMAC_SHA1_80 inline:CMm3zL0fHuYDfbbYewXbXlNjRJmCcb0KK6Vm0nwk]<br>2014-02-24 04:30:13.749495 [DEBUG] sofia_glue.c:1196 sip:nhjp6lmo@<a href="tel:83.54.185.253" value="+18354185253" target="_blank">83.54.185.253</a>:54279;transport=wss Setting proxy route to sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid<br>
2014-02-24 04:30:13.749495 [DEBUG] sofia_glue.c:1225 sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid sending invite version: 1.4.2 git 13a6c52 2014-01-27 03:21:14Z 64bit<br>Local SDP:<br>v=0<br>o=FreeSWITCH 1393187049 1393187050 IN IP4 107.21.93.142<br>
s=FreeSWITCH<br>c=IN IP4 107.21.93.142<br>t=0 0<br>a=msid-semantic: WMS PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA<br>m=audio 29164 RTP/SAVPF 0 8 98 101 13<br>a=rtpmap:98 SILK/8000<br>a=fmtp:98 useinbandfec=1; usedtx=0<br>a=rtpmap:101 telephone-event/8000<br>
a=fingerprint:sha-256 B8:80:97:D5:F8:98:CA:44:C0:22:2F:03:AF:65:C3:FC:3B:54:E1:48:57:DB:02:CB:02:B6:47:39:42:17:FC:C6<br>a=rtcp-mux<br>a=rtcp:29164 IN IP4 107.21.93.142<br>a=ssrc:1426965429 cname:fzDRpQc11txIk1v3<br>a=ssrc:1426965429 msid:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA a0<br>
a=ssrc:1426965429 mslabel:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA<br>a=ssrc:1426965429 label:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMAa0<br>a=ice-ufrag:VcNeI1UI1U5MwHdC<br>a=ice-pwd:7HjmnmeeGCNQu8Jg<br>a=candidate:<a href="tel:5066451673" value="+15066451673" target="_blank">5066451673</a> 1 udp 659136 107.21.93.142 29164 typ host generation 0<br>
a=candidate:<a href="tel:5066451673" value="+15066451673" target="_blank">5066451673</a> 2 udp 659136 107.21.93.142 29164 typ host generation 0<br>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:6Oas5/0HmIBkrAd7fi0+QFxAicLfB7YJ0CXN4IL4<br>
a=ptime:20<br>a=sendrecv<br>m=video 20458 RTP/SAVPF 99<br>
a=rtpmap:99 VP8/90000<br>a=fingerprint:sha-256 B8:80:97:D5:F8:98:CA:44:C0:22:2F:03:AF:65:C3:FC:3B:54:E1:48:57:DB:02:CB:02:B6:47:39:42:17:FC:C6<br>a=rtcp-mux<br>a=rtcp:20458 IN IP4 107.21.93.142<br>b=AS:256<br>a=rtcp-fb:99 ccm fir<br>
a=ssrc:730376706 cname:fzDRpQc11txIk1v3<br>a=ssrc:730376706 msid:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA v0<br>a=ssrc:730376706 mslabel:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA<br>a=ssrc:730376706 label:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMAv0<br>
a=ice-ufrag:af1BKEqk7AVuPGwa<br>a=ice-pwd:WpmfpG8BgTaB4PA4<br>a=candidate:0784757969 1 udp 659136 107.21.93.142 20458 typ host generation 0<br>a=candidate:0784757969 2 udp 659134 107.21.93.142 20458 typ host generation 0<br>
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:CMm3zL0fHuYDfbbYewXbXlNjRJmCcb0KK6Vm0nwk<br><br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:40 sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid Standard INIT<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State Change CS_INIT -> CS_ROUTING<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [BREAK]<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State INIT going to sleep<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) Running State Change CS_INIT<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State INIT<br>2014-02-24 04:30:13.749495 [DEBUG] mod_sofia.c:87 sofia/internal/sip:pnojhoc6@epei31817pmt.invalid SOFIA INIT<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) Running State Change CS_ROUTING<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State ROUTING<br>
2014-02-24 04:30:13.749495 [DEBUG] mod_sofia.c:123 sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid SOFIA ROUTING<br>2014-02-24 04:30:13.749495 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [BREAK]<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State ROUTING going to sleep<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) Running State Change CS_CONSUME_MEDIA<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_media.c:865 Set Local Key [1 AES_CM_128_HMAC_SHA1_80 inline:WFQf+xSphedUnC3jORmd/Zb4wrllwVdvf0lR0AV3]<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_media.c:865 Set Local Key [1 AES_CM_128_HMAC_SHA1_80 inline:J3yvmf88hrrerARBBaR46mrd4BtqFK5Mpfowvf+n]<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State CONSUME_MEDIA<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State CONSUME_MEDIA going to sleep<br>2014-02-24 04:30:13.749495 [DEBUG] sofia_glue.c:1196 sip:pnojhoc6@<a href="tel:83.54.185.253" value="+18354185253" target="_blank">83.54.185.253</a>:54585;transport=wss Setting proxy route to sofia/internal/sip:pnojhoc6@epei31817pmt.invalid<br>
2014-02-24 04:30:13.749495 [DEBUG] sofia_glue.c:1225 sofia/internal/sip:pnojhoc6@epei31817pmt.invalid sending invite version: 1.4.2 git 13a6c52 2014-01-27 03:21:14Z 64bit<br>Local SDP:<br>v=0<br>o=FreeSWITCH 1393185249 1393185250 IN IP4 107.21.93.142<br>
s=FreeSWITCH<br>c=IN IP4 107.21.93.142<br>t=0 0<br>a=msid-semantic: WMS iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR<br>m=audio 30964 RTP/SAVPF 0 8 98 101 13<br>a=rtpmap:98 SILK/8000<br>a=fmtp:98 useinbandfec=1; usedtx=0<br>a=rtpmap:101 telephone-event/8000<br>
a=fingerprint:sha-256 B8:80:97:D5:F8:98:CA:44:C0:22:2F:03:AF:65:C3:FC:3B:54:E1:48:57:DB:02:CB:02:B6:47:39:42:17:FC:C6<br>a=rtcp-mux<br>a=rtcp:30964 IN IP4 107.21.93.142<br>a=ssrc:1427072037 cname:gCLCYSwKwxCm1Jng<br>a=ssrc:1427072037 msid:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR a0<br>
a=ssrc:1427072037 mslabel:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR<br>a=ssrc:1427072037 label:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMRa0<br>a=ice-ufrag:nqaySg7Uh4ZQHBxO<br>a=ice-pwd:49hSRDse1UrStEYH<br>a=candidate:7351941118 1 udp 659136 107.21.93.142 30964 typ host generation 0<br>
a=candidate:7351941118 2 udp 659136 107.21.93.142 30964 typ host generation 0<br>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:WFQf+xSphedUnC3jORmd/Zb4wrllwVdvf0lR0AV3<br>a=ptime:20<br>a=sendrecv<br>m=video 23696 RTP/SAVPF 99<br>
a=rtpmap:99 VP8/90000<br>a=fingerprint:sha-256 B8:80:97:D5:F8:98:CA:44:C0:22:2F:03:AF:65:C3:FC:3B:54:E1:48:57:DB:02:CB:02:B6:47:39:42:17:FC:C6<br>a=rtcp-mux<br>a=rtcp:23696 IN IP4 107.21.93.142<br>b=AS:256<br>a=rtcp-fb:99 ccm fir<br>
a=ssrc:730483314 cname:gCLCYSwKwxCm1Jng<br>a=ssrc:730483314 msid:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR v0<br>a=ssrc:730483314 mslabel:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR<br>a=ssrc:730483314 label:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMRv0<br>
a=ice-ufrag:EK5suEPTi5amYPaq<br>a=ice-pwd:XGJqkC11kq8kTn7n<br>a=candidate:7957132764 1 udp 659136 107.21.93.142 23696 typ host generation 0<br>a=candidate:7957132764 2 udp 659134 107.21.93.142 23696 typ host generation 0<br>
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:J3yvmf88hrrerARBBaR46mrd4BtqFK5Mpfowvf+n<br><br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:40 sofia/internal/sip:pnojhoc6@epei31817pmt.invalid Standard INIT<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State Change CS_INIT -> CS_ROUTING<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State INIT going to sleep<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) Running State Change CS_ROUTING<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State ROUTING<br>2014-02-24 04:30:13.749495 [DEBUG] mod_sofia.c:123 sofia/internal/sip:pnojhoc6@epei31817pmt.invalid SOFIA ROUTING<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State ROUTING going to sleep<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) Running State Change CS_CONSUME_MEDIA<br>
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State CONSUME_MEDIA<br>2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State CONSUME_MEDIA going to sleep<br>
2014-02-24 04:30:18.789190 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid [BREAK]<br>2014-02-24 04:30:18.789190 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid entering state [calling][0]<br>
2014-02-24 04:30:23.809184 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid [BREAK]<br>2014-02-24 04:30:23.809184 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid entering state [calling][0]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid entering state [calling][0]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid [BREAK]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid [BREAK]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid [BREAK]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [BREAK]<br>
2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:pnojhoc6@epei31817pmt.invalid entering state [calling][0]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [BREAK]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid entering state [terminated][503]<br>
2014-02-24 04:30:28.889197 [NOTICE] sofia.c:6737 Hangup sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]<br>2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid entering state [terminated][503]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_channel.c:3212 Send signal sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid [KILL]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid [BREAK]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) Running State Change CS_HANGUP<br>2014-02-24 04:30:28.889197 [NOTICE] sofia.c:6737 Hangup sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) State HANGUP<br>2014-02-24 04:30:28.889197 [DEBUG] mod_sofia.c:413 Channel sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid hanging up, cause: NORMAL_TEMPORARY_FAILURE<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_channel.c:3212 Send signal sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid [KILL]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid [BREAK]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) Running State Change CS_HANGUP<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) State HANGUP<br>
2014-02-24 04:30:28.889197 [DEBUG] mod_sofia.c:413 Channel sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid hanging up, cause: NORMAL_TEMPORARY_FAILURE<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:58 sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) State HANGUP going to sleep<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:744 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) Callstate Change DOWN -> HANGUP<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) State Change CS_HANGUP -> CS_REPORTING<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid [BREAK]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) Running State Change CS_REPORTING<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) State REPORTING<br>
2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid entering state [terminated][503]<br>2014-02-24 04:30:28.889197 [NOTICE] sofia.c:6737 Hangup sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:102 sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) State REPORTING going to sleep<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:493 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) State Change CS_REPORTING -> CS_DESTROY<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid [BREAK]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1591 Session 7 (sofia/internal/sip:i6id3piu@5uqfu3lhbc2f.invalid) Locked, Waiting on external entities<br>2014-02-24 04:30:28.889197 [DEBUG] switch_channel.c:3212 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [KILL]<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) Running State Change CS_HANGUP<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State HANGUP<br>2014-02-24 04:30:28.889197 [DEBUG] mod_sofia.c:413 Channel sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid hanging up, cause: NORMAL_TEMPORARY_FAILURE<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:58 sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State HANGUP going to sleep<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:744 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) Callstate Change DOWN -> HANGUP<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State Change CS_HANGUP -> CS_REPORTING<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) Running State Change CS_REPORTING<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State REPORTING<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:102 sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State REPORTING going to sleep<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:493 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) State Change CS_REPORTING -> CS_DESTROY<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1591 Session 9 (sofia/internal/sip:nhjp6lmo@uu7rv2u8nd1k.invalid) Locked, Waiting on external entities<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:58 sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) State HANGUP going to sleep<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:744 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) Callstate Change DOWN -> HANGUP<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) State Change CS_HANGUP -> CS_REPORTING<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) Running State Change CS_REPORTING<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) State REPORTING<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:102 sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) State REPORTING going to sleep<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:493 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) State Change CS_REPORTING -> CS_DESTROY<br>
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid [BREAK]<br>2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1591 Session 8 (sofia/internal/sip:1taotv9a@s5sin55a2uts.invalid) Locked, Waiting on external entities<br>
2014-02-24 04:30:29.689191 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>2014-02-24 04:30:29.689191 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>
2014-02-24 04:30:29.689191 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:pnojhoc6@epei31817pmt.invalid entering state [proceeding][180]<br>2014-02-24 04:30:29.689191 [NOTICE] sofia.c:5986 Ring-Ready sofia/internal/sip:pnojhoc6@epei31817pmt.invalid!<br>
2014-02-24 04:30:29.689191 [DEBUG] switch_channel.c:3267 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) Callstate Change DOWN -> RINGING<br>2014-02-24
04:30:31.689188 [DEBUG] switch_ivr_play_say.c:1718 done playing file
tone_stream://%(2000, 4000, 440.0, 480.0);%(2000, 4000, 440.0,
480.0);%(2000, 4000, 440.0, 480.0)<br>
EXECUTE sofia/external/<a href="mailto:7914@sip.twilio.com" target="_blank">7914@sip.twilio.com</a> playback(tone_stream://%(2000, 4000, 440.0, 480.0);%(2000, 4000, 440.0, 480.0);%(2000, 4000, 440.0, 480.0))<br>2014-02-24 04:30:31.689188 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms<br>
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>
2014-02-24 04:30:37.849189 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:pnojhoc6@epei31817pmt.invalid entering state [completing][200]<br>2014-02-24 04:30:37.849189 [DEBUG] sofia.c:5906 Remote SDP:<br>v=0<br>o=Mozilla-SIPUA-27.0.1 49 0 IN IP4 0.0.0.0<br>
s=SIP Call<br>t=0 0<br>a=ice-ufrag:ccc0f400<br>a=ice-pwd:7df7453c5114d0294e62528f72d9be6e<br>a=fingerprint:sha-256 C5:4C:96:01:BB:0C:83:94:FA:A6:4D:CA:FD:4D:93:8E:B9:1F:27:80:6F:26:AE:2D:A3:3E:81:94:93:D6:99:CB<br>m=audio 50240 RTP/SAVPF 0 101<br>
c=IN IP4 <a href="tel:83.54.185.253" value="+18354185253" target="_blank">83.54.185.253</a><br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-15<br>a=setup:active<br>a=candidate:0 1 UDP <a href="tel:2128609535" value="+12128609535" target="_blank">2128609535</a> 10.10.1.81 50239 typ host<br>
a=candidate:2 1 UDP <a href="tel:2128543999" value="+12128543999" target="_blank">2128543999</a> 192.168.1.102 50240 typ host<br>
a=candidate:3 1 UDP 1692401663 <a href="tel:83.54.185.253" value="+18354185253" target="_blank">83.54.185.253</a> 50240 typ srflx raddr 192.168.1.102 rport 50240<br>a=candidate:0 2 UDP <a href="tel:2128609534" value="+12128609534" target="_blank">2128609534</a> 10.10.1.81 50241 typ host<br>
a=candidate:2 2 UDP <a href="tel:2128543998" value="+12128543998" target="_blank">2128543998</a> 192.168.1.102 50242 typ host<br>a=candidate:3 2 UDP 1692401662 <a href="tel:83.54.185.253" value="+18354185253" target="_blank">83.54.185.253</a> 50242 typ srflx raddr 192.168.1.102 rport 50242<br>
a=rtcp-mux<br>m=video 50244 RTP/SAVPF 99<br>c=IN IP4 <a href="tel:83.54.185.253" value="+18354185253" target="_blank">83.54.185.253</a><br>a=rtpmap:99 VP8/90000<br>a=recvonly<br>a=rtcp-fb:99 ccm fir<br>a=setup:active<br>
a=candidate:0 1 UDP 2128609535 10.10.1.81 50243 typ host<br>a=candidate:2 1 UDP 2128543999 192.168.1.102 50244 typ host<br>
a=candidate:3 1 UDP 1692401663 83.54.185.253 50244 typ srflx raddr 192.168.1.102 rport 50244<br>a=candidate:0 2 UDP 2128609534 10.10.1.81 50245 typ host<br>a=candidate:2 2 UDP 2128543998 192.168.1.102 50246 typ host<br>a=candidate:3 2 UDP 1692401662 83.54.185.253 50246 typ srflx raddr 192.168.1.102 rport 50246<br>
a=rtcp-mux<br><br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1048 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>
2014-02-24 04:30:37.849189 [DEBUG] sofia.c:5896 Channel sofia/internal/sip:pnojhoc6@epei31817pmt.invalid entering state [ready][200]<br><b>2014-02-24
04:30:37.849189 [WARNING] switch_core_media.c:3073 Declining invite
with only SAVP because secure media is administratively disabled</b><br>
2014-02-24 04:30:37.849189 [NOTICE] sofia.c:6704 Hangup sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]<br>2014-02-24 04:30:37.849189 [DEBUG] switch_channel.c:3212 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [KILL]<br>
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>2014-02-24 04:30:37.849189 [DEBUG] sofia.c:1109 Channel is already hungup.<br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) Running State Change CS_HANGUP<br>
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:731 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State HANGUP<br>2014-02-24 04:30:37.849189 [DEBUG] mod_sofia.c:413 Channel sofia/internal/sip:pnojhoc6@epei31817pmt.invalid hanging up, cause: INCOMPATIBLE_DESTINATION<br>
2014-02-24 04:30:37.849189 [DEBUG] mod_sofia.c:465 Sending BYE to sofia/internal/sip:pnojhoc6@epei31817pmt.invalid<br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:58 sofia/internal/sip:pnojhoc6@epei31817pmt.invalid Standard HANGUP, cause: INCOMPATIBLE_DESTINATION<br>
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:731 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State HANGUP going to sleep<br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:744 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) Callstate Change RINGING -> HANGUP<br>
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State Change CS_HANGUP -> CS_REPORTING<br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) Running State Change CS_REPORTING<br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:816 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State REPORTING<br>
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:102 sofia/internal/sip:pnojhoc6@epei31817pmt.invalid Standard REPORTING, cause: INCOMPATIBLE_DESTINATION<br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:816 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State REPORTING going to sleep<br>
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:493 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) State Change CS_REPORTING -> CS_DESTROY<br>2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1383 Send signal sofia/internal/sip:pnojhoc6@epei31817pmt.invalid [BREAK]<br>
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1591 Session 10 (sofia/internal/sip:pnojhoc6@epei31817pmt.invalid) Locked, Waiting on external entities<br>2014-02-24 04:30:37.849189 [DEBUG] switch_ivr_originate.c:3695 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]</div>
</div>