[jitsi-dev] late reply to lower CSeq OPTIONS


#1

Hello,

any reason why Jitsi is replying very late to an OPTIONS request that has a lower or equal CSeq number that the previous request in the dialog? It takes over 20 seconds after first OPTIONS to get the 500 reply. I attached the ngrep taken from ncap file from Jitsi log folders. It is nothing related in the log file itself, I can send it over if that helps.

Cheers,
Daniel

U 2012/04/29 15:20:39.443000 192.168.178.21:5060 -> 0.0.0.0:33230
   INVITE sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Record-Route: <sip:192.168.178.21;lr=on;did=cf2.f851>..Via: SIP/2.0/UDP 192.1
   68.178.21;branch=z9hG4bK3336.86415e76.0,SIP/2.0/UDP 192.168.178.22:1024;branch=z9hG4bK-1k331az0szsj;rport=1024..From: "401" <sip:401@192.168.178.21>;tag=zqzke36xdt..To
   : <sip:402@192.168.178.21;user=phone>..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..CSeq: 1 INVITE..Max-Forwards: 69..Contact: <sip:401@192.168.178.22:1024;line=gjr3jtr8>;reg-i
   d=1..X-Serialnumber: 0004132672E3..P-Key-Flags: resolution="31x13", keys="4"..User-Agent: snom370/8.4.35..Accept: application/sdp..Allow: INVITE,ACK,CANCEL,BYE,REFER,O
   PTIONS,NOTIFY,SUBSCRIBE,PRACK,MESSAGE,INFO,UPDATE..Allow-Events: talk,hold,refer,call-info..Supported: timer,100rel,replaces,from-change..Call-Info: <sip:192.168.178.2
   1>;appearance-index=1..Session-Expires: 3600;refresher=uas..Min-SE: 90..Content-Type: application/sdp..Content-Length: 477....v=0..o=root 1809397062 1809397062 IN IP4
   192.168.178.22..s=call..c=IN IP4 192.168.178.22..t=0 0..m=audio 57688 RTP/AVP 0 8 9 2 3 18 4 101..a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:0gXT0Ux7hhbGL9nqxt7l/r8KXAt
   zYiz+Son5MHEY..a=rtpmap:0 PCMU/8000..a=rtpmap:8 PCMA/8000..a=rtpmap:9 G722/8000..a=rtpmap:2 G726-32/8000..a=rtpmap:3 GSM/8000..a=rtpmap:18 G729/8000..a=fmtp:18 annexb=
   no..a=rtpmap:4 G723/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16..a=ptime:20..a=sendrecv..

U 2012/04/29 15:20:39.445000 0.0.0.0:33230 -> 192.168.178.21:5060
   SIP/2.0 180 Ringing..To: <sip:402@192.168.178.21;user=phone>;tag=a0090563..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.86415e76.0,SIP/2.0/UDP 192.168.178.22:102
   4;branch=z9hG4bK-1k331az0szsj;rport=1024..Record-Route: <sip:192.168.178.21;lr=on;did=cf2.f851>..CSeq: 1 INVITE..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..From: "401" <sip:4
   01@192.168.178.21>;tag=zqzke36xdt..Contact: "402" <sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21>..User-Agent: Jitsi1.0-build.3967Mac OS X.
   .Content-Length: 0....

U 2012/04/29 15:20:42.818000 0.0.0.0:33230 -> 192.168.178.21:5060
   SIP/2.0 200 OK..To: <sip:402@192.168.178.21;user=phone>;tag=a0090563..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.86415e76.0,SIP/2.0/UDP 192.168.178.22:1024;bra
   nch=z9hG4bK-1k331az0szsj;rport=1024..Record-Route: <sip:192.168.178.21;lr=on;did=cf2.f851>..CSeq: 1 INVITE..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..From: "401" <sip:401@19
   2.168.178.21>;tag=zqzke36xdt..Contact: "402" <sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21>..User-Agent: Jitsi1.0-build.3967Mac OS X..Cont
   ent-Type: application/sdp..Content-Length: 231....v=0..o=402 0 0 IN IP4 192.168.178.21..s=-..c=IN IP4 192.168.178.21..t=0 0..m=audio 5024 RTP/AVP 0 8 9 3 101..a=rtpmap
   :0 PCMU/8000..a=rtpmap:8 PCMA/8000..a=rtpmap:9 G722/8000..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..

