[OpenSIPS-Users] Unsuccessfull upgrade from 1.4.5 to 1.6.1 (RR module)

[Oleg Burlacu]

Hi,
I'm running a statefull proxy that in most cases need to relay the calls to
a PSTN gateway.
After the migration to the Opensips 1.6.1, there is a problem with
compatibility / RR module and the gateway (Cisco AS5300).
Opensips does not relay 'correctly' (in my case) the ACK messages. The Cisco
gateway do not receive ACKs and hangup the call after a timeout.
The configuration script is developed on the sipwise template, but it works
perfectly in 1.4 version of Opensips.

When debugging I see each time more and more headers in ACK packets
 Record-Route: <sip:xx.yy.56.226;lr=on;ftag=4a734ab13a9>
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=4a734ab13a9>
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=4a734ab13a9>
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=4a734ab13a9>
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKa636.94cf333.2
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKa636.94cf333.2
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKa636.94cf333.2
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKa636.94cf333.2
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000023e34b43aea600004e6700001405

When disabling the record_route(), and messages go from sip client directly
to the gateway - all in ok.
When communicating between 2 sip clients on the same proxy - the messages
are relayed correctly.
What can be the solution?


The entire message log:

 U xx.yy.17.20:5060 -> xx.yy.56.226:5060
INVITE sip:987654 at xx.yy.56.226 SIP/2.0.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026464b446164000023ea000016ed.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>.
Contact: <sip:123456 at xx.yy.17.20>.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 1 INVITE.
Max-Forwards: 70.
User-Agent: SJphone/1.65.377a (SJ Labs).


U xx.yy.56.226:5060 -> xx.yy.17.20:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026464b446164000023ea000016ed.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 1 INVITE.



U xx.yy.56.226:5060 -> xx.yy.17.20:5060
SIP/2.0 407 Proxy Authentication Required.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026464b446164000023ea000016ed.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=c97b4d1cb1f3d0da549e06a8d482ef63.8548.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 1 INVITE.



U xx.yy.17.20:5060 -> xx.yy.56.226:5060
ACK sip:987654 at xx.yy.56.226 SIP/2.0.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026464b446164000023ea000016ed.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=c97b4d1cb1f3d0da549e06a8d482ef63.8548.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 1 ACK.
Max-Forwards: 70.
User-Agent: SJphone/1.65.377a (SJ Labs).
Content-Length: 0.



U xx.yy.17.20:5060 -> xx.yy.56.226:5060
INVITE sip:987654 at xx.yy.56.226 SIP/2.0.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>.
Contact: <sip:123456 at xx.yy.17.20>.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 2 INVITE.
Max-Forwards: 70.
User-Agent: SJphone/1.65.377a (SJ Labs).
Content-Length: 362.
Content-Type: application/sdp.
Supported: replaces,norefersub,timer.
Proxy-Authorization: Digest
username="123456",realm="xx.yy.56.226",nonce="4b43ec220000000883661dbea2617b91d28401983dd85b7d",uri="sip:987654 at xx.yy.56.226
",response="91a62cf62af7870c1e24d6c35857e78d".



U xx.yy.56.226:5060 -> xx.yy.17.20:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 2 INVITE.
Server: OpenSIPS (1.6.1-notls (x86_64/linux)).
Content-Length: 0.


U xx.yy.56.226:5060 -> xx.yy.17.20:5060
SIP/2.0 100 Giving a try.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 2 INVITE.


U xx.yy.56.226:5060 -> xx.yy.25.114:5060
INVITE sip:987654 at xx.yy.25.114:5060;transport=udp SIP/2.0.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKf69b.0ced9bb7.0.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>.
Contact: <sip:123456 at xx.yy.17.20>.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 2 INVITE.
Max-Forwards: 69.



U xx.yy.25.114:5060 -> xx.yy.56.226:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKf69b.0ced9bb7.0,SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow-Events: telephone-event.
Content-Length: 0.



U xx.yy.25.114:5060 -> xx.yy.56.226:5060
SIP/2.0 183 Session Progress.
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKf69b.0ced9bb7.0,SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow-Events: telephone-event.
Contact: <sip:987654 at xx.yy.25.114:5060>.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Content-Disposition: session;handling=required.



U xx.yy.56.226:5060 -> xx.yy.17.20:5060
SIP/2.0 183 Session Progress.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow-Events: telephone-event.
Contact: <sip:987654 at xx.yy.25.114:5060>.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.



U xx.yy.25.114:5060 -> xx.yy.56.226:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKf69b.0ced9bb7.0,SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE,
NOTIFY, INFO.
Allow-Events: telephone-event.
Contact: <sip:987654 at xx.yy.25.114:5060>.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.




