Re: [reSIProcate] Performance of a P2P-over-SIP program using resiprocate
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@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:5060>
To: spzero <sip:spzero@xxxxxxxxxxxxxx:12005>
From: olathe <sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005>;tag=f3c2db46
From: "olathe"<sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005>;tag=f3c2db46
From: olathe <sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005>;tag=f3c2db46
From: "olathe"<sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx:12005 tid=-3006-107-0 cseq=INVITE
contact=olathe@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:5060>
To: spzero <sip:spzero@xxxxxxxxxxxxxx:12005>
From: olathe <sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx:12005 tid=-3006-107-0 cseq=INVITE
contact=olathe@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:5060>
To: spzero <sip:spzero@xxxxxxxxxxxxxx:12005>
From: olathe <sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx:12005 tid=-3006-107-0 cseq=INVITE
contact=olathe@xxxxxxxxxxxxxx:5060 / 1 from(wire)
DEBUG | 20070505-000009.006 | kenobi | nsp | RESIP:DUM | 3071 | 3080104320 |
DialogUsageManager.cxx:1494 |
DialogUsageManager::processRequest: SipReq: INVITE
spzero@xxxxxxxxxxxxxx:12005
tid=-3006-107-0 cseq=INVITE
contact=olathe@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxxxxxxxxxxx*************
UAS(INVITE): testAppDialogSet: created.
UAS(INVITE): testAppDialogSet: UAS UserProfile requested for msg:
SipReq: INVITE spzero@xxxxxxxxxxxxxx:12005 tid=-3006-107-0 cseq=INVITE
contact=olathe@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 | 3080104320 |
DialogSet.cxx:736 | mState == 3 Creating
a new Dialog from msg:
INVITE sip:spzero@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:5060>
To: "spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>
From: "olathe"<sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 | 3080104320 |
DialogId.cxx:63 | DialogId::DialogId:
107-3006@xxxxxxxxxxxxxxxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx:5060>;tag=107
DEBUG | 20070505-000009.009 | kenobi | nsp | RESIP:DUM | 3071 | 3080104320 |
Dialog.cxx:131 | mLocalNameAddr:
"spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>;tag=f3c2db46
DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 | 3080104320 |
Dialog.cxx:132 | mLocalContact:
<sip:spzero@xxxxxxxxxxxxxx:12005>
DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 | 3080104320 |
Dialog.cxx:133 | mRemoteTarget:
<sip:olathe@xxxxxxxxxxxxxx:5060>
DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 | 3080104320 |
Dialog.cxx:227 | Dialog::Dialog
107-3006@xxxxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20070505-000009.010 | kenobi | nsp | RESIP:DUM | 3071 | 3080104320 |
DialogSet.cxx:774 | ### Calling
CreateAppDialog ###:
INVITE sip:spzero@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:5060>
To: "spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>
From: "olathe"<sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx:12005 tid=-3006-107-0 cseq=INVITE
contact=olathe@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005
tid=-3006-107-0 cseq=INVITE
contact=olathe@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005 tid=-3006-107-0 cseq=INVITE
contact=olathe@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005 tid=-3006-107-0 cseq=INVITE
contact=olathe@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005>;tag=f3c2db46
From: "olathe"<sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx:12005>;tag=f3c2db46
From: "olathe"<sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxxxxxxxxxxx*************
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@xxxxxxxxxxxxxx:12005>;tag=f3c2db46
From: "olathe"<sip:olathe@xxxxxxxxxxxxxx:5060>;tag=107
Call-ID: 107-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx:12005 tid=-3006-108-0 cseq=INVITE
contact=malise@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005 tid=-3006-138-0 cseq=INVITE
contact=yorick@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005 tid=-3006-138-0 cseq=INVITE
contact=yorick@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx:12005>;tag=16547413
From: "yorick"<sip:yorick@xxxxxxxxxxxxxx:5060>;tag=138
Call-ID: 138-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx:12005>;tag=16547413
From: "yorick"<sip:yorick@xxxxxxxxxxxxxx:5060>;tag=138
Call-ID: 138-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxxxxxxxxxxx*************
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@xxxxxxxxxxxxxx:12005>;tag=16547413
From: "yorick"<sip:yorick@xxxxxxxxxxxxxx:5060>;tag=138
Call-ID: 138-3006@xxxxxxxxxxxxxx
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@xxxxxxxxxxxxxx:12005 tid=-3006-139-0 cseq=INVITE
contact=huyen@xxxxxxxxxxxxxx: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@xxxxxxxxxxxxxx: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 .?..@.@.=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@xxxxxxx
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@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@xxxxx
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@xxxxx
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....
=================