[Kamailio-Users] SIPp dispatcher load testing failure with too many hops - why?

sergejf serge at elasticall.com
Mon Aug 18 21:17:35 CEST 2008


Hi Daniel,

Please find below the capture of a call from INVITE to BYE using the default
SIPp UAC and UAS scenarios that I modified to copy the Contact: header
content to the R-URI of ACK and BYE and the Record-Route: header content to
Route: (though I'm not sure whether it is the desired behaviour; I just
based it on traces I saw from successful calls to a VoIP carrier going
through the same OpenSER configuration). The call fails with a SIP 503 at
the very end. 

There is also a SIP INVITE retransmit from OpenSER that I do not understand
- why does the OpenSER do this? Since the UAS scenario of SIPp doesn't
expect that SIP INVITE it aborts with an "unexpected message" condition.
Further BYE messages are not answered by SIPp UAS.

The scenario actually works for the first few calls. I had up to 10 calls
processed successfully like that. It starts failing when more calls are made
(e.g. 50) and unexpected SIP messages start to occur.

I hope the trace below helps in figuring out whether Record Routing handling
in SIPp is the culprit here.

Thanks a lot for your help.

Serge


14:28:33.902070 IP (tos 0x0, ttl 124, id 14924, offset 0, flags [none],
proto: UDP (17), length: 542) tmo120.sip-tls > sip100.sip: [udp sum ok] SIP,
length: 514
        INVITE sip:service at 63.XXX.XX.110:5060 SIP/2.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-0
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 INVITE
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 70
        Subject: Performance Test
        Content-Type: application/sdp
        Content-Length:   129

        v=0
        o=user1 53655765 2353687637 IN IP4 8.XX.XX.10
        s=-
        c=IN IP4 8.XX.XX.10
        t=0 0
        m=audio 6000 RTP/AVP 0
        a=rtpmap:0 PCMU/8000

14:28:34.459706 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 323) sip100.sip > tmo120.sip-tls: [bad udp cksum 3cbc!]
SIP, length: 295
        SIP/2.0 100 Giving a try
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-0
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 INVITE
        Server: OpenSER (1.3.2-notls (x86_64/linux))
        Content-Length: 0

14:28:34.603824 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 651) sip100.sip > tmo122.sip: [bad udp cksum 1e2f!] SIP,
length: 623
        INVITE sip:8.XX.XX.12:5060 SIP/2.0
        Record-Route: <sip:63.XXX.XX.110;lr;ftag=4068SIPpTag008>
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK5016.872b3ad4.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-0
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 INVITE
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 12
        Subject: Performance Test
        Content-Type: application/sdp
        Content-Length:   129

        v=0
        o=user1 53655765 2353687637 IN IP4 8.XX.XX.10
        s=-
        c=IN IP4 8.XX.XX.10
        t=0 0
        m=audio 6000 RTP/AVP 0
        a=rtpmap:0 PCMU/8000

14:28:34.604602 IP (tos 0x0, ttl 124, id 20573, offset 0, flags [none],
proto: UDP (17), length: 446) tmo122.sip > sip100.sip: [udp sum ok] SIP,
length: 418
        SIP/2.0 180 Ringing
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK5016.872b3ad4.0,
SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-0
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 INVITE
        Contact: <sip:8.XX.XX.12:5060;transport=UDP>
        Route:  <sip:63.XXX.XX.110;lr;ftag=4068SIPpTag008>
        Content-Length: 0

14:28:34.604936 IP (tos 0x0, ttl 124, id 20574, offset 0, flags [none],
proto: UDP (17), length: 605) tmo122.sip > sip100.sip: [udp sum ok] SIP,
length: 577
        SIP/2.0 200 OK
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK5016.872b3ad4.0,
SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-0
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 INVITE
        Contact: <sip:8.XX.XX.12:5060;transport=UDP>
        Route:  <sip:63.XXX.XX.110;lr;ftag=4068SIPpTag008>
        Content-Type: application/sdp
        Content-Length:   129

        v=0
        o=user1 53655765 2353687637 IN IP4 8.XX.XX.12
        s=-
        c=IN IP4 8.XX.XX.12
        t=0 0
        m=audio 6000 RTP/AVP 0
        a=rtpmap:0 PCMU/8000
        
14:28:35.083466 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 651) sip100.sip > tmo122.sip: [bad udp cksum 1e2f!] SIP,
length: 623
        INVITE sip:8.XX.XX.12:5060 SIP/2.0
        Record-Route: <sip:63.XXX.XX.110;lr;ftag=4068SIPpTag008>
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK5016.872b3ad4.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-0
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 INVITE
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 12
        Subject: Performance Test
        Content-Type: application/sdp
        Content-Length:   129

        v=0
        o=user1 53655765 2353687637 IN IP4 8.XX.XX.10
        s=-
        c=IN IP4 8.XX.XX.10
        t=0 0
        m=audio 6000 RTP/AVP 0
        a=rtpmap:0 PCMU/8000

14:28:35.272943 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 389) sip100.sip > tmo120.sip-tls: [bad udp cksum cf26!]
SIP, length: 361
        SIP/2.0 180 Ringing
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-0
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 INVITE
        Contact: <sip:8.XX.XX.12:5060;transport=UDP>
        Route:  <sip:63.XXX.XX.110;lr;ftag=4068SIPpTag008>
        Content-Length: 0

