[Freeswitch-users] Issue with PSTN-2-webrtc client call

Alexey Svetliakov asvetliakov at teledini.com
Mon Feb 24 07:57:07 MSK 2014 

I've issue when call comes from PSTN network to Firefox webrtc client, the
chrome client is working fine though.

The outbound webrtc leg is being rejected with:
switch_core_media.c:3073 Declining invite with only SAVP because secure
media is administratively disabled

Any help will be appreciated

The log is:
2014-02-24 04:30:13.749495 [DEBUG] mod_sofia.c:87
sofia/internal/sip:nhjp6lmo@
uu7rv2u8nd1k.invalid SOFIA INIT
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]
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]
2014-02-24 04:30:13.749495 [DEBUG] sofia_glue.c:1196 sip:nhjp6lmo@
83.54.185.253:54279;transport=wss Setting proxy route to
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid
2014-02-24 04:30:13.749495 [DEBUG] sofia_glue.c:1225
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid sending invite version:
1.4.2 git 13a6c52 2014-01-27 03:21:14Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1393187049 1393187050 IN IP4 107.21.93.142
s=FreeSWITCH
c=IN IP4 107.21.93.142
t=0 0
a=msid-semantic: WMS PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA
m=audio 29164 RTP/SAVPF 0 8 98 101 13
a=rtpmap:98 SILK/8000
a=fmtp:98 useinbandfec=1; usedtx=0
a=rtpmap:101 telephone-event/8000
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
a=rtcp-mux
a=rtcp:29164 IN IP4 107.21.93.142
a=ssrc:1426965429 cname:fzDRpQc11txIk1v3
a=ssrc:1426965429 msid:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA a0
a=ssrc:1426965429 mslabel:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA
a=ssrc:1426965429 label:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMAa0
a=ice-ufrag:VcNeI1UI1U5MwHdC
a=ice-pwd:7HjmnmeeGCNQu8Jg
a=candidate:5066451673 1 udp 659136 107.21.93.142 29164 typ host generation
0
a=candidate:5066451673 2 udp 659136 107.21.93.142 29164 typ host generation
0
a=crypto:1 AES_CM_128_HMAC_SHA1_80
inline:6Oas5/0HmIBkrAd7fi0+QFxAicLfB7YJ0CXN4IL4
a=ptime:20
a=sendrecv
m=video 20458 RTP/SAVPF 99
a=rtpmap:99 VP8/90000
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
a=rtcp-mux
a=rtcp:20458 IN IP4 107.21.93.142
b=AS:256
a=rtcp-fb:99 ccm fir
a=ssrc:730376706 cname:fzDRpQc11txIk1v3
a=ssrc:730376706 msid:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA v0
a=ssrc:730376706 mslabel:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMA
a=ssrc:730376706 label:PCWdM0muLkrGR0EWT3QTsGoPaa9wVMMAv0
a=ice-ufrag:af1BKEqk7AVuPGwa
a=ice-pwd:WpmfpG8BgTaB4PA4
a=candidate:0784757969 1 udp 659136 107.21.93.142 20458 typ host generation
0
a=candidate:0784757969 2 udp 659134 107.21.93.142 20458 typ host generation
0
a=crypto:1 AES_CM_128_HMAC_SHA1_80
inline:CMm3zL0fHuYDfbbYewXbXlNjRJmCcb0KK6Vm0nwk

