[OpenSIPS-Users] Problem with 408 Request Timeout

mmarzuola at interfree.it mmarzuola at interfree.it
Wed Apr 15 18:27:07 CEST 2009 

Hi Bogdan.
I changed the following lines in the script from:

failure_route[1] {
        if(t_was_cancelled()) {
                route(9);
        }
}

# Request route 'base-filter-failover'
route[9] {
        if(!t_check_status("408|500|503")) {
                xlog("L_INFO", "No failover routing needed for this response code - M=$rm RURI=$ru F=$fu T=$tu IP=$si ID=$ci\n");
                exit;
        }
}

to:

failure_route[1] {
        if(t_was_cancelled()) {
                route(9);
        }
}

# Request route 'base-filter-failover'
route[9] {
        if(!t_check_status("500|503")) {
                xlog("L_INFO", "No failover routing needed for this response code - M=$rm RURI=$ru F=$fu T=$tu IP=$si ID=$ci\n");
                exit;
        }
}

Now sniffing the traffic I see more 180 Ringing of the callee and the 408 Request Timeout is sent by proxy only after a time greater than the value of the fr_inv_timer (ex:fr_inv_timer=60 sec ==> 408 Request Timeout after 145 sec).

This is the complete trace:

U 10.10.45.102:1024 -> 10.10.45.172:5060
INVITE sip:1002 at 10.10.45.172 SIP/2.0.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport;branch=z9hG4bK0a0a2d660000001d49e605a5658321fc0000000f.
Content-Length: 366.
Contact: <sip:1000 at 10.10.45.102:1024>.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
Content-Type: application/sdp.
CSeq: 1 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Max-Forwards: 70.
To: <sip:1002 at 10.10.45.172>.
User-Agent: SJphone/1.60.299a/L (SJ Labs).
.
v=0.
o=- 3448800293 3448800293 IN IP4 10.10.45.102.
s=SJphone.
c=IN IP4 10.10.45.102.
t=0 0.
a=direction:active.
m=audio 49154 RTP/AVP 3 97 98 110 8 0 101.
a=rtpmap:3 GSM/8000.
a=rtpmap:97 iLBC/8000.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.
a=rtpmap:110 speex/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:0 PCMU/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-11,16.

#
U 10.10.45.172:5060 -> 10.10.45.102:1024
SIP/2.0 407 Proxy Authentication Required.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport=1024;branch=z9hG4bK0a0a2d660000001d49e605a5658321fc0000000f.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 1 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
To: <sip:1002 at 10.10.45.172>;tag=068ab00170139f334f53ff70106fa38e.c6ec.
Proxy-Authenticate: Digest realm="10.10.45.172", nonce="49e6011a000000193a5171874f2b2bab5410bdd9684823ce".
Server: OpenSIPS (1.5.0dev5-notls (i386/linux)).
Content-Length: 0.
.

#
U 10.10.45.102:1024 -> 10.10.45.172:5060
ACK sip:1002 at 10.10.45.172 SIP/2.0.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport;branch=z9hG4bK0a0a2d660000001d49e605a5658321fc0000000f.
Content-Length: 0.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 1 ACK.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Max-Forwards: 70.
To: <sip:1002 at 10.10.45.172>;tag=068ab00170139f334f53ff70106fa38e.c6ec.
User-Agent: SJphone/1.60.299a/L (SJ Labs).
.

#
U 10.10.45.102:1024 -> 10.10.45.172:5060
INVITE sip:1002 at 10.10.45.172 SIP/2.0.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 366.
Contact: <sip:1000 at 10.10.45.102:1024>.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
Content-Type: application/sdp.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Max-Forwards: 70.
To: <sip:1002 at 10.10.45.172>.
User-Agent: SJphone/1.60.299a/L (SJ Labs).
Proxy-Authorization: Digest username="1000",realm="10.10.45.172",nonce="49e6011a000000193a5171874f2b2bab5410bdd9684823ce",uri="sip:1002 at 10.10.45.172",response="b74f5055e95b01eafe38739805415166".
.
v=0.
o=- 3448800293 3448800293 IN IP4 10.10.45.102.
s=SJphone.
c=IN IP4 10.10.45.102.
t=0 0.
a=direction:active.
m=audio 49154 RTP/AVP 3 97 98 110 8 0 101.
a=rtpmap:3 GSM/8000.
a=rtpmap:97 iLBC/8000.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.
a=rtpmap:110 speex/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:0 PCMU/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-11,16.

