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

Oleg Stolyar olegstolyar at gmail.com
Wed May 27 20:13:36 MSD 2015 

Jira: https://freeswitch.org/jira/browse/FS-7582

It turns out that FS actually does not send a=setup at all in reINVITES.  I
am not comfortable enough with the code to add an SDP attribute.  Also this
is now a low priority for me since there is a simple workaround of
disabling session timers.

On Tue, May 26, 2015 at 9:07 AM, Michael Jerris <mike at jerris.com> wrote:

> Please file a jira for us on that.  Even better with a pull request to fix
> it.
>
> On May 26, 2015, at 11:13 AM, Oleg Stolyar <olegstolyar at gmail.com> wrote:
>
> Just FYI, the JsSip engineers replied to my questions with this:
>
> The error happens because the incoming reINVITE has a=setup:active in
> the SDP which is a bug in FreeSwitch (the RFC clearly states that the
> SDP offer MUST have a=setup:actpass):
>
> On Mon, May 25, 2015 at 7:36 AM, Oleg Stolyar <olegstolyar at gmail.com>
> wrote:
>
>> Thanks Steven!
>>
>> It may be https://freeswitch.org/jira/browse/FS-7040.
>>
>> As far as the 120 sec is concerned - Below are snippets from both the
>> invite and the 200 OK from FS.  I know that FS reads the Session-Expires
>> from the client because if I change it to a value less than 120, FS sends
>> back a "SIP/2.0 422 Session Interval Too Small".  I  even thought the
>> problem could be that the Session-Exipres format in the original INVITE is
>> incorrect since it does not contain ";refresher=uac" but when I added that
>> and made the line "Session-Expires: 300;refresher=uac" nothing changed.
>>
>>
>> INVITE sip:echo-test at anonymous.invalid SIP/2.0
>> Via: SIP/2.0/WSS pfcnm9rjv5en.invalid;branch=z9hG4bK8223029
>> Max-Forwards: 69
>> To: <sip:echo-test at anonymous.invalid>
>> From: "b3N0b2x5YXI" <sip:anonymous at anonymous.invalid>;tag=h1059gcvb2
>> Call-ID: r65e48gp171p21rkppcu
>> CSeq: 6621 INVITE
>> Contact: <sip:mqthb9dj at pfcnm9rjv5en.invalid;transport=ws;ob>
>> Content-Type: application/sdp
>> Session-Expires: 300
>> Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
>> Supported: timer,ice,outbound
>> User-Agent: JsSIP 0.6.26
>> Content-Length: 2754
>>
>> SIP/2.0 200 OK
>> Via: SIP/2.0/WSS
>> pfcnm9rjv5en.invalid;branch=z9hG4bK8223029;received=69.53.236.236;rport=35200
>> From: "b3N0b2x5YXI" <sip:anonymous at anonymous.invalid>;tag=h1059gcvb2
>> To: <sip:echo-test at anonymous.invalid>;tag=SgjX0X4arHUFg
>> Call-ID: r65e48gp171p21rkppcu
>> CSeq: 6621 INVITE
>> Contact: <sip:echo-test at 50.19.255.62:5080;transport=udp>
>> User-Agent: FreeSWITCH-mod_sofia/1.5.15b~64bit
>> Accept: application/sdp
>> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
>> REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
>> Require: timer
>> 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
>> Session-Expires: 120;refresher=uac
>> Content-Type: application/sdp
>> Content-Disposition: session
>> Content-Length: 809
>> X-Netflix: yes
>> Remote-Party-ID: "echo-test" <sip:echo-test at anonymous.invalid
>> >;party=calling;privacy=off;screen=no
>>
>>
>> On Mon, May 25, 2015 at 1:51 AM, Steven Ayre <steveayre at gmail.com> wrote:
>>
>>> The values to actual use are negotiated during the call invite, and your
>>> trace shows it's using 2 minutes.
>>> Session-Expires: 120;refresher=uac
>>> Min-SE: 120
>>>
>>> Just as an idea because you didn't send both invite and re-invite...
>>> perhaps the SDP body is different in the reinvite without the version
>>> number having changed. If so it may be
>>> https://freeswitch.org/jira/browse/FS-7040
>>>
>>> On 20 May 2015 at 15:40, Oleg Stolyar <olegstolyar at gmail.com> wrote:
>>>
>>>> But isn't that based on the session-timeout param which defaults to 30
>>>> min?  My re-invites occur much sooner than 30 min into a call.  Or does
>>>> session-timeout param only control sessions initiated by FS while incoming
>>>> sessions use the minimum-session-expires param if it's not explicitly
>>>> passed by the session initiator?
>>>>
>>>> On Tue, May 19, 2015 at 11:40 PM, Michael Jerris <mike at jerris.com>
>>>> wrote:
>>>>
>>>>> session timer
>>>>>
>>>>>
>>>>> On Tuesday, May 19, 2015, Oleg Stolyar <olegstolyar at gmail.com> wrote:
>>>>>
>>>>>> Thanks Michael, I'll see if we can do that!
>>>>>>
>>>>>> So, is the re-INVITE legit and the problem is that JsSip does not
>>>>>> respond to it?  Still, I am curious what is triggering the re-INVITE.
>>>>>>
>>>>>> On Tue, May 19, 2015 at 8:05 PM, Michael Jerris <mike at jerris.com>
>>>>>> wrote:
>>>>>>
>>>>>>> I think the sip.js guys fixed this issue when they forked jssip.
>>>>>>> I'd suggest using that.
>>>>>>>
>>>>>>> > On May 19, 2015, at 10:01 PM, Oleg Stolyar <olegstolyar at gmail.com>
>>>>>>> wrote:
>>>>>>> >
>>>>>>> > 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
>>>>>>> >
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> _________________________________________________________________________
>>>>>>> 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
>>>>
>>>
>>>
>>> _________________________________________________________________________
>>> 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
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20150527/0c8ef867/attachment-0001.html

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