2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:40
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid Standard INIT
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:48
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State Change CS_INIT ->
CS_ROUTING
2014-02-24 04:30:13.749495 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [BREAK]
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:507
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State INIT going to sleep
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) Running State Change
CS_INIT
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:507
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State INIT
2014-02-24 04:30:13.749495 [DEBUG] mod_sofia.c:87
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid SOFIA INIT
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) Running State Change
CS_ROUTING
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:523
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State ROUTING
2014-02-24 04:30:13.749495 [DEBUG] mod_sofia.c:123
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid SOFIA ROUTING
2014-02-24 04:30:13.749495 [DEBUG] switch_ivr_originate.c:67
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State Change CS_ROUTING
-> CS_CONSUME_MEDIA
2014-02-24 04:30:13.749495 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [BREAK]
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:523
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State ROUTING going to
sleep
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) Running State Change
CS_CONSUME_MEDIA
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]
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]
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:542
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State CONSUME_MEDIA
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:542
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State CONSUME_MEDIA
going to sleep
2014-02-24 04:30:13.749495 [DEBUG] sofia_glue.c:1196 sip:pnojhoc6@
83.54.185.253:54585;transport=wss Setting proxy route to
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid
2014-02-24 04:30:13.749495 [DEBUG] sofia_glue.c:1225
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid sending invite version:
1.4.2 git 13a6c52 2014-01-27 03:21:14Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1393185249 1393185250 IN IP4 107.21.93.142
s=FreeSWITCH
c=IN IP4 107.21.93.142
t=0 0
a=msid-semantic: WMS iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR
m=audio 30964 RTP/SAVPF 0 8 98 101 13
a=rtpmap:98 SILK/8000
a=fmtp:98 useinbandfec=1; usedtx=0
a=rtpmap:101 telephone-event/8000
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
a=rtcp-mux
a=rtcp:30964 IN IP4 107.21.93.142
a=ssrc:1427072037 cname:gCLCYSwKwxCm1Jng
a=ssrc:1427072037 msid:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR a0
a=ssrc:1427072037 mslabel:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR
a=ssrc:1427072037 label:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMRa0
a=ice-ufrag:nqaySg7Uh4ZQHBxO
a=ice-pwd:49hSRDse1UrStEYH
a=candidate:7351941118 1 udp 659136 107.21.93.142 30964 typ host generation
0
a=candidate:7351941118 2 udp 659136 107.21.93.142 30964 typ host generation
0
a=crypto:1 AES_CM_128_HMAC_SHA1_80
inline:WFQf+xSphedUnC3jORmd/Zb4wrllwVdvf0lR0AV3
a=ptime:20
a=sendrecv
m=video 23696 RTP/SAVPF 99
a=rtpmap:99 VP8/90000
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
a=rtcp-mux
a=rtcp:23696 IN IP4 107.21.93.142
b=AS:256
a=rtcp-fb:99 ccm fir
a=ssrc:730483314 cname:gCLCYSwKwxCm1Jng
a=ssrc:730483314 msid:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR v0
a=ssrc:730483314 mslabel:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMR
a=ssrc:730483314 label:iD04vvg3VOb2ZDhaoNd4lCgJca6eINMRv0
a=ice-ufrag:EK5suEPTi5amYPaq
a=ice-pwd:XGJqkC11kq8kTn7n
a=candidate:7957132764 1 udp 659136 107.21.93.142 23696 typ host generation
0
a=candidate:7957132764 2 udp 659134 107.21.93.142 23696 typ host generation
0
a=crypto:1 AES_CM_128_HMAC_SHA1_80
inline:J3yvmf88hrrerARBBaR46mrd4BtqFK5Mpfowvf+n