#
U 10.10.45.172:5060 -> 10.10.45.102:1024
SIP/2.0 100 Giving a try.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport=1024;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
To: <sip:1002 at 10.10.45.172>.
Server: OpenSIPS (1.5.0dev5-notls (i386/linux)).
Content-Length: 0.
.

#
U 10.10.45.172:5060 -> 10.10.45.72:5060
INVITE sip:1002 at 10.10.45.72:5060 SIP/2.0.
Record-Route: <sip:10.10.45.172;lr=on;ftag=2860911806398006426>.
Via: SIP/2.0/UDP 10.10.45.172;branch=z9hG4bK27ab.ee7b5002.0.
Via: SIP/2.0/UDP 10.10.45.102:1024;received=10.10.45.102;rport=1024;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 366.
Contact: <sip:1000 at 10.10.45.102:1024>.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
Content-Type: application/sdp.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Max-Forwards: 69.
To: <sip:1002 at 10.10.45.172>.
User-Agent: SJphone/1.60.299a/L (SJ Labs).
.
v=0.
o=- 3448800293 3448800293 IN IP4 10.10.45.102.
s=SJphone.
c=IN IP4 10.10.45.102.
t=0 0.
a=direction:active.
m=audio 49154 RTP/AVP 3 97 98 110 8 0 101.
a=rtpmap:3 GSM/8000.
a=rtpmap:97 iLBC/8000.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.
a=rtpmap:110 speex/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:0 PCMU/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-11,16.

#
U 10.10.45.72:5060 -> 10.10.45.172:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP 10.10.45.172;branch=z9hG4bK27ab.ee7b5002.0,SIP/2.0/UDP 10.10.45.102:1024;rport=1024;received=10.10.45.102;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 0.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Server: SJphone/1.60.299a/L (SJ Labs).
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
.

#
U 10.10.45.72:5060 -> 10.10.45.172:5060
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 10.10.45.172;branch=z9hG4bK27ab.ee7b5002.0,SIP/2.0/UDP 10.10.45.102:1024;rport=1024;received=10.10.45.102;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 0.
Contact: <sip:1002 at 10.10.45.72:5060>.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Record-Route: <sip:10.10.45.172;lr=on;ftag=2860911806398006426>.
Server: SJphone/1.60.299a/L (SJ Labs).
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
.

#
U 10.10.45.172:5060 -> 10.10.45.102:1024
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport=1024;received=10.10.45.102;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 0.
Contact: <sip:1002 at 10.10.45.72:5060>.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Record-Route: <sip:10.10.45.172;lr=on;ftag=2860911806398006426>.
Server: SJphone/1.60.299a/L (SJ Labs).
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
.

U 10.10.45.72:5060 -> 10.10.45.172:5060
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 10.10.45.172;branch=z9hG4bK27ab.ee7b5002.0,SIP/2.0/UDP 10.10.45.102:1024;rport=1024;received=10.10.45.102;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 0.
Contact: <sip:1002 at 10.10.45.72:5060>.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Record-Route: <sip:10.10.45.172;lr=on;ftag=2860911806398006426>.
Server: SJphone/1.60.299a/L (SJ Labs).
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
.

#
U 10.10.45.172:5060 -> 10.10.45.102:1024
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport=1024;received=10.10.45.102;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 0.
Contact: <sip:1002 at 10.10.45.72:5060>.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Record-Route: <sip:10.10.45.172;lr=on;ftag=2860911806398006426>.
Server: SJphone/1.60.299a/L (SJ Labs).
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
.

U 10.10.45.72:5060 -> 10.10.45.172:5060
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 10.10.45.172;branch=z9hG4bK27ab.ee7b5002.0,SIP/2.0/UDP 10.10.45.102:1024;rport=1024;received=10.10.45.102;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 0.
Contact: <sip:1002 at 10.10.45.72:5060>.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Record-Route: <sip:10.10.45.172;lr=on;ftag=2860911806398006426>.
Server: SJphone/1.60.299a/L (SJ Labs).
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
.

