[reSIProcate] Performance of a P2P-over-SIP program using resiprocate
Byron Campen
bcampen at estacado.net
Mon May 7 13:44:34 CDT 2007
Ok, I've looked these logs over again, and I am not seeing the log
statement that should be happening when the retransmission is sent.
Try uncommenting resip/stack/UdpTransport.cxx; this will get the
stack to log the stuff it is about to push onto the wire. If we don't
see two identical copies of the 606 from that logging statement,
something funny is going on elsewhere.
Best regards,
Byron Campen
> So here it is...
>
> I did not have logs for that trace so I made a new one.
>
> Here is the sequence of packets:
> (The INVITEs 542 and 545 are only for reference for the DEBUG logs)
>
>
> ======
> No. Time Source Destination
> Protocol Info
> 429 213.330240 192.168.61.132 192.168.61.130
> SIP/XML Request: INVITE
> sip:spzero at 192.168.61.130:12005
>
> Frame 429 (623 bytes on wire, 623 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-3006-107-0
> Max-Forwards: 70
> Contact: <sip:olathe at 192.168.61.132:5060>
> To: spzero <sip:spzero at 192.168.61.130:12005>
> From: olathe <sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 180
> Message body
>
> No. Time Source Destination
> Protocol Info
> 542 216.556699 192.168.61.132 192.168.61.130
> SIP/XML Request: INVITE
> sip:spzero at 192.168.61.130:12005
>
> No. Time Source Destination
> Protocol Info
> 543 216.600727 192.168.61.130 192.168.61.132
> SIP/XML Status: 606 Not
> Acceptable
>
> Frame 543 (396 bytes on wire, 396 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-3006-107-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=f3c2db46
> From: "olathe"<sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Type: text/xml
> Content-Length: 50
> Message body
>
> No. Time Source Destination
> Protocol Info
> 544 216.600936 192.168.61.132 192.168.61.130 SIP
> Request: ACK
> sip:spzero at 192.168.61.130:12005
>
> Frame 544 (333 bytes on wire, 333 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-3006-107-0
> To: spzero <sip:spzero at 192.168.61.130:12005>;tag=f3c2db46
> From: olathe <sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 ACK
> Content-Length: 0
>
> No. Time Source Destination
> Protocol Info
> 545 216.640772 192.168.61.132 192.168.61.130
> SIP/XML Request: INVITE
> sip:spzero at 192.168.61.130:12005
>
> No. Time Source Destination
> Protocol Info
> 578 217.680430 192.168.61.130 192.168.61.132
> SIP/XML Status: 606 Not
> Acceptable
>
> Frame 578 (396 bytes on wire, 396 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: True]
> [Suspected resend of frame: 543]
> Message Header
> Via: SIP/2.0/UDP
> 192.168.61.132:5060;branch=z9hG4bK-3006-107-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=f3c2db46
> From: "olathe"<sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Type: text/xml
> Content-Length: 50
> Message body
>
> ======
>
>
> And here are the DEBUG logs:
> (This is the best I could manage to filter)
> First the INVITE and 606 to the tx queue.
> Then what I found to be between the two "reference" INVITE's.
> (So that the 606 and the ACK should be there)
> Last is a list of what should be many 606, one of them is the
> retransmission but I cannot
> tell which.
>
>
> =========
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000009.006 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TransactionUser.cxx:66 | Checking
> if SipReq: INVITE spzero at 192.168.61.130:12005 tid=-3006-107-0
> cseq=INVITE
> contact=olathe at 192.168.61.132:5060 / 1
> from(wire) is for me
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TransactionUser.cxx:71 | Checking
> rule...DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:TRANSACTION
> | 3071 | 3080104320
> | 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-3006-107-0
> Max-Forwards: 70
> Contact: <sip:olathe at 192.168.61.132:5060>
> To: spzero <sip:spzero at 192.168.61.130:12005>
> From: olathe <sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 180
>
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <request>
> <name>Meno</name>
> <extended type="size">0</extended>
> <extended type="date">01.01.1900-01.01.2100</extended>
> </request>
>
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TransactionUser.cxx:74 | Match!
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP | 3071 | 3080104320
> | Helper.cxx:372 |
> Helper::makeResponse(SipReq:
> INVITE spzero at 192.168.61.130:12005 tid=-3006-107-0 cseq=INVITE
> contact=olathe at 192.168.61.132:5060 / 1 from(wire)
> code=100 reason=
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer Trying tid=-3006-107-0 ms=80
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> 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-3006-107-0
> Max-Forwards: 70
> Contact: <sip:olathe at 192.168.61.132:5060>
> To: spzero <sip:spzero at 192.168.61.130:12005>
> From: olathe <sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 180
>
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <request>
> <name>Meno</name>
> <extended type="size">0</extended>
> <extended type="date">01.01.1900-01.01.2100</extended>
> </request>
>
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000009.006 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-96-0 ms=2000
> INFO | 20070505-000009.006 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogUsageManager.cxx:1227 | Got: SipReq:
> INVITE spzero at 192.168.61.130:12005 tid=-3006-107-0 cseq=INVITE
> contact=olathe at 192.168.61.132:5060 / 1 from(wire)
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogUsageManager.cxx:1494 |
> DialogUsageManager::processRequest: SipReq: INVITE
> spzero at 192.168.61.130:12005
> tid=-3006-107-0 cseq=INVITE
> contact=olathe at 192.168.61.132:5060 / 1 from(wire)
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogSetId.cxx:31 | ********** Generated
> Local Tag ***********
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogSetId.cxx:31 | ********** Generated
> Local Tag ***********
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogSetId.cxx:31 | ********** Generated
> Local Tag ***********
> DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogSet.cxx:84 | ************* Created
> DialogSet(UAS) -- 107-3006 at 192.168.61.132-f3c2db46*************
> UAS(INVITE): testAppDialogSet: created.
> UAS(INVITE): testAppDialogSet: UAS UserProfile requested for msg:
> SipReq: INVITE spzero at 192.168.61.130:12005 tid=-3006-107-0
> cseq=INVITE
> contact=olathe at 192.168.61.132:5060 / 1
> from(wire)
> DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogSetId.cxx:31 | ********** Generated
> Local Tag ***********
> DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogId.cxx:50 | DialogId::DialogId:
> 107-3006 at 192.168.61.132-f03bf91a-107
> DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> 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-3006-107-0
> Max-Forwards: 70
> Contact: <sip:olathe at 192.168.61.132:5060>
> To: "spzero"<sip:spzero at 192.168.61.130:12005>
> From: "olathe"<sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 180
>
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <request>
> <name>Meno</name>
> <extended type="size">0</extended>
> <extended type="date">01.01.1900-01.01.2100</extended>
> </request>
>
> DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:87 | UAS dialog ID creation,
> DS: 107-3006 at 192.168.61.132-f3c2db46
> DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogId.cxx:63 | DialogId::DialogId:
> 107-3006 at 192.168.61.132-f3c2db46-107
> DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:129 | ************** Created
> Dialog as UAS **************
> DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:130 | mRemoteNameAddr:
> "olathe"<sip:olathe at 192.168.61.132:5060>;tag=107
> DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:131 | mLocalNameAddr:
> "spzero"<sip:spzero at 192.168.61.130:12005>;tag=f3c2db46
> DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:132 | mLocalContact:
> <sip:spzero at 192.168.61.130:12005>
> DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:133 | mRemoteTarget:
> <sip:olathe at 192.168.61.132:5060>
> DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:227 | Dialog::Dialog
> 107-3006 at 192.168.61.132-f3c2db46-107
> DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> 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-3006-107-0
> Max-Forwards: 70
> Contact: <sip:olathe at 192.168.61.132:5060>
> To: "spzero"<sip:spzero at 192.168.61.130:12005>
> From: "olathe"<sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Allow: INVITE, ACK, MESSAGE
> Content-Type: text/xml
> Content-Length: 180
>
> <?xml version="1.0" encoding="ISO-8859-1" ?>
> <request>
> <name>Meno</name>
> <extended type="size">0</extended>
> <extended type="date">01.01.1900-01.01.2100</extended>
> </request>
>
> UAS(INVITE): testAppDialog: created.
> DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:313 | Dialog::dispatch:
> SipReq: INVITE spzero at 192.168.61.130:12005 tid=-3006-107-0
> cseq=INVITE
> contact=olathe at 192.168.61.132:5060 / 1
> from(wire)
> DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:345 | Dialog::dispatch --
> Created new server invite sessionSipReq: INVITE
> spzero at 192.168.61.130:12005
> tid=-3006-107-0 cseq=INVITE
> contact=olathe at 192.168.61.132:5060 / 1 from(wire)
> DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> InviteSession.cxx:73 | ^^^
> InviteSession::InviteSession 0x83f9be8
> DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP | 3071 |
> 3080104320 |
> SipMessage.cxx:963 | SipMessage::getContents:
> text/xml
> INFO | 20070505-000009.013 | kenobi | nsp | RESIP | 3071 |
> 3080104320 |
> SipMessage.cxx:972 | SipMessage::getContents:
> got content type (text/xml) that is not known, returning as opaque
> application/octet-stream
> INFO | 20070505-000009.015 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> InviteSession.cxx:2067 | Transition
> UAS_Start -> UAS_NoOffer
> UAS: ServerInviteSession-onNewSession -
> SipReq: INVITE spzero at 192.168.61.130:12005 tid=-3006-107-0
> cseq=INVITE
> contact=olathe at 192.168.61.132:5060 / 1
> from(wire)
> searching for files containing: Meno
> INFO | 20070505-000009.025 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> ServerInviteSession.cxx:384 | UAS_NoOffer:
> reject(606)
> DEBUG | 20070505-000009.025 | kenobi | nsp | RESIP | 3071 | 3080104320
> | Helper.cxx:372 |
> Helper::makeResponse(SipReq:
> INVITE spzero at 192.168.61.130:12005 tid=-3006-107-0 cseq=INVITE
> contact=olathe at 192.168.61.132:5060 / 1 from(wire)
> code=606 reason=
> DEBUG | 20070505-000009.025 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:982 | Dialog::makeResponse:
>
> SIP/2.0 606 Not Acceptable
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3006-107-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=f3c2db46
> From: "olathe"<sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Length: 0
>
>
> UAS: InviteSession-onReadyToSend -
> DEBUG | 20070505-000009.025 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogUsageManager.cxx:800 | SEND:
>
> SIP/2.0 606 Not Acceptable
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3006-107-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=f3c2db46
> From: "olathe"<sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Type: text/xml
> Content-Length: 50
>
> <?xml version="1.0" ?><reply status="Not found" />
> DEBUG | 20070505-000009.025 | kenobi | nsp | RESIP | 3071 | 3080104320
> | SipStack.cxx:290
> | SEND: SipResp: 606
> tid=-3006-107-0 cseq=INVITE / 1 from(tu)
> INFO | 20070505-000009.025 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> InviteSession.cxx:2067 | Transition
> UAS_NoOffer -> InviteSession::Terminated
> UAS: InviteSession-onTerminated -
> DEBUG | 20070505-000009.025 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> InviteSession.cxx:79 | ^^^
> InviteSession::~InviteSession 0x83f9be8
> DEBUG | 20070505-000009.025 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:232 | Dialog::~Dialog()
> UAS(INVITE): testAppDialog: destroyed.
> DEBUG | 20070505-000009.025 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogUsageManager.cxx:1986 | Got merged
> request removal request
> DEBUG | 20070505-000009.025 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:105 | Adding
> application timer:
> DEBUG | 20070505-000009.025 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogSet.cxx:122 | **********
> DialogSet::~DialogSet: 107-3006 at 192.168.61.132-f3c2db46*************
> UAS(INVITE): testAppDialogSet: destroyed.
> DEBUG | 20070505-000009.078 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000009.078 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer H tid=-3006-107-0 ms=64000
> DEBUG | 20070505-000009.078 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-107-0 ms=1000
> DEBUG | 20070505-000009.078 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000009.078 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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-3006-107-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=f3c2db46
> From: "olathe"<sip:olathe at 192.168.61.132:5060>;tag=107
> Call-ID: 107-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Type: text/xml
> Content-Length: 50
>
> <?xml version="1.0" ?><reply status="Not found" />
> DEBUG | 20070505-000009.078 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000009.078 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer I tid=-3006-80-0 ms=10000
> DEBUG | 20070505-000009.129 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000009.129 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TransactionUser.cxx:66 | Checking
> if SipReq: INVITE spzero at 192.168.61.130:12005 tid=-3006-108-0
> cseq=INVITE
> contact=malise at 192.168.61.132:5060 / 1
> from(wire) is for me
> DEBUG | 20070505-000009.129 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TransactionUser.cxx:71 | Checking
> rule...DEBUG | 20070505-000009.129 | kenobi | nsp | RESIP:TRANSACTION
> | 3071 | 3080104320
> | MessageFilterRule.cxx:42 |
> Matching rule for:
>
> INVITE sip:spzero at 192.168.61.130:12005 SIP/2.0
>
> =====
>
> 20070505-000012.574 | kenobi | nsp | RESIP | 3071 | 3080104320 |
> SipMessage.cxx:972 |
> SipMessage::getContents: got
> content type (text/xml) that is not known, returning as opaque
> application/octet-stream
> INFO | 20070505-000012.574 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> InviteSession.cxx:2067 | Transition
> UAS_Start -> UAS_NoOffer
> UAS: ServerInviteSession-onNewSession -
> SipReq: INVITE spzero at 192.168.61.130:12005 tid=-3006-138-0
> cseq=INVITE
> contact=yorick at 192.168.61.132:5060 / 1
> from(wire)
> searching for files containing: Behin
> INFO | 20070505-000012.587 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> ServerInviteSession.cxx:384 | UAS_NoOffer:
> reject(606)
> DEBUG | 20070505-000012.587 | kenobi | nsp | RESIP | 3071 | 3080104320
> | Helper.cxx:372 |
> Helper::makeResponse(SipReq:
> INVITE spzero at 192.168.61.130:12005 tid=-3006-138-0 cseq=INVITE
> contact=yorick at 192.168.61.132:5060 / 1 from(wire)
> code=606 reason=
> DEBUG | 20070505-000012.587 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:982 | Dialog::makeResponse:
>
> SIP/2.0 606 Not Acceptable
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3006-138-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=16547413
> From: "yorick"<sip:yorick at 192.168.61.132:5060>;tag=138
> Call-ID: 138-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Length: 0
>
>
> UAS: InviteSession-onReadyToSend -
> DEBUG | 20070505-000012.587 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogUsageManager.cxx:800 | SEND:
>
> SIP/2.0 606 Not Acceptable
> Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3006-138-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=16547413
> From: "yorick"<sip:yorick at 192.168.61.132:5060>;tag=138
> Call-ID: 138-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Type: text/xml
> Content-Length: 50
>
> <?xml version="1.0" ?><reply status="Not found" />
> DEBUG | 20070505-000012.604 | kenobi | nsp | RESIP | 3071 | 3080104320
> | SipStack.cxx:290
> | SEND: SipResp: 606
> tid=-3006-138-0 cseq=INVITE / 1 from(tu)
> INFO | 20070505-000012.604 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> InviteSession.cxx:2067 | Transition
> UAS_NoOffer -> InviteSession::Terminated
> UAS: InviteSession-onTerminated -
> DEBUG | 20070505-000012.605 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> InviteSession.cxx:79 | ^^^
> InviteSession::~InviteSession 0x83f9be8
> DEBUG | 20070505-000012.605 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> Dialog.cxx:232 | Dialog::~Dialog()
> UAS(INVITE): testAppDialog: destroyed.
> DEBUG | 20070505-000012.605 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogUsageManager.cxx:1986 | Got merged
> request removal request
> DEBUG | 20070505-000012.605 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:105 | Adding
> application timer:
> DEBUG | 20070505-000012.605 | kenobi | nsp | RESIP:DUM | 3071 |
> 3080104320 |
> DialogSet.cxx:122 | **********
> DialogSet::~DialogSet: 138-3006 at 192.168.61.132-16547413*************
> UAS(INVITE): testAppDialogSet: destroyed.
> DEBUG | 20070505-000012.656 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000012.657 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer H tid=-3006-138-0 ms=64000
> DEBUG | 20070505-000012.658 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-138-0 ms=1000
> DEBUG | 20070505-000012.658 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000012.659 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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-3006-138-0
> To: "spzero"<sip:spzero at 192.168.61.130:12005>;tag=16547413
> From: "yorick"<sip:yorick at 192.168.61.132:5060>;tag=138
> Call-ID: 138-3006 at 192.168.61.132
> CSeq: 1 INVITE
> Content-Type: text/xml
> Content-Length: 50
>
> <?xml version="1.0" ?><reply status="Not found" />
> DEBUG | 20070505-000012.659 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000012.659 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer I tid=-3006-107-0 ms=10000
> DEBUG | 20070505-000012.659 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000012.659 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-111-0 ms=4000
> DEBUG | 20070505-000012.659 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000012.659 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-129-0 ms=2000
>
> DEBUG | 20070505-000012.713 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000012.713 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TransactionUser.cxx:66 | Checking
> if SipReq: INVITE spzero at 192.168.61.130:12005 tid=-3006-139-0
> cseq=INVITE
> contact=huyen at 192.168.61.132:5060 / 1
> from(wire) is for meDEBUG | 20070505-000012.713 | kenobi | nsp |
> RESIP:TRANSACTION | 3071
> | 3080104320 |
> TransactionUser.cxx:71 | Checking rule...DEBUG | 20070505-000012.713 |
> kenobi | nsp |
> RESIP:TRANSACTION | 3071 |
> 3080104320 | MessageFilterRule.cxx:42 | Matching rule for:
>
> INVITE sip:spzero at 192.168.61.130:12005 SIP/2.0
> =======
>
> DEBUG | 20070505-000013.268 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer I tid=-3006-111-0 ms=10000
> DEBUG | 20070505-000013.268 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.268 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-116-0 ms=4000
> DEBUG | 20070505-000013.268 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.268 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-135-0 ms=2000
> DEBUG | 20070505-000013.373 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.373 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-117-0 ms=4000
> DEBUG | 20070505-000013.423 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.423 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-136-0 ms=2000
> DEBUG | 20070505-000013.476 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.476 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-118-0 ms=4000
> DEBUG | 20070505-000013.581 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.581 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-137-0 ms=2000
> DEBUG | 20070505-000013.581 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.581 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-119-0 ms=4000
> DEBUG | 20070505-000013.631 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.631 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-120-0 ms=4000
> DEBUG | 20070505-000013.683 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.683 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-138-0 ms=2000
> DEBUG | 20070505-000013.789 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.789 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-121-0 ms=4000
> DEBUG | 20070505-000013.839 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.839 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-122-0 ms=4000
> DEBUG | 20070505-000013.839 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.839 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-139-0 ms=2000
> DEBUG | 20070505-000013.948 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.948 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-123-0 ms=4000
> DEBUG | 20070505-000013.948 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000013.948 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-140-0 ms=2000
> DEBUG | 20070505-000014.056 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.056 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-141-0 ms=2000
> DEBUG | 20070505-000014.107 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.108 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-124-0 ms=4000
> DEBUG | 20070505-000014.160 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.160 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-142-0 ms=2000
> DEBUG | 20070505-000014.212 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.212 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-125-0 ms=4000
> DEBUG | 20070505-000014.317 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.317 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-143-0 ms=2000
> DEBUG | 20070505-000014.421 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.421 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-126-0 ms=4000
> DEBUG | 20070505-000014.421 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.421 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-127-0 ms=4000
> DEBUG | 20070505-000014.577 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.577 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-128-0 ms=4000
> DEBUG | 20070505-000014.681 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.681 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-129-0 ms=4000
> DEBUG | 20070505-000014.837 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.837 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-130-0 ms=4000
> DEBUG | 20070505-000014.941 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000014.941 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-131-0 ms=4000
> DEBUG | 20070505-000015.045 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000015.045 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-132-0 ms=4000
> DEBUG | 20070505-000015.046 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000015.046 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-133-0 ms=4000
> DEBUG | 20070505-000015.201 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000015.202 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-134-0 ms=4000
> DEBUG | 20070505-000015.306 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000015.306 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-135-0 ms=4000
> DEBUG | 20070505-000015.469 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000015.469 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-136-0 ms=4000
> DEBUG | 20070505-000015.626 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000015.626 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-137-0 ms=4000
> DEBUG | 20070505-000015.729 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000015.730 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-138-0 ms=4000
> DEBUG | 20070505-000015.886 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000015.886 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-139-0 ms=4000
> DEBUG | 20070505-000015.990 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000015.990 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-140-0 ms=4000
> DEBUG | 20070505-000016.094 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000016.094 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-141-0 ms=4000
> DEBUG | 20070505-000016.198 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000016.198 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-142-0 ms=4000
> DEBUG | 20070505-000016.354 | kenobi | nsp | RESIP:TRANSPORT | 3071 |
> 3080104320 |
> 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 | 20070505-000016.354 | kenobi | nsp | RESIP:TRANSACTION | 3071
> | 3080104320 |
> TimerQueue.cxx:85 | Adding timer:
> Timer G tid=-3006-143-0 ms=4000
> ==========
>
> And just to not leave anything out here is the raw ACK.
>
> ==========
>
> 0000 00 0c 29 f2 ad ed 00 0c 29 5f e3 82 08 00 45 00 ..).....)
> _....E.
> 0010 01 3f 00 00 40 00 40 11 3d 57 c0 a8 3d 84 c0
> a8 .?.. at .@.=W..=...
> 0020 3d 82 13 c4 2e e5 01 2b 04 b8 41 43 4b 20 73 69 =......
> +..ACK si
> 0030 70 3a 73 70 7a 65 72 6f 40 31 39 32 2e 31 36 38
> p:spzero at 192.168
> 0040 2e 36 31 2e 31 33 30 3a 31 32 30 30 35 20 53 49 .
> 61.130:12005 SI
> 0050 50 2f 32 2e 30 0d 0a 56 69 61 3a 20 53 49 50 2f P/2.0..Via:
> SIP/
> 0060 32 2e 30 2f 55 44 50 20 31 39 32 2e 31 36 38 2e 2.0/UDP
> 192.168.
> 0070 36 31 2e 31 33 32 3a 35 30 36 30 3b 62 72 61 6e
> 61.132:5060;bran
> 0080 63 68 3d 7a 39 68 47 34 62 4b 2d 33 30 30 36 2d
> ch=z9hG4bK-3006-
> 0090 31 30 37 2d 30 0d 0a 54 6f 3a 20 73 70 7a 65 72 107-0..To:
> spzer
> 00a0 6f 20 3c 73 69 70 3a 73 70 7a 65 72 6f 40 31 39 o
> <sip:spzero at 19
> 00b0 32 2e 31 36 38 2e 36 31 2e 31 33 30 3a 31 32 30
> 2.168.61.130:120
> 00c0 30 35 3e 3b 74 61 67 3d 66 33 63 32 64 62 34 36
> 05>;tag=f3c2db46
> 00d0 0d 0a 46 72 6f 6d 3a 20 6f 6c 61 74 68 65 20 3c ..From:
> olathe <
> 00e0 73 69 70 3a 6f 6c 61 74 68 65 40 31 39 32 2e 31
> sip:olathe at 192.1
> 00f0 36 38 2e 36 31 2e 31 33 32 3a 35 30 36 30 3e 3b
> 68.61.132:5060>;
> 0100 74 61 67 3d 31 30 37 0d 0a 43 61 6c 6c 2d 49 44
> tag=107..Call-ID
> 0110 3a 20 31 30 37 2d 33 30 30 36 40 31 39 32 2e 31 :
> 107-3006 at 192.1
> 0120 36 38 2e 36 31 2e 31 33 32 0d 0a 43 53 65 71 3a
> 68.61.132..CSeq:
> 0130 20 31 20 41 43 4b 0d 0a 43 6f 6e 74 65 6e 74 2d 1
> ACK..Content-
> 0140 4c 65 6e 67 74 68 3a 20 30 0d 0a 0d 0a Length: 0....
> =================
>
>
> _______________________________________________
> resiprocate-devel mailing list
> resiprocate-devel at list.resiprocate.org
> https://list.resiprocate.org/mailman/listinfo/resiprocate-devel
-------------- 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/20070507/059454d1/attachment.bin>
More information about the resiprocate-devel
mailing list