2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:40
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid Standard INIT
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:48
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State Change CS_INIT ->
CS_ROUTING
2014-02-24 04:30:13.749495 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:507
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State INIT going to sleep
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) Running State Change
CS_ROUTING
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:523
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State ROUTING
2014-02-24 04:30:13.749495 [DEBUG] mod_sofia.c:123
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid SOFIA ROUTING
2014-02-24 04:30:13.749495 [DEBUG] switch_ivr_originate.c:67
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State Change CS_ROUTING
-> CS_CONSUME_MEDIA
2014-02-24 04:30:13.749495 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:523
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State ROUTING going to
sleep
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) Running State Change
CS_CONSUME_MEDIA
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:542
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State CONSUME_MEDIA
2014-02-24 04:30:13.749495 [DEBUG] switch_core_state_machine.c:542
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State CONSUME_MEDIA
going to sleep
2014-02-24 04:30:18.789190 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid [BREAK]
2014-02-24 04:30:18.789190 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid entering state [calling][0]
2014-02-24 04:30:23.809184 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid [BREAK]
2014-02-24 04:30:23.809184 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid entering state [calling][0]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid entering state [calling][0]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid entering state [calling][0]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid entering state
[terminated][503]
2014-02-24 04:30:28.889197 [NOTICE] sofia.c:6737 Hangup
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid [CS_CONSUME_MEDIA]
[NORMAL_TEMPORARY_FAILURE]
2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid entering state
[terminated][503]
2014-02-24 04:30:28.889197 [DEBUG] switch_channel.c:3212 Send signal
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid [KILL]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) Running State Change
CS_HANGUP
2014-02-24 04:30:28.889197 [NOTICE] sofia.c:6737 Hangup
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid [CS_CONSUME_MEDIA]
[NORMAL_TEMPORARY_FAILURE]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) State HANGUP
2014-02-24 04:30:28.889197 [DEBUG] mod_sofia.c:413 Channel
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid hanging up, cause:
NORMAL_TEMPORARY_FAILURE
2014-02-24 04:30:28.889197 [DEBUG] switch_channel.c:3212 Send signal
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid [KILL]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) Running State Change
CS_HANGUP
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) State HANGUP
2014-02-24 04:30:28.889197 [DEBUG] mod_sofia.c:413 Channel
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid hanging up, cause:
NORMAL_TEMPORARY_FAILURE
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:58
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid Standard HANGUP, cause:
NORMAL_TEMPORARY_FAILURE
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) State HANGUP going to
sleep
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:744
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) Callstate Change DOWN ->
HANGUP
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:499
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) State Change CS_HANGUP
-> CS_REPORTING
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) Running State Change
CS_REPORTING
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) State REPORTING
2014-02-24 04:30:28.889197 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid entering state
[terminated][503]
2014-02-24 04:30:28.889197 [NOTICE] sofia.c:6737 Hangup
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [CS_CONSUME_MEDIA]
[NORMAL_TEMPORARY_FAILURE]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:102
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid Standard REPORTING, cause:
NORMAL_TEMPORARY_FAILURE
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) State REPORTING going to
sleep
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:493
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) State Change
CS_REPORTING -> CS_DESTROY
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1591 Session 7
(sofia/internal/sip:i6id3piu at 5uqfu3lhbc2f.invalid) Locked, Waiting on
external entities
2014-02-24 04:30:28.889197 [DEBUG] switch_channel.c:3212 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [KILL]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) Running State Change
CS_HANGUP
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State HANGUP
2014-02-24 04:30:28.889197 [DEBUG] mod_sofia.c:413 Channel
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid hanging up, cause:
NORMAL_TEMPORARY_FAILURE
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:58
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid Standard HANGUP, cause:
NORMAL_TEMPORARY_FAILURE
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State HANGUP going to
sleep
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:744
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) Callstate Change DOWN ->
HANGUP
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:499
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State Change CS_HANGUP
-> CS_REPORTING
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) Running State Change
CS_REPORTING
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State REPORTING
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:102
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid Standard REPORTING, cause:
NORMAL_TEMPORARY_FAILURE
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State REPORTING going to
sleep
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:493
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) State Change
CS_REPORTING -> CS_DESTROY
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1591 Session 9
(sofia/internal/sip:nhjp6lmo at uu7rv2u8nd1k.invalid) Locked, Waiting on
external entities
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:58
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid Standard HANGUP, cause:
NORMAL_TEMPORARY_FAILURE
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:731
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) State HANGUP going to
sleep
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:744
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) Callstate Change DOWN ->
HANGUP
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:499
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) State Change CS_HANGUP
-> CS_REPORTING
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) Running State Change
CS_REPORTING
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) State REPORTING
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:102
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid Standard REPORTING, cause:
NORMAL_TEMPORARY_FAILURE
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:816
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) State REPORTING going to
sleep
2014-02-24 04:30:28.889197 [DEBUG] switch_core_state_machine.c:493
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) State Change
CS_REPORTING -> CS_DESTROY
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid [BREAK]
2014-02-24 04:30:28.889197 [DEBUG] switch_core_session.c:1591 Session 8
(sofia/internal/sip:1taotv9a at s5sin55a2uts.invalid) Locked, Waiting on
external entities
2014-02-24 04:30:29.689191 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:29.689191 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:29.689191 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid entering state
[proceeding][180]
2014-02-24 04:30:29.689191 [NOTICE] sofia.c:5986 Ring-Ready
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid!
2014-02-24 04:30:29.689191 [DEBUG] switch_channel.c:3267
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) Callstate Change DOWN ->
RINGING
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)
EXECUTE sofia/external/7914 at sip.twilio.com playback(tone_stream://%(2000,
4000, 440.0, 480.0);%(2000, 4000, 440.0, 480.0);%(2000, 4000, 440.0, 480.0))
2014-02-24 04:30:31.689188 [DEBUG] switch_ivr_play_say.c:1314 Codec
Activated L16 at 8000hz 1 channels 20ms
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:37.849189 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid entering state
[completing][200]
2014-02-24 04:30:37.849189 [DEBUG] sofia.c:5906 Remote SDP:
v=0
o=Mozilla-SIPUA-27.0.1 49 0 IN IP4 0.0.0.0
s=SIP Call
t=0 0
a=ice-ufrag:ccc0f400
a=ice-pwd:7df7453c5114d0294e62528f72d9be6e
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
m=audio 50240 RTP/SAVPF 0 101
c=IN IP4 83.54.185.253
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=setup:active
a=candidate:0 1 UDP 2128609535 10.10.1.81 50239 typ host
a=candidate:2 1 UDP 2128543999 192.168.1.102 50240 typ host
a=candidate:3 1 UDP 1692401663 83.54.185.253 50240 typ srflx raddr
192.168.1.102 rport 50240
a=candidate:0 2 UDP 2128609534 10.10.1.81 50241 typ host
a=candidate:2 2 UDP 2128543998 192.168.1.102 50242 typ host
a=candidate:3 2 UDP 1692401662 83.54.185.253 50242 typ srflx raddr
192.168.1.102 rport 50242
a=rtcp-mux
m=video 50244 RTP/SAVPF 99
c=IN IP4 83.54.185.253
a=rtpmap:99 VP8/90000
a=recvonly
a=rtcp-fb:99 ccm fir
a=setup:active
a=candidate:0 1 UDP 2128609535 10.10.1.81 50243 typ host
a=candidate:2 1 UDP 2128543999 192.168.1.102 50244 typ host
a=candidate:3 1 UDP 1692401663 83.54.185.253 50244 typ srflx raddr
192.168.1.102 rport 50244
a=candidate:0 2 UDP 2128609534 10.10.1.81 50245 typ host
a=candidate:2 2 UDP 2128543998 192.168.1.102 50246 typ host
a=candidate:3 2 UDP 1692401662 83.54.185.253 50246 typ srflx raddr
192.168.1.102 rport 50246
a=rtcp-mux

