[OpenSIPS-Users] OpenSIPS send CANCEL to the call legs

Fri Apr 11 13:56:13 CEST 2014

Re-sending the e-mail because capture was too big, puting it into pastebin:

The config file I've tried is the default opensips_trunking.m4.

I don't think the capture will help you much, but here it goes:
x.x.x.x ->softphone's IP (blink)
a.a.a.a->edge SIP server
b.b.b.b->Opensips 11 cancelling the call
c.c.c.c ->final endpoint (sylkserver)
m.m.m.m->mediaproxy

123 is the user registered at a.a.a.a making a call to 123456789 through
b.b.b.b. The capture is done only in opensips server, but look for the
CANCEL with the headers following headers:

Reason: SIP;cause=480;text="NO_ANSWER"
User-Agent: OpenSIPS (1.11.0beta-tls (x86_64/linux)).

http://pastebin.com/nGVEgKLs

Hope it helps to locate the problem,
Samuel.

On 11 April 2014 13:29, samuel <samu60 at gmail.com> wrote:

> The config file I've tried is the default opensips_trunking.m4.
>
> I don't think the capture will help you much, but here it goes:
> x.x.x.x ->softphone's IP (blink)
> a.a.a.a->edge SIP server
> b.b.b.b->Opensips 11 cancelling the call
> c.c.c.c ->final endpoint (sylkserver)
> m.m.m.m->mediaproxy
>
> 123 is the user registered at a.a.a.a making a call to 123456789 through
> b.b.b.b. The capture is done only in opensips server, but look for the
> CANCEL with the headers following headers:
>
> Reason: SIP;cause=480;text="NO_ANSWER"
> User-Agent: OpenSIPS (1.11.0beta-tls (x86_64/linux)).
>
>
> U a.a.a.a:5060 -> b.b.b.b:5060
> INVITE sip:123456789 at testdomain.com SIP/2.0..Record-Route:
> <sip:a.a.a.a;r2=on;lr;fdrrm=4df.1851>..Record-Route: <sip:a.a.a.a;trans
> port=tcp;r2=on;lr;fdrrm=4df.1851>..Via: SIP/2.0/UDP
> a.a.a.a;branch=z9hG4bKf35d.fbe655b5.0;i=9..Via: SIP/2.0/TCP
> x.x.x.x:37731;rport=37731;branc
> h=z9hG4bKPj.2y.6PK1wkCnDxZ9bMJpqssoiLKdQXGB;alias..Max-Forwards: 16..From:
> "123" <sip:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..
> To: <sip:123456789 at testdomain.com>..Contact: <sip:123 at testdomain.com
> ;transport=tcp;gr=urn:uuid:d63b1c4f-d7dc-4f4e-87f1-948123266dc0
> >..Call-ID: Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..CSeq: 31826 INVITE..Allow:
> SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE,
> REFER..Supp
> orted: 100rel, replaces, norefersub, gruu..User-Agent: Blink 0.7.0
> (Linux)..Content-Type: application/sdp..Content-Length:   566....v=0..o=-
> 3606203822 3
> 606203822 IN IP4 x.x.x.x..s=Blink 0.7.0 (Linux)..c=IN IP4 x.x.x.x..t=0
> 0..m=audio 50000 RTP/AVP 113 104 103 9 0 8 101..a=rtcp:50001..a=rtpmap:1
> 13 opus/48000..a=fmtp:113 useinbandfec=1..a=rtpmap:104
> speex/32000..a=rtpmap:103 speex/16000..a=rtpmap:9 G722/8000..a=rtpmap:0
> PCMU/8000..a=rtpmap:8 PCMA
> /8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-15..a=crypto:1
> AES_CM_128_HMAC_SHA1_80
> inline:yTmoivNKmTVIbWNZQmHkXn8BBQ5qTbNBqTSWXB1C..a=crypto:2
> AES_CM_128_HMAC_SHA1_32
> inline:9hLQMH5DHfbh/J+qyrRUEwbEF0BL5gXjQ2xpWze0..a=sendrecv..
>
>
>
> U b.b.b.b:5060 -> c.c.c.c:5060
> INVITE sip:123456789 at c.c.c.c SIP/2.0..Record-Route:
> <sip:b.b.b.b;lr;did=4df.472d8ec1>..Record-Route:
> <sip:a.a.a.a;r2=on;lr;fdrrm=4df.1
> 851>..Record-Route:
> <sip:a.a.a.a;transport=tcp;r2=on;lr;fdrrm=4df.1851>..Via: SIP/2.0/UDP
> b.b.b.b:5060;branch=z9hG4bKf35d.411cb24.0..Via: SIP/2
> .0/UDP a.a.a.a;branch=z9hG4bKf35d.fbe655b5.0;i=9..Via: SIP/2.0/TCP
> x.x.x.x:37731;rport=37731;branch=z9hG4bKPj.2y.6PK1wkCnDxZ9bMJpqssoiLKdQXGB;a
> lias..Max-Forwards: 15..From: "123" <sip:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..To:
> <sip:123456789 at testdomain.com>..Contact: <sip:123 at testdomain.com;transport=tcp;gr=urn:uuid:d63b1c4f-d7dc-4f4e-87f1-948123266dc0>..Call-ID:
> Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso.
> .CSeq: 31826 INVITE..Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE,
> CANCEL, UPDATE, MESSAGE, REFER..Supported: 100rel, replaces, norefersub,
> gruu..Us
> er-Agent: Blink 0.7.0 (Linux)..Content-Type:
> application/sdp..Content-Length:   566....v=0..o=- 3606203822 3606203822IN IP4 x.x.x.x..s=Blink 0.7.0
> (Linux)..c=IN IP4 m.m.m.m..t=0 0..m=audio 50122 RTP/AVP 113 104 103 9 0 8
> 101..a=rtcp:50123..a=rtpmap:113 opus/48000..a=fmtp:113 useinbandfec=1..a=r
> tpmap:104 speex/32000..a=rtpmap:103 speex/16000..a=rtpmap:9
> G722/8000..a=rtpmap:0 PCMU/8000..a=rtpmap:8 PCMA/8000..a=rtpmap:101
> telephone-event/8000..a=f
> mtp:101 0-15..a=crypto:1 AES_CM_128_HMAC_SHA1_80
> inline:yTmoivNKmTVIbWNZQmHkXn8BBQ5qTbNBqTSWXB1
>
>
> U c.c.c.c:5060 -> b.b.b.b:5060
> SIP/2.0 100 Trying..Via: SIP/2.0/UDP
> b.b.b.b:5060;received=b.b.b.b;branch=z9hG4bKf35d.411cb24.0..Via:
> SIP/2.0/UDP a.a.a.a;branch=z9hG4bKf3
> 5d.fbe655b5.0;i=9..Via: SIP/2.0/TCP
> x.x.x.x:37731;rport=37731;branch=z9hG4bKPj.2y.6PK1wkCnDxZ9bMJpqssoiLKdQXGB;alias..Record-Route:
> <sip:b.b.b.b;lr;did=4df.472d8ec1>..Record-Route:
> <sip:a.a.a.a;lr;r2=on;fdrrm=4df.1851>..Record-Route:
> <sip:a.a.a.a;transport=tcp;lr;r2=on;fdrrm=4df.1851>.
> .Call-ID: Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..From: "123" <
> sip:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..To: <
> sip:123456789 at testdomain.com>..CSeq: 31826 INVITE..Server:
> SylkServer-2.6.0..Content-Length:
> 0....
>
> U c.c.c.c:5060 -> b.b.b.b:5060
> SIP/2.0 180 Ringing..Via: SIP/2.0/UDP
> b.b.b.b:5060;received=b.b.b.b;branch=z9hG4bKf35d.411cb24.0..Via:
> SIP/2.0/UDP a.a.a.a;branch=z9hG4bKf
> 35d.fbe655b5.0;i=9..Via: SIP/2.0/TCP
> x.x.x.x:37731;rport=37731;branch=z9hG4bKPj.2y.6PK1wkCnDxZ9bMJpqssoiLKdQXGB;alias..Record-Route:
> <sip:b.b.b.b;lr;did=4df.472d8ec1>..Record-Route:
> <sip:a.a.a.a;lr;r2=on;fdrrm=4df.1851>..Record-Route:
> <sip:a.a.a.a;transport=tcp;lr;r2=on;fdrrm=4df.1851>
> ..Call-ID: Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..From: "123" <
> sip:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..To: <
> sip:123456789 at testdomain.com>;tag=1fZCf5VnwQz0tjARP76mXtcTlCrdKTw7..CSeq:
> 31826 INVITE..Server: SylkServer-2.6.0..Contact: <sip:123456789 at c.c.c.c
> >..Al
> low: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE,
> REFER..Content-Length:
> 0....
>
> U b.b.b.b:5060 -> a.a.a.a:5060
> SIP/2.0 180 Ringing..Via: SIP/2.0/UDP
> a.a.a.a;branch=z9hG4bKf35d.fbe655b5.0;i=9..Via: SIP/2.0/TCP
> x.x.x.x:37731;rport=37731;branch=z9hG4bKPj.2y
> .6PK1wkCnDxZ9bMJpqssoiLKdQXGB;alias..Record-Route:
> <sip:b.b.b.b;lr;did=4df.472d8ec1>..Record-Route:
> <sip:a.a.a.a;lr;r2=on;fdrrm=4df.1851>..Reco
> rd-Route: <sip:a.a.a.a;transport=tcp;lr;r2=on;fdrrm=4df.1851>..Call-ID:
> Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..From: "123" <sip:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..To:
> <sip:123456789 at testdomain.com>;tag=1fZCf5VnwQz0tjARP76mXtcTlCrdKTw7..CSeq:
> 31826 INVITE..Serve
> r: SylkServer-2.6.0..Contact: <sip:123456789 at c.c.c.c>..Allow: SUBSCRIBE,
> NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER..Conten
> t-Length:
> 0....
>
>
> U b.b.b.b:5060 -> c.c.c.c:5060
> CANCEL sip:123456789 at c.c.c.c SIP/2.0..Via: SIP/2.0/UDP
> b.b.b.b:5060;branch=z9hG4bKf35d.411cb24.0..From: "123" <
> sip:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..Call-ID:
> Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..To: <sip:123456789 at testdomain.com>..CSeq:
> 31826 CANCEL
> ..Max-Forwards: 70..Reason: SIP;cause=480;text="NO_ANSWER"..User-Agent:
> OpenSIPS (1.11.0beta-tls (x86_64/linux))..Content-Length:
> 0....
>
> U c.c.c.c:5060 -> b.b.b.b:5060
> SIP/2.0 200 OK..Via: SIP/2.0/UDP
> b.b.b.b:5060;received=b.b.b.b;branch=z9hG4bKf35d.411cb24.0..Call-ID:
> Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..From: "
> 123" <sip:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..To: <
> sip:123456789 at testdomain.com>;tag=1fZCf5VnwQz0tjARP76mXtcT
> lCrdKTw7..CSeq: 31826 CANCEL..Server: SylkServer-2.6.0..Content-Length:
> 0....
>
>
> U c.c.c.c:5060 -> b.b.b.b:5060
> SIP/2.0 487 Request Terminated..Via: SIP/2.0/UDP
> b.b.b.b:5060;received=b.b.b.b;branch=z9hG4bKf35d.411cb24.0..Via:
> SIP/2.0/UDP a.a.a.a;bran
> ch=z9hG4bKf35d.fbe655b5.0;i=9..Via: SIP/2.0/TCP
> x.x.x.x:37731;rport=37731;branch=z9hG4bKPj.2y.6PK1wkCnDxZ9bMJpqssoiLKdQXGB;alias..Record-Route:
> <sip
> :b.b.b.b;lr;did=4df.472d8ec1>..Record-Route:
> <sip:a.a.a.a;lr;r2=on;fdrrm=4df.1851>..Record-Route:
> <sip:a.a.a.a;transport=tcp;lr;r2=on;fdrr
> m=4df.1851>..Call-ID: Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..From: "123" <
> sip:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..To:
> <sip:0034
> 934465010 at testdomain.com>;tag=1fZCf5VnwQz0tjARP76mXtcTlCrdKTw7..CSeq:
> 31826 INVITE..Server: SylkServer-2.6.0..Allow: SUBSCRIBE, NOTIFY, PRACK, I
> NVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER..Content-Length:
> 0....
>
>
>  b.b.b.b:5060 -> c.c.c.c:5060
> ACK sip:123456789 at c.c.c.c SIP/2.0..Via: SIP/2.0/UDP
> b.b.b.b:5060;branch=z9hG4bKf35d.411cb24.0..From: "123" <
> sip:123 at testdomain.com
> >;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..Call-ID:
> Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..To: <sip:123456789 at testdomain.com
> >;tag=1fZCf5VnwQz0tjARP7
> 6mXtcTlCrdKTw7..CSeq: 31826 ACK..Max-Forwards: 70..User-Agent: OpenSIPS
> (1.11.0beta-tls (x86_64/linux))..Content-Length:
> 0....
>
> U b.b.b.b:5060 -> a.a.a.a:5060
> SIP/2.0 500 All GW are down..Via: SIP/2.0/UDP
> a.a.a.a;branch=z9hG4bKf35d.fbe655b5.0;i=9..Via: SIP/2.0/TCP
> x.x.x.x:37731;rport=37731;branch=z9hG
> 4bKPj.2y.6PK1wkCnDxZ9bMJpqssoiLKdQXGB;alias..From: "123" <
> sip:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..To: <
> sip:123456789 at testdomain.com>;tag=155c340f586c28d0300cf5a6ccf90d99-54bb..Call-ID:
> Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..CSeq: 31826 INVITE..Server: OpenSIPS
> (1.11.0beta-tls (x86_64/linux))..Content-Length:
> 0....
>
>
> U a.a.a.a:5060 -> b.b.b.b:5060
> ACK sip:123456789 at testdomain.com SIP/2.0..Via: SIP/2.0/UDP
> a.a.a.a;branch=z9hG4bKf35d.fbe655b5.0;i=9..Max-Forwards: 16..From: "123" <si
> p:123 at testdomain.com>;tag=9pm7KFXnn1JRgEL185WsCtNSMuJfEZ5U..To: <
> sip:123456789 at testdomain.com>;tag=155c340f586c28d0300cf5a6ccf90d99
> -54bb..Call-ID: Okq8A7w3SwXYwfhOwUIBWq7N8kwlCwso..CSeq: 31826
> ACK..Content-Length: 0....
>
> I hope it will help to locate where the problem is,
>
> Samuel.
>
>
>
>
> On 10 April 2014 18:23, Liviu Chircu <liviu at opensips.org> wrote:
>
>>  Hello all,
>>
>> I have tried to replicate this using the stock opensips.cfg, but without
>> much success. Both timeouts seem to trigger correctly...
>>
>> So, in order to make progress with this, could you please provide a SIP
>> trace of this scenario? (using ngrep or tcpdump)
>>
>> Best regards,
>>
>> Liviu Chircu
>> OpenSIPS Developerhttp://www.opensips-solutions.com
>>
>> On 04/10/2014 06:33 PM, samuel wrote:
>>
>>  It does also happens to me with yesterday's  opensips 1.11.0beta-tls*. *I'm
>> not using $T_fr_inv_timeout from config file explicitely.
>>
>>  Only got the following tm module parameters set:
>>
>> modparam("tm", "fr_timer", 5)
>> modparam("tm", "fr_inv_timer", 30)
>> modparam("tm", "restart_fr_on_each_reply", 0)
>> modparam("tm", "onreply_avp_mode", 1)
>>
>>
>>
>> On 10 April 2014 13:06, Liviu Chircu <liviu at opensips.org> wrote:
>>
>>>  Hi Yavari,
>>>
>>> What tm module parameters are you using? Are you setting the
>>> $T_fr_inv_timeout at all?
>>> Could you also please include a SIP trace, so I can successfully try to
>>> replicate this locally?
>>>
>>> Best regards,
>>>
>>> Liviu Chircu
>>> OpenSIPS Developerhttp://www.opensips-solutions.com
>>>
>>>   On 04/10/2014 07:29 AM, H Yavari wrote:
>>>
>>>  Hi Liviu,
>>> I did yesterday (4/9/2014).
>>>
>>>  Regards,
>>> H.Yavari
>>>
>>>   ------------------------------
>>> Hello Yavari,
>>>
>>> When did you do the migration? There was a fix for this particular issue
>>> on Mar 21 [1]
>>>
>>> [1]: https://github.com/OpenSIPS/opensips/commit/ea6ab4d87ce03d2501
>>>
>>> Best regards,
>>>
>>> Liviu Chircu
>>> OpenSIPS Developerhttp://www.opensips-solutions.com
>>>
>>> On 04/09/2014 12:21 PM, H Yavari wrote:
>>>
>>>  Hi all,
>>> I have this flow of calls :
>>> userA<------>asterisk1<----->opensips<------->asterisk2<------->userB
>>>
>>>  in 1.9 every things was ok, but now after the migration to 1.11, when
>>> userA calls to userB, userB ringing one time and after that opensips send
>>> CANCEL to the caller and callee.
>>> I see in asterisk the CANCEL message that opensips sends, has this
>>> reason;
>>>  Reason: SIP;cause=480;text="NO_ANSWER"
>>>
>>>  I see in opensips logs :
>>>
>>>  DBG:tm:timer_routine: timer routine:1,tl=0x7fcf64891d20 next=(nil),
>>> timeout=672
>>> DBG:tm:cancel_branch: sending cancel...
>>> DBG:tm:set_timer: relative timeout is 500000
>>> DBG:tm:insert_timer_unsafe: [4]: 0x7fcf64891db8 (672900000)
>>> DBG:tm:insert_timer_unsafe: [0]: 0x7fcf64891de8 (682)
>>> DBG:tm:final_response_handler: Cancel sent out, sending 408
>>> (0x7fcf64891ad0)
>>>
>>>  I coudn't find any change log related to TM module or any timer. What
>>> is changed from 1.9 to 1.11 about timers?
>>> I changed the value of TM timers (like restart_fr_on_each_reply,... )
>>> but they didn't help.
>>>  please help to me.
>>>  ( I'm so sorry for this migration)
>>>
>>>  Regards,
>>> H.Yavari
>>>
>>>
>>> _______________________________________________
>>> Users mailing listUsers at lists.opensips.orghttp://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>
>>>
>>>
>>> _______________________________________________
>>> Users mailing list
>>> Users at lists.opensips.org
>>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>
>>>
>>>
>>>
>>> _______________________________________________
>>> Users mailing listUsers at lists.opensips.orghttp://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>
>>>
>>>
>>> _______________________________________________
>>> Users mailing list
>>> Users at lists.opensips.org
>>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>
>>>
>>
>>
>> _______________________________________________
>> Users mailing listUsers at lists.opensips.orghttp://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>
>>
>>
>> _______________________________________________
>> Users mailing list
>> Users at lists.opensips.org
>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>
>>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20140411/9cb7070b/attachment-0001.htm>