14:28:35.346657 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 548) sip100.sip > tmo120.sip-tls: [bad udp cksum b2a1!]
SIP, length: 520
        SIP/2.0 200 OK
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-0
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 INVITE
        Contact: <sip:8.XX.XX.12:5060;transport=UDP>
        Route:  <sip:63.XXX.XX.110;lr;ftag=4068SIPpTag008>
        Content-Type: application/sdp
        Content-Length:   129

        v=0
        o=user1 53655765 2353687637 IN IP4 8.XX.XX.12
        s=-
        c=IN IP4 8.XX.XX.12
        t=0 0
        m=audio 6000 RTP/AVP 0
        a=rtpmap:0 PCMU/8000

14:28:35.347459 IP (tos 0x0, ttl 124, id 15702, offset 0, flags [none],
proto: UDP (17), length: 403) tmo120.sip-tls > sip100.sip: [udp sum ok] SIP,
length: 375
        ACK sip:service at 8.XX.XX.12:5060 SIP/2.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-5
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 ACK
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 70
        Route: (null)
        Subject: Performance Test
        Content-Length: 0

14:28:35.347721 IP (tos 0x0, ttl 124, id 15703, offset 0, flags [none],
proto: UDP (17), length: 403) tmo120.sip-tls > sip100.sip: [udp sum ok] SIP,
length: 375
        BYE sip:service at 8.XX.XX.12:5060 SIP/2.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-7
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 2 BYE
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 70
        Route: (null)
        Subject: Performance Test
        Content-Length: 0

14:28:37.238897 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 465) sip100.sip > tmo122.sip: [bad udp cksum ccfe!] SIP,
length: 437
        ACK sip:service at 8.XX.XX.12:5060 SIP/2.0
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK5016.872b3ad4.2
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-5
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 1 ACK
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 12
        Route: (null)
        Subject: Performance Test
        Content-Length: 0

14:28:37.401885 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 465) sip100.sip > tmo122.sip: [bad udp cksum a539!] SIP,
length: 437
        BYE sip:service at 8.XX.XX.12:5060 SIP/2.0
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK2016.2ea42581.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-7
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 2 BYE
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 12
        Route: (null)
        Subject: Performance Test
        Content-Length: 0

14:28:37.883523 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 465) sip100.sip > tmo122.sip: [bad udp cksum a539!] SIP,
length: 437
        BYE sip:service at 8.XX.XX.12:5060 SIP/2.0
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK2016.2ea42581.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-7
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 2 BYE
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 12
        Route: (null)
        Subject: Performance Test
        Content-Length: 0

14:28:38.883574 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 465) sip100.sip > tmo122.sip: [bad udp cksum a539!] SIP,
length: 437
        BYE sip:service at 8.XX.XX.12:5060 SIP/2.0
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK2016.2ea42581.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-7
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 2 BYE
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 12
        Route: (null)
        Subject: Performance Test
        Content-Length: 0

14:28:40.883600 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 465) sip100.sip > tmo122.sip: [bad udp cksum a539!] SIP,
length: 437
        BYE sip:service at 8.XX.XX.12:5060 SIP/2.0
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK2016.2ea42581.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-7
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 2 BYE
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 12
        Route: (null)
        Subject: Performance Test
        Content-Length: 0

14:28:44.883740 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 465) sip100.sip > tmo122.sip: [bad udp cksum a539!] SIP,
length: 437
        BYE sip:service at 8.XX.XX.12:5060 SIP/2.0
        Via: SIP/2.0/UDP 63.XXX.XX.110;branch=z9hG4bK2016.2ea42581.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-7
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 2 BYE
        Contact: sip:sipp at 8.XX.XX.10:5061
        Max-Forwards: 12
        Route: (null)
        Subject: Performance Test
        Content-Length: 0

14:28:46.483874 IP (tos 0x10, ttl  64, id 0, offset 0, flags [DF], proto:
UDP (17), length: 346) sip100.sip > tmo120.sip-tls: [bad udp cksum 926e!]
SIP, length: 318
        SIP/2.0 503 Service Unavailable
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8-7
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 2 BYE
        Server: OpenSER (1.3.2-notls (x86_64/linux))
        Content-Length: 0

14:28:46.485241 IP (tos 0x0, ttl 124, id 21542, offset 0, flags [none],
proto: UDP (17), length: 381) tmo120.sip-tls > sip100.sip: [udp sum ok] SIP,
length: 353
        BYE sip:service at 63.XXX.XX.110:5060 SIP/2.0
        Via: SIP/2.0/UDP 8.XX.XX.10:5061;branch=z9hG4bK-4068-8--1
        From: sipp <sip:sipp at 8.XX.XX.10:5061>;tag=4068SIPpTag008
        To: sut <sip:service at 63.XXX.XX.110:5060>;tag=2964SIPpTag018
        Call-ID: 8-4068 at 8.XX.XX.10
        CSeq: 3 BYE
        Max-Forwards: 70
        Contact: <sip:sipp at 8.XX.XX.10:5061;transport=UDP>
        Content-Length: 0
-- 
View this message in context: http://www.nabble.com/SIPp-dispatcher-load-testing-failure-with-too-many-hops---why--tp19034416p19037723.html
Sent from the OpenSER Users Mailing List mailing list archive at Nabble.com.





More information about the Users mailing list