2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1048 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:37.849189 [DEBUG] sofia.c:5896 Channel
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid entering state [ready][200]
*2014-02-24 04:30:37.849189 [WARNING] switch_core_media.c:3073 Declining
invite with only SAVP because secure media is administratively disabled*
2014-02-24 04:30:37.849189 [NOTICE] sofia.c:6704 Hangup
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [CS_CONSUME_MEDIA]
[INCOMPATIBLE_DESTINATION]
2014-02-24 04:30:37.849189 [DEBUG] switch_channel.c:3212 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [KILL]
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:37.849189 [DEBUG] sofia.c:1109 Channel is already hungup.
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) Running State Change
CS_HANGUP
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:731
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State HANGUP
2014-02-24 04:30:37.849189 [DEBUG] mod_sofia.c:413 Channel
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid hanging up, cause:
INCOMPATIBLE_DESTINATION
2014-02-24 04:30:37.849189 [DEBUG] mod_sofia.c:465 Sending BYE to
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:58
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid Standard HANGUP, cause:
INCOMPATIBLE_DESTINATION
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:731
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State HANGUP going to
sleep
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:744
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) Callstate Change RINGING
-> HANGUP
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:499
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State Change CS_HANGUP
-> CS_REPORTING
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) Running State Change
CS_REPORTING
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:816
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State REPORTING
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:102
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid Standard REPORTING, cause:
INCOMPATIBLE_DESTINATION
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:816
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State REPORTING going to
sleep
2014-02-24 04:30:37.849189 [DEBUG] switch_core_state_machine.c:493
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) State Change
CS_REPORTING -> CS_DESTROY
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1383 Send signal
sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid [BREAK]
2014-02-24 04:30:37.849189 [DEBUG] switch_core_session.c:1591 Session 10
(sofia/internal/sip:pnojhoc6 at epei31817pmt.invalid) Locked, Waiting on
external entities
2014-02-24 04:30:37.849189 [DEBUG] switch_ivr_originate.c:3695 Originate
Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140224/9a48a12c/attachment-0001.html

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