reSIProcate

Mailing Lists

Source Code

Google Search:



< Previous by Date Date Index Next by Date >
< Previous in Thread Thread Index Next in Thread >

Re: [reSIProcate] Performance of a P2P-over-SIP program using resiprocate

Looking at the resip logs, that ACK must have failed the preparse (the logs indicate that something was received, but it was dropped). Because there is nothing readily apparent in the trace, my guess is that sipp did something wrong with line-termination. You'll need to get a raw capture of that ACK that preserves the line-termination characteristics (my bet is that the line-terminator on the Via is messed up). Instead of using regexp replacement, I would use the [last_Via] directive. That will ensure the branch param is what it needs to be. 

Best regards,
Byron Campen


Hello!

Thank you for answering to my post.
1. Using 606 is historical from a previous project. I think it should not be too difficult to change this to a 4xx. 

2. Here is my scenario :
========================
<?xml version="1.0" encoding="ISO-8859-1" ?>
<scenario name="super-peer stress test UAC">
<!--  In client mode (sipp placing calls), the Call-ID MUST be -->
<!--  generated by sipp. To do so, use [call_id] keyword.      -->
<send start_rtd="1">
<![CDATA[
      INVITE sip:spzero@[remote_ip]:[remote_port] SIP/2.0
      Via: SIP/2.0/UDP [local_ip]:[local_port];branch=[branch]
      Max-Forwards: 70
      Contact: <sip:[field1]@[local_ip]:[local_port]>
      To: spzero <sip:spzero@[remote_ip]:[remote_port]>
From: [field1] <sip:[field1]@[local_ip]:[local_port]>;tag= [call_number] 
      Call-ID: [call_id]
      CSeq: [cseq] INVITE
      Allow: INVITE, ACK, MESSAGE
      Content-Type: text/xml
      Content-Length: [len]

      <?xml version="1.0" encoding="ISO-8859-1" ?>
      <request>
      <name>[field3]</name>
      <extended type="size">0</extended>
      <extended type="date">01.01.1900-01.01.2100</extended>
      </request>

  ]]>
</send>

<recv response="100" optional="true" />

<recv response="606" rtd="1">
 <action>
<ereg regexp="branch=.*" search_in="hdr" header="Via:" assign_to="1" /> 
 </action>
</recv>

<send>
<![CDATA[       ACK sip:spzero@[remote_ip]:[remote_port] SIP/2.0
      Via: SIP/2.0/UDP [local_ip]:[local_port];[$1]
To: spzero <sip:spzero@[remote_ip]:[remote_port]> [peer_tag_param] From: [field1] <sip:[field1]@[local_ip]:[local_port]>;tag= [call_number] 
      Call-ID: [call_id]
      CSeq: [cseq] ACK
      Content-Length: 0

  ]]>
</send>

</scenario>
3. Below I paste the first three packets of a wireshark trace, and then my debug level log. As you can see the branch parameter from the ACK matches that of the INVITE. 

===============
No. Time Source Destination Protocol Info 14 21.034909 192.168.61.132 192.168.61.130 SIP/ XML Request: INVITE sip:spzero@xxxxxxxxxxxxxx:12005 

Frame 14 (619 bytes on wire, 619 bytes captured)
Ethernet II, Src: Vmware_5f:e3:82 (00:0c:29:5f:e3:82), Dst: Vmware_f2:ad:ed (00:0c:29:f2:ad:ed) Internet Protocol, Src: 192.168.61.132 (192.168.61.132), Dst: 192.168.61.130 (192.168.61.130) 
User Datagram Protocol, Src Port: 5060 (5060), Dst Port: 12005 (12005)
Session Initiation Protocol
    Request-Line: INVITE sip:spzero@xxxxxxxxxxxxxx:12005 SIP/2.0
        Method: INVITE
        [Resent Packet: False]
    Message Header
        Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
        Max-Forwards: 70
        Contact: <sip:valiant@xxxxxxxxxxxxxx:5060>
        To: spzero <sip:spzero@xxxxxxxxxxxxxx:12005>
        From: valiant <sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
        Call-ID: 1-3788@xxxxxxxxxxxxxx
        CSeq: 1 INVITE
        Allow: INVITE, ACK, MESSAGE
        Content-Type: text/xml
        Content-Length: 179
    Message body
No. Time Source Destination Protocol Info 15 21.185323 192.168.61.130 192.168.61.132 SIP/ XML Status: 606 Not Acceptable 

Frame 15 (392 bytes on wire, 392 bytes captured)
Ethernet II, Src: Vmware_f2:ad:ed (00:0c:29:f2:ad:ed), Dst: Vmware_5f:e3:82 (00:0c:29:5f:e3:82) Internet Protocol, Src: 192.168.61.130 (192.168.61.130), Dst: 192.168.61.132 (192.168.61.132) 
User Datagram Protocol, Src Port: 12005 (12005), Dst Port: 5060 (5060)
Session Initiation Protocol
    Status-Line: SIP/2.0 606 Not Acceptable
        Status-Code: 606
        [Resent Packet: False]
    Message Header
        Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
        To: "spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>;tag=2dfed151
        From: "valiant"<sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
        Call-ID: 1-3788@xxxxxxxxxxxxxx
        CSeq: 1 INVITE
        Content-Type: text/xml
        Content-Length: 50
    Message body
No. Time Source Destination Protocol Info 16 21.199058 192.168.61.132 192.168.61.130 SIP Request: ACK sip:spzero@xxxxxxxxxxxxxx:12005 

Frame 16 (329 bytes on wire, 329 bytes captured)
Ethernet II, Src: Vmware_5f:e3:82 (00:0c:29:5f:e3:82), Dst: Vmware_f2:ad:ed (00:0c:29:f2:ad:ed) Internet Protocol, Src: 192.168.61.132 (192.168.61.132), Dst: 192.168.61.130 (192.168.61.130) 
User Datagram Protocol, Src Port: 5060 (5060), Dst Port: 12005 (12005)
Session Initiation Protocol
    Request-Line: ACK sip:spzero@xxxxxxxxxxxxxx:12005 SIP/2.0
        Method: ACK
        [Resent Packet: False]
    Message Header
        Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
        To: spzero <sip:spzero@xxxxxxxxxxxxxx:12005>;tag=2dfed151
        From: valiant <sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
        Call-ID: 1-3788@xxxxxxxxxxxxxx
        CSeq: 1 ACK
        Content-Length: 0
=================
DEBUG | 20070504-175353.889 | kenobi | nsp | RESIP | 3850 | 3080010112 | 
Security.cxx:893 | BaseSecurity::BaseSecurity
DEBUG | 20070504-175353.890 | kenobi | nsp | RESIP:DNS | 3850 |
3080010112 | dns/AresDns.cxx:102 | number of name servers found 1
DEBUG | 20070504-175353.890 | kenobi | nsp | RESIP:DNS | 3850 |
3080010112 | dns/AresDns.cxx:105 | name server 192.168.61.2
DEBUG | 20070504-175353.890 | kenobi | nsp | RESIP | 3850 | 3080010112 | 
Compression.cxx:40 | COMPRESSION SUPPORT NOT COMPILED IN
DEBUG | 20070504-175353.891 | kenobi | nsp | RESIP | 3850 | 3080010112 | Compression.cxx:43 | Compression configuration object created; algorithm 
= 0
DEBUG | 20070504-175353.891 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | TransportSelector.cxx:86 | No compression library available DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP | 3850 | 3080010112 | 
Security.cxx:893 | BaseSecurity::BaseSecurity
DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP:DNS | 3850 |
3080010112 | dns/AresDns.cxx:102 | number of name servers found 1
DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP:DNS | 3850 |
3080010112 | dns/AresDns.cxx:105 | name server 192.168.61.2
DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP | 3850 | 3080010112 | 
Compression.cxx:40 | COMPRESSION SUPPORT NOT COMPILED IN
DEBUG | 20070504-175353.892 | kenobi | nsp | RESIP | 3850 | 3080010112 | Compression.cxx:43 | Compression configuration object created; algorithm 
= 0
DEBUG | 20070504-175353.893 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | TransportSelector.cxx:86 | No compression library available 
INFO | 20070504-175353.893 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | UdpTransport.cxx:42 | Creating UDP transport host=
port=12005 ipv4=1
DEBUG | 20070504-175353.894 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | InternalTransport.cxx:86 | Creating fd=3 V4/UDP
DEBUG | 20070504-175353.894 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | InternalTransport.cxx:94 | Binding to 0.0.0.0
DEBUG | 20070504-175353.894 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | UdpTransport.cxx:59 | No compression library available:
Transport: [ V4 0.0.0.0:12005 UDP target domain=unspecified
connectionId=0 ]
DEBUG | 20070504-175353.894 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | TransportSelector.cxx:155 | Adding transport: [ V4
0.0.0.0:12005 UDP target domain=unspecified connectionId=0 ]
DEBUG | 20070504-175353.895 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | UserProfile.cxx:135 | Adding credential:
realm=192.168.61.130 user=spzero
INFO | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | UdpTransport.cxx:42 | Creating UDP transport host=
port=12010 ipv4=1
DEBUG | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | InternalTransport.cxx:86 | Creating fd=6 V4/UDP
DEBUG | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | InternalTransport.cxx:94 | Binding to 0.0.0.0
DEBUG | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | UdpTransport.cxx:59 | No compression library available:
Transport: [ V4 0.0.0.0:12010 UDP target domain=unspecified
connectionId=0 ]
DEBUG | 20070504-175353.902 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | TransportSelector.cxx:155 | Adding transport: [ V4
0.0.0.0:12010 UDP target domain=unspecified connectionId=0 ]
DEBUG | 20070504-175353.903 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | UserProfile.cxx:135 | Adding credential:
realm=192.168.61.130 user=spzero
DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | Transport.cxx:287 | incoming from: [ V4 192.168.61.132:5060 UDP target domain=unspecified received on: Transport: [ V4 0.0.0.0:12005 
UDP target domain=unspecified connectionId=0 ] connectionId=0 ]
DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TransactionUser.cxx:66 | Checking if SipReq:  INVITE
spzero@xxxxxxxxxxxxxx:12005 tid=-3788-1-0 cseq=INVITE
contact=valiant@xxxxxxxxxxxxxx:5060 / 1 from(wire) is for meDEBUG |
20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION | 3850 |
3080010112 | TransactionUser.cxx:71 | Checking rule...DEBUG |
20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION | 3850 |
3080010112 | MessageFilterRule.cxx:42 | Matching rule for:

INVITE sip:spzero@xxxxxxxxxxxxxx:12005 SIP/2.0
Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
Max-Forwards: 70
Contact: <sip:valiant@xxxxxxxxxxxxxx:5060>
To: spzero <sip:spzero@xxxxxxxxxxxxxx:12005>
From: valiant <sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
Call-ID: 1-3788@xxxxxxxxxxxxxx
CSeq: 1 INVITE
Allow: INVITE, ACK, MESSAGE
Content-Type: text/xml
Content-Length: 179

<?xml version="1.0" encoding="ISO-8859-1" ?>
<request>
<name>Loo</name>
<extended type="size">0</extended>
<extended type="date">01.01.1900-01.01.2100</extended>
</request>
DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TransactionUser.cxx:74 | Match!
DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP | 3850 | 3080010112 | 
Helper.cxx:372 | Helper::makeResponse(SipReq:  INVITE
spzero@xxxxxxxxxxxxxx:12005 tid=-3788-1-0 cseq=INVITE
contact=valiant@xxxxxxxxxxxxxx:5060 / 1 from(wire) code=100 reason=
DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TimerQueue.cxx:85 | Adding timer: Timer Trying
tid=-3788-1-0 ms=80
DEBUG | 20070504-175401.521 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TransactionState.cxx:1852 | Send to TU: TU:
DialogUsageManager size=0

INVITE sip:spzero@xxxxxxxxxxxxxx:12005 SIP/2.0
Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
Max-Forwards: 70
Contact: <sip:valiant@xxxxxxxxxxxxxx:5060>
To: spzero <sip:spzero@xxxxxxxxxxxxxx:12005>
From: valiant <sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
Call-ID: 1-3788@xxxxxxxxxxxxxx
CSeq: 1 INVITE
Allow: INVITE, ACK, MESSAGE
Content-Type: text/xml
Content-Length: 179

<?xml version="1.0" encoding="ISO-8859-1" ?>
<request>
<name>Loo</name>
<extended type="size">0</extended>
<extended type="date">01.01.1900-01.01.2100</extended>
</request>

INFO | 20070504-175401.521 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogUsageManager.cxx:1227 | Got: SipReq:  INVITE
spzero@xxxxxxxxxxxxxx:12005 tid=-3788-1-0 cseq=INVITE
contact=valiant@xxxxxxxxxxxxxx:5060 / 1 from(wire)
DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogUsageManager.cxx:1494 |
DialogUsageManager::processRequest: SipReq:  INVITE
spzero@xxxxxxxxxxxxxx:12005 tid=-3788-1-0 cseq=INVITE
contact=valiant@xxxxxxxxxxxxxx:5060 / 1 from(wire)
DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogSetId.cxx:31 | ********** Generated Local Tag
***********
DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogSetId.cxx:31 | ********** Generated Local Tag
***********
DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogSetId.cxx:31 | ********** Generated Local Tag
***********
DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogSet.cxx:84 |  ************* Created DialogSet(UAS)
-- 1-3788@xxxxxxxxxxxxxxxxxxxxxxx*************
UAS(INVITE): testAppDialogSet: created.
UAS(INVITE): testAppDialogSet: UAS UserProfile requested for msg:
SipReq:  INVITE spzero@xxxxxxxxxxxxxx:12005 tid=-3788-1-0 cseq=INVITE
contact=valiant@xxxxxxxxxxxxxx:5060 / 1 from(wire)
DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogSetId.cxx:31 | ********** Generated Local Tag
***********
DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogId.cxx:50 | DialogId::DialogId:
1-3788@xxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | 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-3788-1-0
Max-Forwards: 70
Contact: <sip:valiant@xxxxxxxxxxxxxx:5060>
To: "spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>
From: "valiant"<sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
Call-ID: 1-3788@xxxxxxxxxxxxxx
CSeq: 1 INVITE
Allow: INVITE, ACK, MESSAGE
Content-Type: text/xml
Content-Length: 179

<?xml version="1.0" encoding="ISO-8859-1" ?>
<request>
<name>Loo</name>
<extended type="size">0</extended>
<extended type="date">01.01.1900-01.01.2100</extended>
</request>

DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:87 | UAS dialog ID creation, DS:
1-3788@xxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20070504-175401.522 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogId.cxx:63 | DialogId::DialogId:
1-3788@xxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:129 | ************** Created Dialog as UAS
**************
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:130 | mRemoteNameAddr:
"valiant"<sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:131 | mLocalNameAddr:
"spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>;tag=2dfed151
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:132 | mLocalContact:
<sip:spzero@xxxxxxxxxxxxxx:12005>
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:133 | mRemoteTarget:
<sip:valiant@xxxxxxxxxxxxxx:5060>
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:227 | Dialog::Dialog
1-3788@xxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | 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-3788-1-0
Max-Forwards: 70
Contact: <sip:valiant@xxxxxxxxxxxxxx:5060>
To: "spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>
From: "valiant"<sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
Call-ID: 1-3788@xxxxxxxxxxxxxx
CSeq: 1 INVITE
Allow: INVITE, ACK, MESSAGE
Content-Type: text/xml
Content-Length: 179

<?xml version="1.0" encoding="ISO-8859-1" ?>
<request>
<name>Loo</name>
<extended type="size">0</extended>
<extended type="date">01.01.1900-01.01.2100</extended>
</request>

UAS(INVITE): testAppDialog: created.
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:313 | Dialog::dispatch: SipReq:  INVITE
spzero@xxxxxxxxxxxxxx:12005 tid=-3788-1-0 cseq=INVITE
contact=valiant@xxxxxxxxxxxxxx:5060 / 1 from(wire)
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:345 | Dialog::dispatch  --  Created new server
invite sessionSipReq: INVITE spzero@xxxxxxxxxxxxxx:12005 tid=-3788-1-0 
cseq=INVITE contact=valiant@xxxxxxxxxxxxxx:5060 / 1 from(wire)
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | InviteSession.cxx:73 | ^^^ InviteSession::InviteSession
0x8378168
DEBUG | 20070504-175401.523 | kenobi | nsp | RESIP | 3850 | 3080010112 | 
SipMessage.cxx:963 | SipMessage::getContents: text/xml
INFO | 20070504-175401.523 | kenobi | nsp | RESIP | 3850 | 3080010112 | 
SipMessage.cxx:972 | SipMessage::getContents: got content type
(text/xml) that is not known, returning as opaque
application/octet-stream
INFO | 20070504-175401.524 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | InviteSession.cxx:2067 | Transition UAS_Start ->
UAS_NoOffer
UAS: ServerInviteSession-onNewSession -
SipReq:  INVITE spzero@xxxxxxxxxxxxxx:12005 tid=-3788-1-0 cseq=INVITE
contact=valiant@xxxxxxxxxxxxxx:5060 / 1 from(wire)
searching for files containing: Loo
INFO | 20070504-175401.524 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | ServerInviteSession.cxx:384 | UAS_NoOffer: reject(606)
DEBUG | 20070504-175401.524 | kenobi | nsp | RESIP | 3850 | 3080010112 | 
Helper.cxx:372 | Helper::makeResponse(SipReq:  INVITE
spzero@xxxxxxxxxxxxxx:12005 tid=-3788-1-0 cseq=INVITE
contact=valiant@xxxxxxxxxxxxxx:5060 / 1 from(wire) code=606 reason=
DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:982 | Dialog::makeResponse:

SIP/2.0 606 Not Acceptable
Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
To: "spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>;tag=2dfed151
From: "valiant"<sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
Call-ID: 1-3788@xxxxxxxxxxxxxx
CSeq: 1 INVITE
Content-Length: 0


UAS: InviteSession-onReadyToSend -
DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogUsageManager.cxx:800 | SEND:

SIP/2.0 606 Not Acceptable
Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
To: "spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>;tag=2dfed151
From: "valiant"<sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
Call-ID: 1-3788@xxxxxxxxxxxxxx
CSeq: 1 INVITE
Content-Type: text/xml
Content-Length: 50

<?xml version="1.0" ?><reply status="Not found" />
DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP | 3850 | 3080010112 | 
SipStack.cxx:290 | SEND: SipResp: 606 tid=-3788-1-0 cseq=INVITE / 1
from(tu)
INFO | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | InviteSession.cxx:2067 | Transition UAS_NoOffer ->
InviteSession::Terminated
UAS: InviteSession-onTerminated -
DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | InviteSession.cxx:79 | ^^^ InviteSession::~InviteSession
0x8378168
DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | Dialog.cxx:232 | Dialog::~Dialog()
UAS(INVITE): testAppDialog: destroyed.
DEBUG | 20070504-175401.525 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogUsageManager.cxx:1986 | Got merged request removal
request
DEBUG | 20070504-175401.526 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TimerQueue.cxx:105 | Adding application timer:
DEBUG | 20070504-175401.526 | kenobi | nsp | RESIP:DUM | 3850 |
3080010112 | DialogSet.cxx:122 |  ********** DialogSet::~DialogSet:
1-3788@xxxxxxxxxxxxxxxxxxxxxxx*************
UAS(INVITE): testAppDialogSet: destroyed.
DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TimerQueue.cxx:85 | Adding timer: Timer H tid=-3788-1-0
ms=64000
DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TimerQueue.cxx:85 | Adding timer: Timer G tid=-3788-1-0
ms=1000
DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | InternalTransport.cxx:86 | Creating fd=8 V4/UDP
DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | TransportSelector.cxx:521 | Looked up source for
destination: [ V4 192.168.61.132:5060 UDP target domain=unspecified
received on: Transport: [ V4 0.0.0.0:12005 UDP target domain=unspecified 
connectionId=0 ] connectionId=0 ] -> [ V4 192.168.61.130:0 UDP target
domain=unspecified received on: Transport: [ V4 0.0.0.0:12005 UDP target 
domain=unspecified connectionId=0 ] connectionId=0 ]
sent-by=192.168.61.132 sent-port=5060
DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | TransportSelector.cxx:879 | Transmitting to [ V4
192.168.61.132:5060 UDP target domain=unspecified received on:
Transport: [ V4 0.0.0.0:12005 UDP target domain=unspecified
connectionId=0 ] connectionId=0 ] tlsDomain= via [ V4
192.168.61.130:12005 UDP target domain=unspecified received on:
Transport: [ V4 0.0.0.0:12005 UDP target domain=unspecified
connectionId=0 ] connectionId=0 ]

SIP/2.0 606 Not Acceptable
Via: SIP/2.0/UDP 192.168.61.132:5060;branch=z9hG4bK-3788-1-0
To: "spzero"<sip:spzero@xxxxxxxxxxxxxx:12005>;tag=2dfed151
From: "valiant"<sip:valiant@xxxxxxxxxxxxxx:5060>;tag=1
Call-ID: 1-3788@xxxxxxxxxxxxxx
CSeq: 1 INVITE
Content-Type: text/xml
Content-Length: 50

<?xml version="1.0" ?><reply status="Not found" />
DEBUG | 20070504-175401.580 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | Transport.cxx:213 | Adding message to tx buffer to: [ V4
192.168.61.132:5060 UDP target domain=unspecified received on:
Transport: [ V4 0.0.0.0:12005 UDP target domain=unspecified
connectionId=0 ] connectionId=0 ]
DEBUG | 20070504-175401.684 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | Transport.cxx:287 | incoming from: [ V4 192.168.61.132:5060 UDP target domain=unspecified received on: Transport: [ V4 0.0.0.0:12005 
UDP target domain=unspecified connectionId=0 ] connectionId=0 ]
DEBUG | 20070504-175401.685 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TimerQueue.cxx:85 | Adding timer: Timer I tid=-3788-1-0
ms=10000
DEBUG | 20070504-175402.478 | kenobi | nsp | RESIP:TRANSPORT | 3850 |
3080010112 | Transport.cxx:287 | incoming from: [ V4 192.168.61.132:5060 UDP target domain=unspecified received on: Transport: [ V4 0.0.0.0:12005 
UDP target domain=unspecified connectionId=0 ] connectionId=0 ]
DEBUG | 20070504-175402.479 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TransactionUser.cxx:66 | Checking if SipReq:  INVITE
spzero@xxxxxxxxxxxxxx:12005 tid=-3788-2-0 cseq=INVITE
contact=korene@xxxxxxxxxxxxxx:5060 / 1 from(wire) is for me
DEBUG | 20070504-175402.479 | kenobi | nsp | RESIP:TRANSACTION | 3850 | 
3080010112 | TransactionUser.cxx:71 | Checking rule...DEBUG |
20070504-175402.479 | kenobi | nsp | RESIP:TRANSACTION | 3850 |
3080010112 | MessageFilterRule.cxx:42 | Matching rule for:

INVITE sip:spzero@xxxxxxxxxxxxxx:12005 SIP/2.0
==============================================

Attachment: smime.p7s
Description: S/MIME cryptographic signature