#
U 10.10.45.172:5060 -> 10.10.45.102:1024
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport=1024;received=10.10.45.102;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 0.
Contact: <sip:1002 at 10.10.45.72:5060>.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Record-Route: <sip:10.10.45.172;lr=on;ftag=2860911806398006426>.
Server: SJphone/1.60.299a/L (SJ Labs).
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
.

#
U 10.10.45.172:5060 -> 10.10.45.102:1024
SIP/2.0 408 Request Timeout.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport=1024;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
To: <sip:1002 at 10.10.45.172>;tag=55251676efffdf3e6bbd798bd350b767-9566.
Server: OpenSIPS (1.5.0dev5-notls (i386/linux)).
Content-Length: 0.
.

#
U 10.10.45.102:1024 -> 10.10.45.172:5060
ACK sip:1002 at 10.10.45.172 SIP/2.0.
Via: SIP/2.0/UDP 10.10.45.102:1024;rport;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 0.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 ACK.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Max-Forwards: 70.
To: <sip:1002 at 10.10.45.172>;tag=55251676efffdf3e6bbd798bd350b767-9566.
User-Agent: SJphone/1.60.299a/L (SJ Labs).
.

#
U 10.10.45.172:5060 -> 10.10.45.72:5060
CANCEL sip:1002 at 10.10.45.72:5060 SIP/2.0.
Via: SIP/2.0/UDP 10.10.45.172;branch=z9hG4bK27ab.ee7b5002.0.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
To: <sip:1002 at 10.10.45.172>.
CSeq: 2 CANCEL.
Max-Forwards: 70.
User-Agent: OpenSIPS (1.5.0dev5-notls (i386/linux)).
Content-Length: 0.
.

#
U 10.10.45.72:5060 -> 10.10.45.172:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 10.10.45.172;branch=z9hG4bK27ab.ee7b5002.0.
Content-Length: 0.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 CANCEL.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Server: SJphone/1.60.299a/L (SJ Labs).
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
.

#
U 10.10.45.72:5060 -> 10.10.45.172:5060
SIP/2.0 487 Request Terminated.
Via: SIP/2.0/UDP 10.10.45.172;branch=z9hG4bK27ab.ee7b5002.0,SIP/2.0/UDP 10.10.45.102:1024;rport=1024;received=10.10.45.102;branch=z9hG4bK0a0a2d660000001d49e605a51254e45100000010.
Content-Length: 0.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
CSeq: 2 INVITE.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Server: SJphone/1.60.299a/L (SJ Labs).
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
.

#
U 10.10.45.172:5060 -> 10.10.45.72:5060
ACK sip:1002 at 10.10.45.72:5060 SIP/2.0.
Via: SIP/2.0/UDP 10.10.45.172;branch=z9hG4bK27ab.ee7b5002.0.
From: "Sjphone laptop_User"<sip:1000 at 10.10.45.172>;tag=2860911806398006426.
Call-ID: 27D30394-1DD2-11B2-88E6-FA7AB186875C at 10.10.45.102.
To: "SJphone User"<sip:1002 at 10.10.45.172>;tag=2860714579358653762.
CSeq: 2 ACK.
Max-Forwards: 70.
User-Agent: OpenSIPS (1.5.0dev5-notls (i386/linux)).
Content-Length: 0.
.

Thanks in advance.
Matteo Marzuola







>HI Matteo,

>But can you confirm that the callee device is sending out actually more 
>than one 180 ringing?

>Also, can you send me the full logs (debug=6) for the entire call?

>Regards,
>Bogdan

mmarzuola at interfree.it wrote:
> Hi Bogdan.
> I'm using the 1.4.5 version of opensips. The clients are Sjphone.
> I tried to set 0 the restart_fr_on_each_reply parameter but the 408 is not sent from the proxy.



-------------------------------------------------------------------------------
Valore legale alle tue mail
InterfreePEC - la tua Posta Elettronica Certificata
http://pec.interfree.it
-------------------------------------------------------------------------------

More information about the Users mailing list