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

Re: [reSIProcate] dum behavior on call stale timeout


Ops, I forgot the attachment! :-D

Gianluca Martiniello wrote:
Hi all,

I am using DUM to implement a simple user agent and I have noticed some strange behaviors exposed by DUM itself.

According to the DUM documentation (http://www.estacado.net/resip/sip/resiprocate/doxygen/html/classresip_1_1Profile.html#a18) a call is stale if UAC gets no final response within the stale call timeout whose default value is 3 minutes. Therefore when a call becomes stale, according to RFC 3261 a CANCEL request should be issued (no final response has been received, only one or more provisional).

I have no idea of what DUM is able and what it is not able to manage but as I know it is able to automatically handle registration refreshes, the first time I faced the call stale condition I was expecting a CANCEL request automatically issued by DUM. And in fact DUM, after having notified the InviteSessionHandler about stale call timeout (with the onStaleCallTimeout() callback) automatically generated a request but with my big surprice I saw it was a BYE request! :-|

Then I decided to modify the code of my (dummy) user agent in such a way that on stale call timeout event a CANCEL request was generated (using the end() method of the associated AppDialogSet object) hoping that DUM layer after having sent this message avoided to send a BYE request. But again with my big surprice I saw that after the transmission of the CANCEL request DUM issued a BYE request! :-/ I have attached a log, generated with DEBUG level, which shows the behavior just described, maybe this is a bug in DUM... however if it is not a bug, my question is: what should I do to correctly generate a CANCEL request? (when I say "correctly" I mean "without having DUM to send a BYE request")

Thank you.

Gianluca
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | BaseCreator.cxx:90 | BaseCreator::makeInitialRequest: INVITE 
sip:pippo@xxxxxxxx SIP/2.0
To: <sip:pippo@xxxxxxxx>
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/ ;branch=z9hG4bK-d87543-506ab176ddfe6d2b-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Contact: <sip:testua>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE
Content-Length: 0


DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogSet.cxx:51 |  ************* Created DialogSet(UAC)  -- 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx*************
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogUsageManager.cxx:382 | ************* Adding DialogSet 
***************
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogUsageManager.cxx:383 | Before: 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90]
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogUsageManager.cxx:385 | After: 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogUsageManager.cxx:1493 | Looking for dialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx in map:
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogUsageManager.cxx:1494 | 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogUsageManager.cxx:607 | SEND: INVITE sip:pippo@xxxxxxxx 
SIP/2.0
To: <sip:pippo@xxxxxxxx>
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/ ;branch=z9hG4bK-d87543-506ab176ddfe6d2b-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Contact: <sip:testua>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE
Content-Type: application/sdp
User-Agent: SipMonitoringAgent/0.6
Content-Length: 173

v=0
o=1900 369696545 369696545 IN IP4 192.168.97.154
s=SipMonitoring
c=IN IP4 192.168.97.154
t=0 0
m=audio 8000 RTP/AVP 8 3
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000

DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogId.cxx:50 | DialogId::DialogId: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogUsageManager.cxx:1493 | Looking for dialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx in map:
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogUsageManager.cxx:1494 | 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogSet.cxx:665 | findDialog: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxx in []
DEBUG | 20051028-181734.991 | bianconi | sipmond | RESIP:DUM | 9692 | 
1080886656 | DialogUsageManager.cxx:663 | Using outbound proxy
DEBUG | 20051028-181734.992 | bianconi | sipmond | SIPMON | 9692 | 1080886656 | 
SessionMonitoring.cxx:57 | Passed INVITE message to DUM: 
INVITE sip:pippo@xxxxxxxx SIP/2.0
To: <sip:pippo@xxxxxxxx>
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/ ;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Contact: <sip:testua>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE
Content-Type: application/sdp
User-Agent: SipMonitoringAgent/0.6
Content-Length: 173

v=0
o=1900 369696545 369696545 IN IP4 192.168.97.154
s=SipMonitoring
c=IN IP4 192.168.97.154
t=0 0
m=audio 8000 RTP/AVP 8 3
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000

