[reSIProcate] Performance of a P2P-over-SIP program using resiprocate
Byron Campen
bcampen at estacado.net
Fri May 4 11:36:34 CDT 2007
Looking at the resip logs, that ACK must have failed the preparse
(the logs indicate that something was received, but it was dropped).
Because there is nothing readily apparent in the trace, my guess is
that sipp did something wrong with line-termination. You'll need to
get a raw capture of that ACK that preserves the line-termination
characteristics (my bet is that the line-terminator on the Via is
messed up). Instead of using regexp replacement, I would use the
[last_Via] directive. That will ensure the branch param is what it
needs to be.
Best regards,
Byron Campen
> Hello!
>
> Thank you for answering to my post.
>
> 1. Using 606 is historical from a previous project. I think it
> should not be too difficult to change this to a 4xx.
>
> 2. Here is my scenario :
> ========================
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <scenario name="super-peer stress test UAC">
> <!-- In client mode (sipp placing calls), the Call-ID MUST be -->
> <!-- generated by sipp. To do so, use [call_id] keyword. -->
> <send start_rtd="1">
> <![CDATA[
> INVITE sip:spzero@[remote_ip]:[remote_port] SIP/2.0
> Via: SIP/2.0/UDP [local_ip]:[local_port];branch=[branch]
> Max-Forwards: 70
> Contact: <sip:[field1]@[local_ip]:[local_port]>
> To: spzero <sip:spzero@[remote_ip]:[remote_port]>
> From: [field1] <sip:[field1]@[local_ip]:[local_port]>;tag=
> [call_number]
> Call-ID: [call_id]
> CSeq: [cseq] INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: [len]
>
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <request>
> <name>[field3]</name>
> <extended type="size">0</extended>
> <extended type="date">01.01.1900-01.01.2100</extended>
> </request>
>
> ]]>
> </send>
>
> <recv response="100" optional="true" />
>
> <recv response="606" rtd="1">
> <action>
> <ereg regexp="branch=.*" search_in="hdr" header="Via:"
> assign_to="1" />
> </action>
> </recv>
>
> <send>
> <![CDATA[ ACK sip:spzero@[remote_ip]:[remote_port] SIP/2.0
> Via: SIP/2.0/UDP [local_ip]:[local_port];[$1]
> To: spzero <sip:spzero@[remote_ip]:[remote_port]>
> [peer_tag_param]
> From: [field1] <sip:[field1]@[local_ip]:[local_port]>;tag=
> [call_number]
> Call-ID: [call_id]
> CSeq: [cseq] ACK
> Content-Length: 0
>
> ]]>
> </send>
>
> </scenario>
>
> 3. Below I paste the first three packets of a wireshark trace, and
> then my debug level log. As you can see the branch parameter from
> the ACK matches that of the INVITE.
>
> ===============
> No. Time Source Destination
> Protocol Info
> 14 21.034909 192.168.61.132 192.168.61.130 SIP/
> XML Request: INVITE sip:spzero at 192.168.61.130:12005
>
> Frame 14 (619 bytes on wire, 619 bytes captured)
> Ethernet II, Src: Vmware_5f:e3:82 (00:0c:29:5f:e3:82), Dst:
> Vmware_f2:ad:ed (00:0c:29:f2:ad:ed)
> Internet Protocol, Src: 192.168.61.132 (192.168.61.132), Dst:
> 192.168.61.130 (192.168.61.130)
> User Datagram Protocol, Src Port: 5060 (5060), Dst Port: 12005 (12005)
> Session Initiation Protocol
> Request-Line: INVITE sip:spzero at 192.168.61.130:12005 SIP/2.0
> Method: INVITE
> [Resent Packet: False]
> Message Header
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> Max-Forwards: 70
> Contact: <sip:valiant at 192.168.61.132:5060>
> To: spzero <sip:spzero at 192.168.61.130:12005>
> From: valiant <sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 179
> Message body
>
> No. Time Source Destination
> Protocol Info
> 15 21.185323 192.168.61.130 192.168.61.132 SIP/
> XML Status: 606 Not Acceptable
>
> Frame 15 (392 bytes on wire, 392 bytes captured)
> Ethernet II, Src: Vmware_f2:ad:ed (00:0c:29:f2:ad:ed), Dst:
> Vmware_5f:e3:82 (00:0c:29:5f:e3:82)
> Internet Protocol, Src: 192.168.61.130 (192.168.61.130), Dst:
> 192.168.61.132 (192.168.61.132)
> User Datagram Protocol, Src Port: 12005 (12005), Dst Port: 5060 (5060)
> Session Initiation Protocol
> Status-Line: SIP/2.0 606 Not Acceptable
> Status-Code: 606
> [Resent Packet: False]
> Message Header
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=2dfed151
> From: "valiant"<sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Type: text/xml
> Content-Length: 50
> Message body
>
> No. Time Source Destination
> Protocol Info
> 16 21.199058 192.168.61.132 192.168.61.130
> SIP Request: ACK sip:spzero at 192.168.61.130:12005
>
> Frame 16 (329 bytes on wire, 329 bytes captured)
> Ethernet II, Src: Vmware_5f:e3:82 (00:0c:29:5f:e3:82), Dst:
> Vmware_f2:ad:ed (00:0c:29:f2:ad:ed)
> Internet Protocol, Src: 192.168.61.132 (192.168.61.132), Dst:
> 192.168.61.130 (192.168.61.130)
> User Datagram Protocol, Src Port: 5060 (5060), Dst Port: 12005 (12005)
> Session Initiation Protocol
> Request-Line: ACK sip:spzero at 192.168.61.130:12005 SIP/2.0
> Method: ACK
> [Resent Packet: False]
> Message Header
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> To: spzero <sip:spzero at 192.168.61.130:12005>;tag=2dfed151
> From: valiant <sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 ACK
> Content-Length: 0
> =================
> DEBUG | 20070504-175353.889 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> Security.cxx:893 | BaseSecurity::BaseSecurity
> DEBUG | 20070504-175353.890 | kenobi | nsp | RESIP:DNS | 3850 |
> 3080010112 | dns/AresDns.cxx:102 | number of name servers found 1
> DEBUG | 20070504-175353.890 | kenobi | nsp | RESIP:DNS | 3850 |
> 3080010112 | dns/AresDns.cxx:105 | name server 192.168.61.2
> DEBUG | 20070504-175353.890 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> Compression.cxx:40 | COMPRESSION SUPPORT NOT COMPILED IN
> DEBUG | 20070504-175353.891 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> Compression.cxx:43 | Compression configuration object created;
> algorithm
> = 0
> DEBUG | 20070504-175353.891 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | TransportSelector.cxx:86 | No compression library
> available
> DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> Security.cxx:893 | BaseSecurity::BaseSecurity
> DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP:DNS | 3850 |
> 3080010112 | dns/AresDns.cxx:102 | number of name servers found 1
> DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP:DNS | 3850 |
> 3080010112 | dns/AresDns.cxx:105 | name server 192.168.61.2
> DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> Compression.cxx:40 | COMPRESSION SUPPORT NOT COMPILED IN
> DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> Compression.cxx:43 | Compression configuration object created;
> algorithm
> = 0
> DEBUG | 20070504-175353.893 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | TransportSelector.cxx:86 | No compression library
> available
> INFO | 20070504-175353.893 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | UdpTransport.cxx:42 | Creating UDP transport host=
> port=12005 ipv4=1
> DEBUG | 20070504-175353.894 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | InternalTransport.cxx:86 | Creating fd=3 V4/UDP
> DEBUG | 20070504-175353.894 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | InternalTransport.cxx:94 | Binding to 0.0.0.0
> DEBUG | 20070504-175353.894 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | UdpTransport.cxx:59 | No compression library available:
> Transport: [ V4 0.0.0.0:12005 UDP target domain=unspecified
> connectionId=0 ]
> DEBUG | 20070504-175353.894 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | TransportSelector.cxx:155 | Adding transport: [ V4
> 0.0.0.0:12005 UDP target domain=unspecified connectionId=0 ]
> DEBUG | 20070504-175353.895 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | UserProfile.cxx:135 | Adding credential:
> realm=192.168.61.130 user=spzero
> INFO | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | UdpTransport.cxx:42 | Creating UDP transport host=
> port=12010 ipv4=1
> DEBUG | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | InternalTransport.cxx:86 | Creating fd=6 V4/UDP
> DEBUG | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | InternalTransport.cxx:94 | Binding to 0.0.0.0
> DEBUG | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | UdpTransport.cxx:59 | No compression library available:
> Transport: [ V4 0.0.0.0:12010 UDP target domain=unspecified
> connectionId=0 ]
> DEBUG | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | TransportSelector.cxx:155 | Adding transport: [ V4
> 0.0.0.0:12010 UDP target domain=unspecified connectionId=0 ]
> DEBUG | 20070504-175353.903 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | UserProfile.cxx:135 | Adding credential:
> realm=192.168.61.130 user=spzero
> DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | Transport.cxx:287 | incoming from: [ V4
> 192.168.61.132:5060
> UDP target domain=unspecified received on: Transport: [ V4
> 0.0.0.0:12005
> UDP target domain=unspecified connectionId=0 ] connectionId=0 ]
> DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TransactionUser.cxx:66 | Checking if SipReq: INVITE
> spzero at 192.168.61.130:12005 tid=-3788-1-0 cseq=INVITE
> contact=valiant at 192.168.61.132:5060 / 1 from(wire) is for meDEBUG |
> 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION | 3850 |
> 3080010112 | TransactionUser.cxx:71 | Checking rule...DEBUG |
> 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION | 3850 |
> 3080010112 | MessageFilterRule.cxx:42 | Matching rule for:
>
> INVITE sip:spzero at 192.168.61.130:12005 SIP/2.0
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> Max-Forwards: 70
> Contact: <sip:valiant at 192.168.61.132:5060>
> To: spzero <sip:spzero at 192.168.61.130:12005>
> From: valiant <sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 179
>
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <request>
> <name>Loo</name>
> <extended type="size">0</extended>
> <extended type="date">01.01.1900-01.01.2100</extended>
> </request>
>
> DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TransactionUser.cxx:74 | Match!
> DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> Helper.cxx:372 | Helper::makeResponse(SipReq: INVITE
> spzero at 192.168.61.130:12005 tid=-3788-1-0 cseq=INVITE
> contact=valiant at 192.168.61.132:5060 / 1 from(wire) code=100 reason=
> DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TimerQueue.cxx:85 | Adding timer: Timer Trying
> tid=-3788-1-0 ms=80
> DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TransactionState.cxx:1852 | Send to TU: TU:
> DialogUsageManager size=0
>
> INVITE sip:spzero at 192.168.61.130:12005 SIP/2.0
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> Max-Forwards: 70
> Contact: <sip:valiant at 192.168.61.132:5060>
> To: spzero <sip:spzero at 192.168.61.130:12005>
> From: valiant <sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 179
>
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <request>
> <name>Loo</name>
> <extended type="size">0</extended>
> <extended type="date">01.01.1900-01.01.2100</extended>
> </request>
>
> INFO | 20070504-175401.521 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogUsageManager.cxx:1227 | Got: SipReq: INVITE
> spzero at 192.168.61.130:12005 tid=-3788-1-0 cseq=INVITE
> contact=valiant at 192.168.61.132:5060 / 1 from(wire)
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogUsageManager.cxx:1494 |
> DialogUsageManager::processRequest: SipReq: INVITE
> spzero at 192.168.61.130:12005 tid=-3788-1-0 cseq=INVITE
> contact=valiant at 192.168.61.132:5060 / 1 from(wire)
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogSetId.cxx:31 | ********** Generated Local Tag
> ***********
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogSetId.cxx:31 | ********** Generated Local Tag
> ***********
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogSetId.cxx:31 | ********** Generated Local Tag
> ***********
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogSet.cxx:84 | ************* Created DialogSet(UAS)
> -- 1-3788 at 192.168.61.132-2dfed151*************
> UAS(INVITE): testAppDialogSet: created.
> UAS(INVITE): testAppDialogSet: UAS UserProfile requested for msg:
> SipReq: INVITE spzero at 192.168.61.130:12005 tid=-3788-1-0 cseq=INVITE
> contact=valiant at 192.168.61.132:5060 / 1 from(wire)
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogSetId.cxx:31 | ********** Generated Local Tag
> ***********
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogId.cxx:50 | DialogId::DialogId:
> 1-3788 at 192.168.61.132-9194ad38-1
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogSet.cxx:736 | mState == 3 Creating a new Dialog
> from
> msg:
>
> INVITE sip:spzero at 192.168.61.130:12005 SIP/2.0
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> Max-Forwards: 70
> Contact: <sip:valiant at 192.168.61.132:5060>
> To: "spzero"<sip:spzero at 192.168.61.130:12005>
> From: "valiant"<sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 179
>
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <request>
> <name>Loo</name>
> <extended type="size">0</extended>
> <extended type="date">01.01.1900-01.01.2100</extended>
> </request>
>
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:87 | UAS dialog ID creation, DS:
> 1-3788 at 192.168.61.132-2dfed151
> DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogId.cxx:63 | DialogId::DialogId:
> 1-3788 at 192.168.61.132-2dfed151-1
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:129 | ************** Created Dialog as UAS
> **************
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:130 | mRemoteNameAddr:
> "valiant"<sip:valiant at 192.168.61.132:5060>;tag=1
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:131 | mLocalNameAddr:
> "spzero"<sip:spzero at 192.168.61.130:12005>;tag=2dfed151
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:132 | mLocalContact:
> <sip:spzero at 192.168.61.130:12005>
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:133 | mRemoteTarget:
> <sip:valiant at 192.168.61.132:5060>
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:227 | Dialog::Dialog
> 1-3788 at 192.168.61.132-2dfed151-1
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogSet.cxx:774 | ### Calling CreateAppDialog ###:
>
> INVITE sip:spzero at 192.168.61.130:12005 SIP/2.0
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> Max-Forwards: 70
> Contact: <sip:valiant at 192.168.61.132:5060>
> To: "spzero"<sip:spzero at 192.168.61.130:12005>
> From: "valiant"<sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 179
>
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <request>
> <name>Loo</name>
> <extended type="size">0</extended>
> <extended type="date">01.01.1900-01.01.2100</extended>
> </request>
>
> UAS(INVITE): testAppDialog: created.
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:313 | Dialog::dispatch: SipReq: INVITE
> spzero at 192.168.61.130:12005 tid=-3788-1-0 cseq=INVITE
> contact=valiant at 192.168.61.132:5060 / 1 from(wire)
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:345 | Dialog::dispatch -- Created new server
> invite sessionSipReq: INVITE spzero at 192.168.61.130:12005
> tid=-3788-1-0
> cseq=INVITE contact=valiant at 192.168.61.132:5060 / 1 from(wire)
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | InviteSession.cxx:73 | ^^^ InviteSession::InviteSession
> 0x8378168
> DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> SipMessage.cxx:963 | SipMessage::getContents: text/xml
> INFO | 20070504-175401.523 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> SipMessage.cxx:972 | SipMessage::getContents: got content type
> (text/xml) that is not known, returning as opaque
> application/octet-stream
> INFO | 20070504-175401.524 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | InviteSession.cxx:2067 | Transition UAS_Start ->
> UAS_NoOffer
> UAS: ServerInviteSession-onNewSession -
> SipReq: INVITE spzero at 192.168.61.130:12005 tid=-3788-1-0 cseq=INVITE
> contact=valiant at 192.168.61.132:5060 / 1 from(wire)
> searching for files containing: Loo
> INFO | 20070504-175401.524 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | ServerInviteSession.cxx:384 | UAS_NoOffer: reject(606)
> DEBUG | 20070504-175401.524 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> Helper.cxx:372 | Helper::makeResponse(SipReq: INVITE
> spzero at 192.168.61.130:12005 tid=-3788-1-0 cseq=INVITE
> contact=valiant at 192.168.61.132:5060 / 1 from(wire) code=606 reason=
> DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:982 | Dialog::makeResponse:
>
> SIP/2.0 606 Not Acceptable
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=2dfed151
> From: "valiant"<sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Length: 0
>
>
> UAS: InviteSession-onReadyToSend -
> DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogUsageManager.cxx:800 | SEND:
>
> SIP/2.0 606 Not Acceptable
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=2dfed151
> From: "valiant"<sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Type: text/xml
> Content-Length: 50
>
> <?xml version="1.0" ?><reply status="Not found" />
> DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP | 3850 |
> 3080010112 |
> SipStack.cxx:290 | SEND: SipResp: 606 tid=-3788-1-0 cseq=INVITE / 1
> from(tu)
> INFO | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | InviteSession.cxx:2067 | Transition UAS_NoOffer ->
> InviteSession::Terminated
> UAS: InviteSession-onTerminated -
> DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | InviteSession.cxx:79 | ^^^ InviteSession::~InviteSession
> 0x8378168
> DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | Dialog.cxx:232 | Dialog::~Dialog()
> UAS(INVITE): testAppDialog: destroyed.
> DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogUsageManager.cxx:1986 | Got merged request removal
> request
> DEBUG | 20070504-175401.526 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TimerQueue.cxx:105 | Adding application timer:
> DEBUG | 20070504-175401.526 | kenobi | nsp | RESIP:DUM | 3850 |
> 3080010112 | DialogSet.cxx:122 | ********** DialogSet::~DialogSet:
> 1-3788 at 192.168.61.132-2dfed151*************
> UAS(INVITE): testAppDialogSet: destroyed.
> DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TimerQueue.cxx:85 | Adding timer: Timer H tid=-3788-1-0
> ms=64000
> DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TimerQueue.cxx:85 | Adding timer: Timer G tid=-3788-1-0
> ms=1000
> DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | InternalTransport.cxx:86 | Creating fd=8 V4/UDP
> DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | TransportSelector.cxx:521 | Looked up source for
> destination: [ V4 192.168.61.132:5060 UDP target domain=unspecified
> received on: Transport: [ V4 0.0.0.0:12005 UDP target
> domain=unspecified
> connectionId=0 ] connectionId=0 ] -> [ V4 192.168.61.130:0 UDP target
> domain=unspecified received on: Transport: [ V4 0.0.0.0:12005 UDP
> target
> domain=unspecified connectionId=0 ] connectionId=0 ]
> sent-by=192.168.61.132 sent-port=5060
> DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | TransportSelector.cxx:879 | Transmitting to [ V4
> 192.168.61.132:5060 UDP target domain=unspecified received on:
> Transport: [ V4 0.0.0.0:12005 UDP target domain=unspecified
> connectionId=0 ] connectionId=0 ] tlsDomain= via [ V4
> 192.168.61.130:12005 UDP target domain=unspecified received on:
> Transport: [ V4 0.0.0.0:12005 UDP target domain=unspecified
> connectionId=0 ] connectionId=0 ]
>
> SIP/2.0 606 Not Acceptable
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=2dfed151
> From: "valiant"<sip:valiant at 192.168.61.132:5060>;tag=1
> Call-ID: 1-3788 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Type: text/xml
> Content-Length: 50
>
> <?xml version="1.0" ?><reply status="Not found" />
> DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | Transport.cxx:213 | Adding message to tx buffer to: [ V4
> 192.168.61.132:5060 UDP target domain=unspecified received on:
> Transport: [ V4 0.0.0.0:12005 UDP target domain=unspecified
> connectionId=0 ] connectionId=0 ]
> DEBUG | 20070504-175401.684 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | Transport.cxx:287 | incoming from: [ V4
> 192.168.61.132:5060
> UDP target domain=unspecified received on: Transport: [ V4
> 0.0.0.0:12005
> UDP target domain=unspecified connectionId=0 ] connectionId=0 ]
> DEBUG | 20070504-175401.685 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TimerQueue.cxx:85 | Adding timer: Timer I tid=-3788-1-0
> ms=10000
> DEBUG | 20070504-175402.478 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
> 3080010112 | Transport.cxx:287 | incoming from: [ V4
> 192.168.61.132:5060
> UDP target domain=unspecified received on: Transport: [ V4
> 0.0.0.0:12005
> UDP target domain=unspecified connectionId=0 ] connectionId=0 ]
> DEBUG | 20070504-175402.479 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TransactionUser.cxx:66 | Checking if SipReq: INVITE
> spzero at 192.168.61.130:12005 tid=-3788-2-0 cseq=INVITE
> contact=korene at 192.168.61.132:5060 / 1 from(wire) is for me
> DEBUG | 20070504-175402.479 | kenobi | nsp | RESIP:TRANSACTION |
> 3850 |
> 3080010112 | TransactionUser.cxx:71 | Checking rule...DEBUG |
> 20070504-175402.479 | kenobi | nsp | RESIP:TRANSACTION | 3850 |
> 3080010112 | MessageFilterRule.cxx:42 | Matching rule for:
>
> INVITE sip:spzero at 192.168.61.130:12005 SIP/2.0
> ==============================================
>
>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 2423 bytes
Desc: not available
URL: <http://list.resiprocate.org/pipermail/resiprocate-devel/attachments/20070504/d6a01494/attachment.bin>
More information about the resiprocate-devel
mailing list