U xx.yy.56.226:5060 -> xx.yy.17.20:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE,
NOTIFY, INFO.
Allow-Events: telephone-event.
Contact: <sip:987654 at xx.yy.25.114:5060>.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.



U xx.yy.17.20:5060 -> xx.yy.56.226:5060
ACK sip:987654 at xx.yy.25.114:5060 SIP/2.0.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616500002441000016f6.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Contact: <sip:123456 at xx.yy.17.20>.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 2 ACK.
Max-Forwards: 70.
User-Agent: SJphone/1.65.377a (SJ Labs).
Content-Length: 0.
Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Proxy-Authorization: Digest
username="123456",realm="xx.yy.56.226",nonce="4b43ec220000000883661dbea2617b91d28401983dd85b7d",uri="sip:987654 at xx.yy.56.226
",response="91a62cf62af7870c1e24d6c35857e78d".



U xx.yy.25.114:5060 -> xx.yy.56.226:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKf69b.0ced9bb7.0,SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE,
NOTIFY, INFO.
Allow-Events: telephone-event.
Contact: <sip:987654 at xx.yy.25.114:5060>.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Content-Type: application/sdp.
Content-Length: 234.



U xx.yy.56.226:5060 -> xx.yy.17.20:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE,
NOTIFY, INFO.
Allow-Events: telephone-event.
Contact: <sip:987654 at xx.yy.25.114:5060>.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Content-Type: application/sdp.
Content-Length: 234.



U xx.yy.17.20:5060 -> xx.yy.56.226:5060
ACK sip:987654 at xx.yy.25.114:5060 SIP/2.0.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616500002441000016f6.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Contact: <sip:123456 at xx.yy.17.20>.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 2 ACK.
Max-Forwards: 70.
User-Agent: SJphone/1.65.377a (SJ Labs).
Content-Length: 0.
Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Proxy-Authorization: Digest
username="123456",realm="xx.yy.56.226",nonce="4b43ec220000000883661dbea2617b91d28401983dd85b7d",uri="sip:987654 at xx.yy.56.226
",response="91a62cf62af7870c1e24d6c35857e78d".



U xx.yy.25.114:5060 -> xx.yy.56.226:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKf69b.0ced9bb7.0,SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE,
NOTIFY, INFO.
Allow-Events: telephone-event.
Contact: <sip:987654 at xx.yy.25.114:5060>.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Content-Type: application/sdp.
Content-Length: 234.



U xx.yy.56.226:5060 -> xx.yy.17.20:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE,
NOTIFY, INFO.
Allow-Events: telephone-event.
Contact: <sip:987654 at xx.yy.25.114:5060>.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Content-Type: application/sdp.
Content-Length: 234.



U xx.yy.17.20:5060 -> xx.yy.56.226:5060
ACK sip:987654 at xx.yy.25.114:5060 SIP/2.0.
Via: SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616500002441000016f6.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Contact: <sip:123456 at xx.yy.17.20>.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
CSeq: 2 ACK.
Max-Forwards: 70.
User-Agent: SJphone/1.65.377a (SJ Labs).
Content-Length: 0.
Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Proxy-Authorization: Digest
username="123456",realm="xx.yy.56.226",nonce="4b43ec220000000883661dbea2617b91d28401983dd85b7d",uri="sip:987654 at xx.yy.56.226
",response="91a62cf62af7870c1e24d6c35857e78d".



U xx.yy.25.114:5060 -> xx.yy.56.226:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP xx.yy.56.226;branch=z9hG4bKf69b.0ced9bb7.0,SIP/2.0/UDP
xx.yy.17.20;branch=z9hG4bK591c1114000026474b44616400001647000016f0.
From: "unknown" <sip:123456 at xx.yy.56.226>;tag=152937654c2b.
To: <sip:987654 at xx.yy.56.226>;tag=708A5698-428.
Date: Wed, 06 Jan 2010 09:59:05 GMT.
Call-ID: D17BF0696DC546A7B436401CD27774720x591c1114.
Server: Cisco-SIPGateway/IOS-12.x.
CSeq: 2 INVITE.
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE,
NOTIFY, INFO.
Allow-Events: telephone-event.
Contact: <sip:987654 at xx.yy.25.114:5060>.
Record-Route: <sip:xx.yy.56.226;lr=on;ftag=152937654c2b;did=b39.e643f344>.
Content-Type: application/sdp.
Content-Length: 234.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.opensips.org/pipermail/users/attachments/20100121/6c1fa071/attachment-0001.htm