DEBUG | 20051028-181735.015 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer B tid=0f2ede7818bc752e 
ms=32000
DEBUG | 20051028-181735.015 | bianconi | sipmond | RESIP:DNS | 9692 | 
1089743792 | DnsResult.cxx:136 | DnsResult::lookup sip:192.168.97.154
DEBUG | 20051028-181735.016 | bianconi | sipmond | RESIP:DNS | 9692 | 
1089743792 | DnsResult.cxx:255 | Numeric result so return immediately: [ V4 
192.168.97.154:5060 UDP connectionId=0 ]
DEBUG | 20051028-181735.016 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer A tid=0f2ede7818bc752e 
ms=500
DEBUG | 20051028-181735.016 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:514 | Looked up source for destination: [ V4 
192.168.97.154:5060 UDP connectionId=0 ] -> [ V4 192.168.97.154:0 UDP 
connectionId=0 ] sent-by= sent-port=0
DEBUG | 20051028-181735.016 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:740 | findTransport([ V4 192.168.97.154:0 
UDP connectionId=0 ])
DEBUG | 20051028-181735.016 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:782 | findTransport (any port, any 
interface) => Transport: [ V4 0.0.0.0:5062 UDP connectionId=0 ]
DEBUG | 20051028-181735.016 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:620 | !sipit! Populated Contact: 
<sip:testua@xxxxxxxxxxxxxx:5062>
DEBUG | 20051028-181735.016 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:658 | Transmitting to [ V4 
192.168.97.154:5060 UDP received on: Transport: [ V4 0.0.0.0:5062 UDP 
connectionId=0 ] connectionId=0 ] via [ V4 192.168.97.154:0 UDP connectionId=0 
]INVITE sip:pippo@xxxxxxxx SIP/2.0
To: <sip:pippo@xxxxxxxx>
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Contact: <sip:testua@xxxxxxxxxxxxxx:5062>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE
Content-Type: application/sdp
User-Agent: SipMonitoringAgent/0.6
Content-Length: 173

v=0
o=1900 369696545 369696545 IN IP4 192.168.97.154
s=SipMonitoring
c=IN IP4 192.168.97.154
t=0 0
m=audio 8000 RTP/AVP 8 3
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000

DEBUG | 20051028-181735.016 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | Transport.cxx:160 | Adding message to tx buffer to: [ V4 
192.168.97.154:5060 UDP received on: Transport: [ V4 0.0.0.0:5062 UDP 
connectionId=0 ] connectionId=0 ]
DEBUG | 20051028-181735.118 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | Transport.cxx:209 | incoming from: [ V4 192.168.97.154:5060 UDP 
received on: Transport: [ V4 0.0.0.0:5062 UDP connectionId=0 ] connectionId=0 ]
DEBUG | 20051028-181735.118 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TransactionState.cxx:1535 | Send to TU: TU: DialogUsageManager 
size=0 SIP/2.0 180 Ringing
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Record-Route: <sip:192.168.97.154:5060;transport=udp>
Contact: <sip:pippo@xxxxxxxxxxxxxx:5070>
Server: X-Lite release 1105d
Content-Length: 0


INFO | 20051028-181735.118 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:830 | Got: SipResp: 180 tid=0f2ede7818bc752e 
cseq=INVITE contact=pippo@xxxxxxxxxxxxxx:5070 / 1 from(wire)
DEBUG | 20051028-181735.118 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1380 | DialogUsageManager::processResponse: 
SIP/2.0 180 Ringing
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Record-Route: <sip:192.168.97.154:5060;transport=udp>
Contact: <sip:pippo@xxxxxxxxxxxxxx:5070>
Server: X-Lite release 1105d
Content-Length: 0


DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1493 | Looking for dialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx in map:
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1494 | 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1395 | DialogUsageManager::processResponse: 
SipResp: 180 tid=0f2ede7818bc752e cseq=INVITE contact=pippo@xxxxxxxxxxxxxx:5070 
/ 1 from(wire)
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogId.cxx:50 | DialogId::DialogId: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogSet.cxx:665 | findDialog: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx in []
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogSet.cxx:600 | Creating a new Dialog from msg: SIP/2.0 180 
Ringing
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Record-Route: <sip:192.168.97.154:5060;transport=udp>
Contact: <sip:pippo@xxxxxxxxxxxxxx:5070>
Server: X-Lite release 1105d
Content-Length: 0


DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogId.cxx:50 | DialogId::DialogId: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:197 | ************** Created Dialog as UAC 
**************
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:198 | mRemoteNameAddr: 
<sip:pippo@xxxxxxxx>;tag=457544134
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:199 | mLocalNameAddr: 
<sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:200 | mLocalContact: <sip:testua>
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:201 | mRemoteTarget: <sip:pippo@xxxxxxxxxxxxxx:5070>
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:206 | Dialog::Dialog 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogSet.cxx:635 | ### Calling CreateAppDialog ### SIP/2.0 180 
Ringing
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Record-Route: <sip:192.168.97.154:5060;transport=udp>
Contact: <sip:pippo@xxxxxxxxxxxxxx:5070>
Server: X-Lite release 1105d
Content-Length: 0


DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:289 | Dialog::dispatch: SipResp: 180 
tid=0f2ede7818bc752e cseq=INVITE contact=pippo@xxxxxxxxxxxxxx:5070 / 1 
from(wire)
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:526 | Dialog::dispatch  --  Created new client invite 
sessionSipResp: 180 tid=0f2ede7818bc752e cseq=INVITE 
contact=pippo@xxxxxxxxxxxxxx:5070 / 1 from(wire)
DEBUG | 20051028-181735.119 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | InviteSession.cxx:45 | ^^^ InviteSession::InviteSession 0x808c9d0
INFO | 20051028-181735.120 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| InviteSession.cxx:1438 | Transition UAC_Start -> UAC_Early
INFO | 20051028-181735.120 | bianconi | sipmond | SIPMON | 9692 | 1098136496 | 
SessionMonitoring.cxx:104 | testua@xxxxxxxxxxxxxx from pippo@xxxxxxxx initial 
response code=180
INFO | 20051028-181735.120 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| ClientInviteSession.cxx:286 | UAC_Early: startStaleCallTimer
DEBUG | 20051028-181735.120 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1098136496 | TimerQueue.cxx:105 | Adding application timer: 
DumTimeout::StaleCall INVITE: 5 UAC_Early 
ADDR=<sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74 
PEER=<sip:pippo@xxxxxxxx>;tag=457544134: duration=30 seq=2
INFO | 20051028-181735.120 | bianconi | sipmond | SIPMON | 9692 | 1098136496 | 
SessionMonitoring.cxx:164 | testua@xxxxxxxxxxxxxx onProvisional pippo@xxxxxxxx 
StatusCode= 180
INFO | 20051028-181805.120 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:830 | Got: DumTimeout::StaleCall INVITE: 5 UAC_Early 
ADDR=<sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74 
PEER=<sip:pippo@xxxxxxxx>;tag=457544134: duration=30 seq=2
INFO | 20051028-181805.120 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:942 | Timeout Message
WARNING | 20051028-181805.120 | bianconi | sipmond | SIPMON | 9692 | 1098136496 
| SessionMonitoring.cxx:202 | testua@xxxxxxxxxxxxxx Stale Call Timeout with 
pippo@xxxxxxxx
DEBUG | 20051028-181805.120 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1493 | Looking for dialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx in map:
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1494 | 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:607 | SEND: CANCEL sip:pippo@xxxxxxxx 
SIP/2.0
To: <sip:pippo@xxxxxxxx>
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/ ;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 CANCEL
User-Agent: SipMonitoringAgent/0.6
Content-Length: 0


DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogId.cxx:50 | DialogId::DialogId: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1493 | Looking for dialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx in map:
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1494 | 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogSet.cxx:665 | findDialog: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxx in 
[7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx -> 0x8082130]
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:663 | Using outbound proxy
INFO | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| ClientInviteSession.cxx:237 | UAC_Early: cancel
INFO | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| ClientInviteSession.cxx:279 | UAC_Early: startCancelTimer
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1098136496 | TimerQueue.cxx:105 | Adding application timer: 
DumTimeout::Cancelled INVITE: 5 UAC_Early 
ADDR=<sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74 
PEER=<sip:pippo@xxxxxxxx>;tag=457544134: duration=32000 seq=2
INFO | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| InviteSession.cxx:1438 | Transition UAC_Early -> UAC_Cancelled
INFO | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| ClientInviteSession.cxx:149 | UAC_Cancelled: end
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:850 | Dialog::makeRequest: BYE 
sip:pippo@xxxxxxxxxxxxxx:5070 SIP/2.0
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/ ;branch=z9hG4bK-d87543-be94704f07ae0d00-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 2 BYE
Route: <sip:192.168.97.154:5060;transport=udp>
Contact: <sip:testua>
Max-Forwards: 70
Content-Length: 0


INFO | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| InviteSession.cxx:1689 | Sending SipReq:  BYE pippo@xxxxxxxxxxxxxx:5070 
tid=be94704f07ae0d00 cseq=BYE contact=testua / 2 from(tu)
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1493 | Looking for dialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx in map:
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1494 | 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181805.121 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:607 | SEND: BYE 
sip:pippo@xxxxxxxxxxxxxx:5070 SIP/2.0
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/ ;branch=z9hG4bK-d87543-be94704f07ae0d00-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 2 BYE
Route: <sip:192.168.97.154:5060;transport=udp>
Contact: <sip:testua>
Max-Forwards: 70
User-Agent: SipMonitoringAgent/0.6
Content-Length: 0


DEBUG | 20051028-181805.122 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogId.cxx:50 | DialogId::DialogId: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181805.122 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1493 | Looking for dialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx in map:
DEBUG | 20051028-181805.122 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1494 | 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181805.122 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogSet.cxx:665 | findDialog: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx in 
[7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx -> 0x8082130]
DEBUG | 20051028-181805.122 | bianconi | sipmond | RESIP | 9692 | 1098136496 | 
SipStack.cxx:269 | SEND: SipReq:  BYE 192.168.97.154:5060 tid=e34a98184b0be369 
cseq=BYE contact=testua / 2 from(tu)
INFO | 20051028-181805.122 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| InviteSession.cxx:1438 | Transition UAC_Cancelled -> InviteSession::Terminated
INFO | 20051028-181805.122 | bianconi | sipmond | SIPMON | 9692 | 1098136496 | 
SessionMonitoring.cxx:280 | testua@xxxxxxxxxxxxxx ended call with pippo@xxxxxxxx
DEBUG | 20051028-181805.145 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer E1 
tid=0f2ede7818bc752ecancel ms=500
DEBUG | 20051028-181805.145 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer F 
tid=0f2ede7818bc752ecancel ms=32000
DEBUG | 20051028-181805.145 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:514 | Looked up source for destination: [ V4 
192.168.97.154:5060 UDP received on: Transport: [ V4 0.0.0.0:5062 UDP 
connectionId=0 ] connectionId=0 ] -> [ V4 192.168.97.154:0 UDP received on: 
Transport: [ V4 0.0.0.0:5062 UDP connectionId=0 ] connectionId=0 ] sent-by= 
sent-port=0
DEBUG | 20051028-181805.145 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:658 | Transmitting to [ V4 
192.168.97.154:5060 UDP received on: Transport: [ V4 0.0.0.0:5062 UDP 
connectionId=0 ] connectionId=0 ] via [ V4 192.168.97.154:0 UDP received on: 
Transport: [ V4 0.0.0.0:5062 UDP connectionId=0 ] connectionId=0 ]CANCEL 
sip:pippo@xxxxxxxx SIP/2.0
To: <sip:pippo@xxxxxxxx>
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 CANCEL
User-Agent: SipMonitoringAgent/0.6
Content-Length: 0


DEBUG | 20051028-181805.145 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | Transport.cxx:160 | Adding message to tx buffer to: [ V4 
192.168.97.154:5060 UDP received on: Transport: [ V4 0.0.0.0:5062 UDP 
connectionId=0 ] connectionId=0 ]
DEBUG | 20051028-181805.145 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer Cleanup 
tid=0f2ede7818bc752e ms=64000
DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer F tid=e34a98184b0be369 
ms=32000
DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:DNS | 9692 | 
1089743792 | DnsResult.cxx:136 | DnsResult::lookup 
sip:192.168.97.154:5060;transport=udp
DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:DNS | 9692 | 
1089743792 | DnsResult.cxx:152 | Found immediate result: [ V4 
192.168.97.154:5060 UDP connectionId=0 ]
DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer E1 tid=e34a98184b0be369 
ms=500
DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:514 | Looked up source for destination: [ V4 
192.168.97.154:5060 UDP connectionId=0 ] -> [ V4 192.168.97.154:0 UDP 
connectionId=0 ] sent-by= sent-port=0
DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:740 | findTransport([ V4 192.168.97.154:0 
UDP connectionId=0 ])
DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:782 | findTransport (any port, any 
interface) => Transport: [ V4 0.0.0.0:5062 UDP connectionId=0 ]
DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:620 | !sipit! Populated Contact: 
<sip:testua@xxxxxxxxxxxxxx:5062>
DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:658 | Transmitting to [ V4 
192.168.97.154:5060 UDP received on: Transport: [ V4 0.0.0.0:5062 UDP 
connectionId=0 ] connectionId=0 ] via [ V4 192.168.97.154:0 UDP connectionId=0 
]BYE sip:192.168.97.154:5060;transport=udp SIP/2.0
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-e34a98184b0be369-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 2 BYE
Route: <sip:pippo@xxxxxxxxxxxxxx:5070>
Contact: <sip:testua@xxxxxxxxxxxxxx:5062>
Max-Forwards: 70
User-Agent: SipMonitoringAgent/0.6
Content-Length: 0


DEBUG | 20051028-181805.146 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | Transport.cxx:160 | Adding message to tx buffer to: [ V4 
192.168.97.154:5060 UDP received on: Transport: [ V4 0.0.0.0:5062 UDP 
connectionId=0 ] connectionId=0 ]
DEBUG | 20051028-181805.175 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | Transport.cxx:209 | incoming from: [ V4 192.168.97.154:5060 UDP 
received on: Transport: [ V4 0.0.0.0:5062 UDP connectionId=0 ] connectionId=0 ]
DEBUG | 20051028-181805.175 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer D tid=0f2ede7818bc752e 
ms=32000
DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | TransportSelector.cxx:658 | Transmitting to [ V4 
192.168.97.154:5060 UDP received on: Transport: [ V4 0.0.0.0:5062 UDP 
connectionId=0 ] connectionId=0 ] via [ V4 192.168.97.154:5062 UDP 
connectionId=0 ]ACK sip:pippo@xxxxxxxx SIP/2.0
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 ACK
Content-Length: 0


DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | Transport.cxx:160 | Adding message to tx buffer to: [ V4 
192.168.97.154:5060 UDP received on: Transport: [ V4 0.0.0.0:5062 UDP 
connectionId=0 ] connectionId=0 ]
DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TransactionState.cxx:1535 | Send to TU: TU: DialogUsageManager 
size=0 SIP/2.0 487 Request Terminated
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Content-Length: 0


INFO | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:830 | Got: SipResp: 487 tid=0f2ede7818bc752e 
cseq=INVITE / 1 from(wire)
DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1380 | DialogUsageManager::processResponse: 
SIP/2.0 487 Request Terminated
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Content-Length: 0


DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1493 | Looking for dialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx in map:
DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1494 | 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1395 | DialogUsageManager::processResponse: 
SipResp: 487 tid=0f2ede7818bc752e cseq=INVITE / 1 from(wire)
DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogId.cxx:50 | DialogId::DialogId: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogSet.cxx:665 | findDialog: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx in 
[7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx -> 0x8082130]
DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogSet.cxx:361 | Found matching dialog mClientSubscriptions(0), 
mServerSubscriptions(0) for 
SIP/2.0 487 Request Terminated
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 INVITE
Content-Length: 0


DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:289 | Dialog::dispatch: SipResp: 487 
tid=0f2ede7818bc752e cseq=INVITE / 1 from(wire)
INFO | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| InviteSession.cxx:930 | InviteSession::dispatchTerminated SipResp: 487 
tid=0f2ede7818bc752e cseq=INVITE / 1 from(wire)
INFO | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:830 | Got: DestroyUsage INVITE: 5 
InviteSession::Terminated ADDR=<sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74 
PEER=<sip:pippo@xxxxxxxx>;tag=457544134
DEBUG | 20051028-181805.176 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:934 | Destroying usageDestroyUsage INVITE: 
5 InviteSession::Terminated ADDR=<sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74 
PEER=<sip:pippo@xxxxxxxx>;tag=457544134
DEBUG | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | InviteSession.cxx:51 | ^^^ InviteSession::~InviteSession 0x808c9d0
INFO | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:830 | Got: DestroyDialog 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:934 | Destroying usageDestroyDialog 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | Dialog.cxx:211 | Dialog::~Dialog() 
INFO | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:830 | Got: DestroyDialogSet 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:934 | Destroying usageDestroyDialogSet 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx
DEBUG | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogSet.cxx:119 |  ********** DialogSet::~DialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx*************
DEBUG | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1524 | ************* Removing DialogSet 
***************
DEBUG | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1525 | Before: 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90, 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx -> 0x8085ad0]
DEBUG | 20051028-181805.177 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1527 | After: 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90]
DEBUG | 20051028-181805.209 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | Transport.cxx:209 | incoming from: [ V4 192.168.97.154:5060 UDP 
received on: Transport: [ V4 0.0.0.0:5062 UDP connectionId=0 ] connectionId=0 ]
DEBUG | 20051028-181805.209 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TransactionState.cxx:1535 | Send to TU: TU: DialogUsageManager 
size=0 SIP/2.0 500 Server Internal Error
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-e34a98184b0be369-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 2 BYE
Max-Forwards: 70
Content-Length: 0

DEBUG | 20051028-181805.209 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer K tid=e34a98184b0be369 
ms=5000
INFO | 20051028-181805.209 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:830 | Got: SipResp: 500 tid=e34a98184b0be369 cseq=BYE 
/ 2 from(wire)
DEBUG | 20051028-181805.209 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1380 | DialogUsageManager::processResponse: 
SIP/2.0 500 Server Internal Error
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-e34a98184b0be369-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 2 BYE
Max-Forwards: 70
Content-Length: 0


DEBUG | 20051028-181805.209 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1493 | Looking for dialogSet: 
7bb9d3408fec6d0d@xxxxxxxxxxxxxxxxxxxxx in map:
DEBUG | 20051028-181805.209 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1494 | 
[549472531613ba69@xxxxxxxxxxxxxxxxxxxxx -> 0x8081b90]
INFO | 20051028-181805.209 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:1400 | Throwing away stray response: SipResp: 500 
tid=e34a98184b0be369 cseq=BYE / 2 from(wire)
DEBUG | 20051028-181805.233 | bianconi | sipmond | RESIP:TRANSPORT | 9692 | 
1089743792 | Transport.cxx:209 | incoming from: [ V4 192.168.97.154:5060 UDP 
received on: Transport: [ V4 0.0.0.0:5062 UDP connectionId=0 ] connectionId=0 ]
DEBUG | 20051028-181805.234 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TransactionState.cxx:1535 | Send to TU: TU: DialogUsageManager 
size=0 SIP/2.0 200 Ok
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 CANCEL
Record-Route: <sip:192.168.97.154:5060;transport=udp>
Contact: <sip:pippo@xxxxxxxxxxxxxx:5070>
Server: X-Lite release 1105d
Content-Length: 0


DEBUG | 20051028-181805.234 | bianconi | sipmond | RESIP:TRANSACTION | 9692 | 
1089743792 | TimerQueue.cxx:85 | Adding timer: Timer K 
tid=0f2ede7818bc752ecancel ms=5000
INFO | 20051028-181805.234 | bianconi | sipmond | RESIP:DUM | 9692 | 1098136496 
| DialogUsageManager.cxx:830 | Got: SipResp: 200 tid=0f2ede7818bc752e 
cseq=CANCEL contact=pippo@xxxxxxxxxxxxxx:5070 / 1 from(wire)
DEBUG | 20051028-181805.234 | bianconi | sipmond | RESIP:DUM | 9692 | 
1098136496 | DialogUsageManager.cxx:1380 | DialogUsageManager::processResponse: 
SIP/2.0 200 Ok
To: <sip:pippo@xxxxxxxx>;tag=457544134
From: <sip:testua@xxxxxxxxxxxxxx>;tag=b1b0ba74
Via: SIP/2.0/UDP 
192.168.97.154:5062;branch=z9hG4bK-d87543-0f2ede7818bc752e-1--d87543-;rport
Call-ID: 7bb9d3408fec6d0d@YmlhbmNvbmk.
CSeq: 1 CANCEL
Record-Route: <sip:192.168.97.154:5060;transport=udp>
Contact: <sip:pippo@xxxxxxxxxxxxxx:5070>
Server: X-Lite release 1105d
Content-Length: 0