[reSIProcate] Performance of a P2P-over-SIP program using resiprocate
Byron Campen
bcampen at estacado.net
Mon May 7 13:54:16 CDT 2007
Crud, I didn't give you the line number. So, uncomment resip/stack/
UdpTransport.cxx:81.
Best regards,
Byron Campen
> 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
>
> _______________________________________________
> 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/289c9c87/attachment.bin>
More information about the resiprocate-devel
mailing list