U 2012/04/29 15:20:42.883000 192.168.178.21:5060 -> 0.0.0.0:33230
   ACK sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bKcydzigwkX,SIP/2.0/UDP 192.168.178.
   22:1024;branch=z9hG4bK-5w9vlegiqbpq;rport=1024..From: "401" <sip:401@192.168.178.21>;tag=zqzke36xdt..To: <sip:402@192.168.178.21;user=phone>;tag=a0090563..Call-ID: 3c3
   75b7bdafb-kkaz1fkn5qoe..CSeq: 1 ACK..Max-Forwards: 69..Contact: <sip:401@192.168.178.22:1024;line=gjr3jtr8>;reg-id=1..Content-Length: 0....

U 2012/04/29 15:20:54.247000 192.168.178.21:5060 -> 0.0.0.0:33230
   OPTIONS sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..To: <sip:402@
   192.168.178.21>;tag=a0090563;user=phone..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..CSeq: 1 OPTIONS..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..User-Agent: kamailio (3.3.
   0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:20:54.703000 192.168.178.21:5060 -> 0.0.0.0:33230
   OPTIONS sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..To: <sip:402@
   192.168.178.21>;tag=a0090563;user=phone..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..CSeq: 1 OPTIONS..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..User-Agent: kamailio (3.3.
   0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:20:55.706000 192.168.178.21:5060 -> 0.0.0.0:33230
   OPTIONS sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..To: <sip:402@
   192.168.178.21>;tag=a0090563;user=phone..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..CSeq: 1 OPTIONS..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..User-Agent: kamailio (3.3.
   0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:20:57.705000 192.168.178.21:5060 -> 0.0.0.0:33230
   OPTIONS sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..To: <sip:402@
   192.168.178.21>;tag=a0090563;user=phone..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..CSeq: 1 OPTIONS..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..User-Agent: kamailio (3.3.
   0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:21:01.703000 192.168.178.21:5060 -> 0.0.0.0:33230
   OPTIONS sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..To: <sip:402@
   192.168.178.21>;tag=a0090563;user=phone..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..CSeq: 1 OPTIONS..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..User-Agent: kamailio (3.3.
   0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:21:05.703000 192.168.178.21:5060 -> 0.0.0.0:33230
   OPTIONS sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..To: <sip:402@
   192.168.178.21>;tag=a0090563;user=phone..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..CSeq: 1 OPTIONS..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..User-Agent: kamailio (3.3.
   0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:21:09.704000 192.168.178.21:5060 -> 0.0.0.0:33230
   OPTIONS sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..To: <sip:402@
   192.168.178.21>;tag=a0090563;user=phone..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..CSeq: 1 OPTIONS..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..User-Agent: kamailio (3.3.
   0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:21:13.703000 192.168.178.21:5060 -> 0.0.0.0:33230
   OPTIONS sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..To: <sip:402@
   192.168.178.21>;tag=a0090563;user=phone..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..CSeq: 1 OPTIONS..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..User-Agent: kamailio (3.3.
   0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:21:15.915000 192.168.178.21:5060 -> 0.0.0.0:33230
   BYE sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK0336.7eb201f2.0,SIP/2.0/UDP 192.16
   8.178.22:1024;branch=z9hG4bK-3fdgdkck6rr7;rport=1024..From: "401" <sip:401@192.168.178.21>;tag=zqzke36xdt..To: <sip:402@192.168.178.21>;tag=a0090563;user=phone..Call-I
   D: 3c375b7bdafb-kkaz1fkn5qoe..CSeq: 2 BYE..Max-Forwards: 69..Contact: <sip:401@192.168.178.22:1024;line=gjr3jtr8>;reg-id=1..User-Agent: snom370/8.4.35..RTP-RxStat: Tot
   al_Rx_Pkts=1566,Rx_Pkts=0,Rx_Pkts_Lost=1,Remote_Rx_Pkts_Lost=0..RTP-TxStat: Total_Tx_Pkts=1624,Tx_Pkts=1624,Remote_Tx_Pkts=1519..Content-Length: 0....

U 2012/04/29 15:21:15.918000 0.0.0.0:33230 -> 192.168.178.21:5060
   SIP/2.0 200 OK..To: <sip:402@192.168.178.21>;tag=a0090563;user=phone..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK0336.7eb201f2.0,SIP/2.0/UDP 192.168.178.22:1024;bra
   nch=z9hG4bK-3fdgdkck6rr7;rport=1024..CSeq: 2 BYE..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..From: "401" <sip:401@192.168.178.21>;tag=zqzke36xdt..Contact: "402" <sip:402@192.
   168.178.21:33230;transport=udp;registering_acc=192_168_178_21>..User-Agent: Jitsi1.0-build.3967Mac OS X..Content-Length: 0....

U 2012/04/29 15:21:17.703000 192.168.178.21:5060 -> 0.0.0.0:33230
   OPTIONS sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21 SIP/2.0..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..To: <sip:402@
   192.168.178.21>;tag=a0090563;user=phone..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..CSeq: 1 OPTIONS..Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..User-Agent: kamailio (3.3.
   0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:21:17.705000 0.0.0.0:33230 -> 192.168.178.21:5060
   SIP/2.0 500 Request out of order..To: <sip:402@192.168.178.21>;tag=a0090563;user=phone..Via: SIP/2.0/UDP 192.168.178.21;branch=z9hG4bK3336.a6415e76.0..CSeq: 1 OPTIONS.
   .Call-ID: 3c375b7bdafb-kkaz1fkn5qoe..From: <sip:401@192.168.178.21>;tag=zqzke36xdt..Retry-After: 10..Content-Length: 0....

U 2012/04/29 15:28:57.037000 0.0.0.0:33230 -> 192.168.178.21:5060
   REGISTER sip:192.168.178.21 SIP/2.0..Call-ID: e69f83732a5b6c4bf3f0d7023e361541@0:0:0:0:0:0:0:0..CSeq: 2 REGISTER..From: "402" <sip:402@192.168.178.21>;tag=ab9c4f44..To
   : "402" <sip:402@192.168.178.21>..Via: SIP/2.0/UDP 192.168.178.21:33230;branch=z9hG4bK-393932-71d22a8cf91b621f191dbf42a8e1fd8c..Max-Forwards: 70..User-Agent: Jitsi1.0-
   build.3967Mac OS X..Expires: 600..Contact: "402" <sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21>;expires=600..Content-Length: 0....

U 2012/04/29 15:28:57.041000 192.168.178.21:5060 -> 0.0.0.0:33230
   SIP/2.0 200 OK..Call-ID: e69f83732a5b6c4bf3f0d7023e361541@0:0:0:0:0:0:0:0..CSeq: 2 REGISTER..From: "402" <sip:402@192.168.178.21>;tag=ab9c4f44..To: "402" <sip:402@192.
   168.178.21>;tag=b27e1a1d33761e85846fc98f5f3a7e58.3625..Via: SIP/2.0/UDP 192.168.178.21:33230;branch=z9hG4bK-393932-71d22a8cf91b621f191dbf42a8e1fd8c..Contact: <sip:402@
   192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21>;expires=600..Server: kamailio (3.3.0-dev8 (x86_64/darwin))..Content-Length: 0....

U 2012/04/29 15:35:52.065000 0.0.0.0:33230 -> 192.168.178.21:5060
   REGISTER sip:192.168.178.21 SIP/2.0..Call-ID: e69f83732a5b6c4bf3f0d7023e361541@0:0:0:0:0:0:0:0..CSeq: 3 REGISTER..From: "402" <sip:402@192.168.178.21>;tag=ab9c4f44..To
   : "402" <sip:402@192.168.178.21>..Via: SIP/2.0/UDP 192.168.178.21:33230;branch=z9hG4bK-393932-e9062ec034f8da9aaebebe516ed1779f..Max-Forwards: 70..Expires: 0..Contact:
   "402" <sip:402@192.168.178.21:33230;transport=udp;registering_acc=192_168_178_21>..User-Agent: Jitsi1.0-build.3967Mac OS X..Content-Length: 0....

···

--
Daniel-Constantin Mierla - http://www.asipto.com
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda