[Freeswitch-users] INVITE and hangup in the middle of a call

[Oleg Stolyar]

Hi guys,

Several weeks ago I started getting an occasional problem where FS is
sending an INVITE to the other side in the middle of a call, the other side
does not respond and FS hangs up the leg.  Below is the relevant log.  The
user experience is that they keep talking and hearing each other up to the
very end.  I have a recording of that call, so can confirm.

The call uses WebRTC and is originated by JsSip from Chrome.  Then the user
is put into a conference but I doubt it's relevant in this case since the
INVITE and disconnect are not happening from mod_conference

I suspect it's a re-INVITE but what triggers FS to send it?  I couldn't
find anything in the logs that could shed light.

send 1625 bytes to wss/[##.##.##.##]:50292 at 00:00:19.702933:
   ------------------------------------------------------------------------
   INVITE sip:dj012n41 at u40rf5qikah5.invalid;transport=ws;ob SIP/2.0
   Via: SIP/2.0/WSS 10.97.158.232:5067;branch=z9hG4bK7Xm4tjevU45Sr
   Max-Forwards: 70
   From: <sip:answer-c4e14a20-fe82-11e4-95ed-22000b358f22 at anonymous.invalid
>;tag=KQecUSr12rSQp
   To: "user1" <sip:anonymous at anonymous.invalid>;tag=v1rlqab64i
   Call-ID: g8980rbrbk2t45oj5mru
   CSeq: 75703945 INVITE
   Contact: <sip:answer-c4e14a20-fe82-11e4-95ed-22000b358f22@
##.##.###.###:5080;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.5.15b~64bit
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, path, replaces
   Session-Expires: 120;refresher=uac
   Min-SE: 120
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 825

   v=0
   o=FreeSWITCH 1432057287 1432057288 IN IP4 ##.##.##.##
   s=FreeSWITCH
   c=IN IP4 ##.##.##.##
   t=0 0
   a=msid-semantic: WMS uZEfxiWc2Dm3Mj8pRAdC8BQDwtTzC6hW
   m=audio 22670 RTP/SAVPF 9 126 106
   a=rtpmap:9 G722/8000
   a=rtpmap:126 telephone-event/8000
   a=rtpmap:106 CN/8000
   a=ptime:20
   a=fingerprint:sha-256
E4:E2:DD:6C:60:61:69:9D:FD:21:64:79:66:C0:14:58:DD:67:CE:29:35:35:58:65:2E:91:70:85:4C:6C:47:69
   a=rtcp-mux
   a=rtcp:22670 IN IP4 ##.##.##.##
   a=ssrc:1029894069 cname:VL2jTPmLiyFVIEaq
   a=ssrc:1029894069 msid:uZEfxiWc2Dm3Mj8pRAdC8BQDwtTzC6hW a0
   a=ssrc:1029894069 mslabel:uZEfxiWc2Dm3Mj8pRAdC8BQDwtTzC6hW
   a=ssrc:1029894069 label:uZEfxiWc2Dm3Mj8pRAdC8BQDwtTzC6hWa0
   a=ice-ufrag:5dS3Fzx1Thrmdy9Z
   a=ice-pwd:a19UHlvPK1BjvBzrFilbII2s
   a=candidate:3876535948 1 udp 659136 107.20.175.160 22670 typ host
generation 0
   ------------------------------------------------------------------------
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:19.376858 [DEBUG]
switch_core_session.c:1061 Send signal
sofia/leia_agent/anonymous at anonymous.invalid [BREAK]
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:19.376858 [DEBUG]
sofia.c:6627 Channel sofia/leia_agent/anonymous at anonymous.invalid entering
state [calling][0]
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.616874 [DEBUG]
switch_core_session.c:1061 Send signal
sofia/leia_agent/anonymous at anonymous.invalid [BREAK]
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.616874 [DEBUG]
switch_core_session.c:1061 Send signal
sofia/leia_agent/anonymous at anonymous.invalid [BREAK]
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.616874 [DEBUG]
sofia.c:6627 Channel sofia/leia_agent/anonymous at anonymous.invalid entering
state [terminating][503]
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.616874 [NOTICE]
sofia.c:7543 Hangup sofia/leia_agent/anonymous at anonymous.invalid
[CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.616874 [DEBUG]
switch_channel.c:3242 Send signal
sofia/leia_agent/anonymous at anonymous.invalid [KILL]
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.616874 [DEBUG]
switch_core_session.c:1396 Send signal
sofia/leia_agent/anonymous at anonymous.invalid [BREAK]
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
mod_conference.c:5057 Channel leaving conference, cause:
NORMAL_TEMPORARY_FAILURE
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
mod_conference.c:9650 sofia/leia_agent/anonymous at anonymous.invalid skip
receive message [UNBRIDGE] (channel is hungup already)
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
switch_core_media.c:7772 sofia/leia_agent/anonymous at anonymous.invalid skip
receive message [REFER_EVENT] (channel is hungup already)
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
switch_core_codec.c:246 sofia/leia_agent/anonymous at anonymous.invalid
Restore previous codec G722:9.
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
switch_core_session.c:2901 sofia/leia_agent/anonymous at anonymous.invalid
skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
switch_core_state_machine.c:535
(sofia/leia_agent/anonymous at anonymous.invalid) State EXECUTE going to sleep
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
switch_core_state_machine.c:472
(sofia/leia_agent/anonymous at anonymous.invalid) Running State Change
CS_HANGUP
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
switch_core_state_machine.c:735
(sofia/leia_agent/anonymous at anonymous.invalid) Callstate Change ACTIVE ->
HANGUP
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
switch_core_state_machine.c:737
(sofia/leia_agent/anonymous at anonymous.invalid) State HANGUP
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
mod_sofia.c:413 Channel sofia/leia_agent/anonymous at anonymous.invalid
hanging up, cause: NORMAL_TEMPORARY_FAILURE
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
switch_core_state_machine.c:60 sofia/leia_agent/anonymous at anonymous.invalid
Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
fc1927a8-2257-400f-b4cb-67aff7297170 2015-05-20 00:00:22.636900 [DEBUG]
switch_core_state_machine.c:737
(sofia/leia_agent/anonymous at anonymous.invalid) State HANGUP going to sleep
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20150519/383d8f7e/attachment.html