[OpenSIPS-Users] Presence problem

Thu Apr 2 07:37:48 CEST 2009

Hi!
I attach SIP trace from server, opensips config file and log with debug level 7. I can send full packed logs.
In this log
1) 3000000311 PUBLISH open status
2) 3000000300 get valid NOTIFY (3000000300 subscribed to 3000000311 before)
3) 3000000311 subscribe to 3000000300 and get valid NOTIFY
4) 3000000311 subscribe to 3000000311 (itself) and get empty NOTIFY

-----------------
Vladimir Romanov
Scartel Star Lab
CTO
+7 (960) 239-0853

-----Original Message-----
From: Anca Vamanu [mailto:anca at opensips.org] 
Sent: Wednesday, April 01, 2009 4:52 PM
To: Romanov Vladimir
Cc: Users at lists.opensips.org
Subject: Re: [OpenSIPS-Users] Presence problem

Hi Vladimir,

This is strange.
Can you please enable debug logging ( set the debug level to 7) and send 
me(privately) the part of the log starting with when the Subscribe that 
receives a Notify with empty body is received.

Thanks and regards,
Anca

Romanov Vladimir wrote:
>
> Hi all!
>
> From time to time we have strange problem with presence. Some user 
> publish status and another user or same user get NOTIFY with empty 
> body. Here you can see log.
>
> 1) User 3000000311 subscribed to 3000000300. (not in log)
>
> 2) User 3000000300 publish “closed” status (not in log)
>
> 3) User 3000000300 unregister/register (not in log)
>
> 4) User 3000000300 publish status
>
> 5) User 3000000311 get valid NOTIFY
>
> 6) User 3000000300 subscribe to 3000000300
>
> 7) User 3000000300 get EMPTY NOTIFY.
>
> We see this problem in 1.3.2, 1.4.4 and now in 1.5. After service 
> restart problem gone.
>
> U 2009/03/31 20:17:53.670677 10.2.41.227:5060 -> 10.2.170.218:5060
>
> PUBLISH sip:3000000300 at newsip.testik.com SIP/2.0.
>
> Via: SIP/2.0/UDP 
> 10.2.41.227:5060;rport;branch=z9hG4bKPjLaJO1Wh79YgI70M6DTitndmV63rkQSVd.
>
> Max-Forwards: 70.
>
> From: 
> <sip:3000000300 at newsip.testik.com>;tag=nLcp2myfWOJGOxaeaJYPAupvQymWJVSF.
>
> To: <sip:3000000300 at newsip.testik.com>.
>
> Call-ID: 8MO4ZrusKdRT1Y1QsoFzXb0Htk9jBWfk.
>
> CSeq: 26315 PUBLISH.
>
> Event: presence.
>
> SIP-If-Match: a.1238498784.12060.238.4.
>
> Expires: 300.
>
> User-Agent: Scartel SIP Framework.
>
> Content-Type: application/pidf+xml.
>
> Content-Length: 243.
>
> .
>
> <?xml version="1.0" encoding="UTF-8"?>
>
> <presence entity="sip:3000000300 at newsip.testik.com" 
> xmlns="urn:ietf:params:xml:ns:pidf">
>
> <tuple id="cVd0GRUvYAUNy8TwGZLQJxFLZCOzCv9m">
>
> <status>
>
> <basic>open</basic>
>
> </status>
>
> </tuple>
>
> </presence>
>
> #
>
> U 2009/03/31 20:17:53.672017 10.2.170.218:5060 -> 10.2.41.227:5060
>
> SIP/2.0 200 OK.
>
> Via: SIP/2.0/UDP 
> 10.2.41.227:5060;rport=5060;branch=z9hG4bKPjLaJO1Wh79YgI70M6DTitndmV63rkQSVd.
>
> From: 
> <sip:3000000300 at newsip.testik.com>;tag=nLcp2myfWOJGOxaeaJYPAupvQymWJVSF.
>
> To: 
> <sip:3000000300 at newsip.testik.com>;tag=396d496be660f92cee9282162d986068-73b4.
>
> Call-ID: 8MO4ZrusKdRT1Y1QsoFzXb0Htk9jBWfk.
>
> CSeq: 26315 PUBLISH.
>
> Expires: 300.
>
> SIP-ETag: a.1238498784.12064.246.5.
>
> Server: OpenSIPS (1.5.0-notls (x86_64/linux)).
>
> Content-Length: 0.
>
> .
>
> #
>
> U 2009/03/31 20:17:53.672185 10.2.170.218:5060 -> 10.2.42.139:5060
>
> NOTIFY sip:3000000311 at 10.2.42.139:5060 SIP/2.0.
>
> Via: SIP/2.0/UDP 10.2.170.218;branch=z9hG4bKba2d.af11f987.0.
>
> To: 
> <sip:3000000311 at newsip.testik.com>;tag=nGKFnGW2tPQmtpG8pL32KU5kkzVsyu49.
>
> From: 
> <sip:3000000300 at newsip.testik.com>;tag=396d496be660f92cee9282162d986068-36d8.
>
> CSeq: 4 NOTIFY.
>
> Call-ID: TejtOZr.-cCCTnp9ZWS5.kXY0fpI9YMX.
>
> Content-Length: 243.
>
> User-Agent: OpenSIPS (1.5.0-notls (x86_64/linux)).
>
> Max-Forwards: 70.
>
> Event: presence.
>
> Contact: <sip:10.2.170.218:5060>.
>
> Subscription-State: active;expires=263.
>
> Content-Type: application/pidf+xml.
>
> .
>
> <?xml version="1.0" encoding="UTF-8"?>
>
> <presence entity="sip:3000000300 at newsip.testik.com" 
> xmlns="urn:ietf:params:xml:ns:pidf">
>
> <tuple id="cVd0GRUvYAUNy8TwGZLQJxFLZCOzCv9m">
>
> <status>
>
> <basic>open</basic>
>
> </status>
>
> </tuple>
>
> </presence>
>
> #
>
> U 2009/03/31 20:17:53.745153 10.2.42.139:5060 -> 10.2.170.218:5060
>
> SIP/2.0 200 OK.
>
> Via: SIP/2.0/UDP 
> 10.2.170.218;received=10.2.170.218;branch=z9hG4bKba2d.af11f987.0.
>
> Call-ID: TejtOZr.-cCCTnp9ZWS5.kXY0fpI9YMX.
>
> From: 
> <sip:3000000300 at newsip.testik.com>;tag=396d496be660f92cee9282162d986068-36d8.
>
> To: 
> <sip:3000000311 at newsip.testik.com>;tag=nGKFnGW2tPQmtpG8pL32KU5kkzVsyu49.
>
> CSeq: 4 NOTIFY.
>
> Contact: <sip:3000000311 at 10.2.42.139:5060>.
>
> Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, 
> REFER, OPTIONS.
>
> Supported: replaces, 100rel, norefersub, P-SSO-Token.
>
> Content-Length: 0.
>
> .
>
> #
>
> U 2009/03/31 20:17:53.880098 10.2.41.227:5060 -> 10.2.170.218:5060
>
> SUBSCRIBE sip:3000000300 at newsip.testik.com SIP/2.0.
>
> Via: SIP/2.0/UDP 
> 10.2.41.227:5060;rport;branch=z9hG4bKPjVF8rIAAPCMhIVUoVtpmLFFGuHahioPHJ.
>
> Max-Forwards: 70.
>
> From: 
> sip:3000000300 at newsip.testik.com;tag=KRiTvvVB-nTFGqHzxw5A4Q5T7Idi6uf2.
>
> To: sip:3000000300 at newsip.testik.com.
>
> Contact: <sip:3000000300 at 10.2.41.227:5060>.
>
> Call-ID: vbjkFc0yWtQvdVJjeh1ikIYlcjPJBPLv.
>
> CSeq: 10353 SUBSCRIBE.
>
> Event: presence.
>
> Expires: 600.
>
> Accept: application/pidf+xml, application/xpidf+xml.
>
> Allow-Events: presence, refer.
>
> User-Agent: Scartel SIP Framework.
>
> Content-Length: 0.
>
> .
>
> #
>
> U 2009/03/31 20:17:53.881794 10.2.170.218:5060 -> 10.2.41.227:5060
>
> SIP/2.0 200 OK.
>
> Via: SIP/2.0/UDP 
> 10.2.41.227:5060;rport=5060;branch=z9hG4bKPjVF8rIAAPCMhIVUoVtpmLFFGuHahioPHJ.
>
> From: 
> sip:3000000300 at newsip.testik.com;tag=KRiTvvVB-nTFGqHzxw5A4Q5T7Idi6uf2.
>
> To: 
> sip:3000000300 at newsip.testik.com;tag=396d496be660f92cee9282162d986068-1cbc.
>
> Call-ID: vbjkFc0yWtQvdVJjeh1ikIYlcjPJBPLv.
>
> CSeq: 10353 SUBSCRIBE.
>
> Expires: 600.
>
> Contact: <sip:10.2.170.218:5060>.
>
> Server: OpenSIPS (1.5.0-notls (x86_64/linux)).
>
> Content-Length: 0.
>
> .
>
> #
>
> U 2009/03/31 20:17:53.882053 10.2.170.218:5060 -> 10.2.41.227:5060
>
> NOTIFY sip:3000000300 at 10.2.41.227:5060 SIP/2.0.
>
> Via: SIP/2.0/UDP 10.2.170.218;branch=z9hG4bKa987.ede53722.0.
>
> To: 
> <sip:3000000300 at newsip.testik.com>;tag=KRiTvvVB-nTFGqHzxw5A4Q5T7Idi6uf2.
>
> From: 
> <sip:3000000300 at newsip.testik.com>;tag=396d496be660f92cee9282162d986068-1cbc.
>
> CSeq: 1 NOTIFY.
>
> Call-ID: vbjkFc0yWtQvdVJjeh1ikIYlcjPJBPLv.
>
> Content-Length: 0.
>
> User-Agent: OpenSIPS (1.5.0-notls (x86_64/linux)).
>
> Max-Forwards: 70.
>
> Event: presence.
>
> Contact: <sip:10.2.170.218:5060>.
>
> Subscription-State: active;expires=600.
>
> .
>
> #
>
> U 2009/03/31 20:17:54.285596 10.2.41.227:5060 -> 10.2.170.218:5060
>
> SIP/2.0 200 OK.
>
> Via: SIP/2.0/UDP 
> 10.2.170.218;received=10.2.170.218;branch=z9hG4bKa987.ede53722.0.
>
> Call-ID: vbjkFc0yWtQvdVJjeh1ikIYlcjPJBPLv.
>
> From: 
> <sip:3000000300 at newsip.testik.com>;tag=396d496be660f92cee9282162d986068-1cbc.
>
> To: 
> <sip:3000000300 at newsip.testik.com>;tag=KRiTvvVB-nTFGqHzxw5A4Q5T7Idi6uf2.
>
> CSeq: 1 NOTIFY.
>
> Contact: <sip:3000000300 at 10.2.41.227:5060>.
>
> Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, 
> REFER, OPTIONS.
>
> Supported: replaces, 100rel, norefersub, P-SSO-Token.
>
> Content-Length: 0.
>
> .
>
> -----------------
>
> Vladimir Romanov
>
> Scartel Star Lab
>
> CTO
>
> +7 (960) 239-0853
>
> ------------------------------------------------------------------------
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>   

-------------- next part --------------
A non-text attachment was scrubbed...
Name: error.sip-log
Type: application/octet-stream
Size: 7926 bytes
Desc: error.sip-log
Url : http://lists.opensips.org/pipermail/users/attachments/20090402/72640ca3/attachment-0003.obj 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: error.log
Type: application/octet-stream
Size: 66237 bytes
Desc: error.log
Url : http://lists.opensips.org/pipermail/users/attachments/20090402/72640ca3/attachment-0004.obj 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: opensips.cfg
Type: application/octet-stream
Size: 10265 bytes
Desc: opensips.cfg
Url : http://lists.opensips.org/pipermail/users/attachments/20090402/72640ca3/attachment-0005.obj 