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] Bug report

I'm using Log::initialize(Log::File, Log::Debug, "TEST UA", "c:\\log.txt"); for the log, there are more logs:
 
DEBUG | 20081103-113224.359 | TEST UA | RESIP:DUM | 5436 | DialogId.cxx:50 | DialogId::DialogId: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.-ca7c4849-
DEBUG | 20081103-113224.359 | TEST UA | RESIP:DUM | 5436 | DialogUsageManager.cxx:975 | Using outbound proxy: sip:192.168.5.25:5060;lr -> SipReq:  INVITE 201@xxxxxxxxxxxx tid=1a13c2433178a747 cseq=INVITE contact=Admin / 1 from(tu)
DEBUG | 20081103-113224.375 | TEST UA | RESIP:TRANSACTION | 3152 | TimerQueue.cxx:85 | Adding timer: Timer B tid=1a13c2433178a747 ms=32000
DEBUG | 20081103-113224.375 | TEST UA | RESIP:DNS | 3152 | DnsResult.cxx:214 | DnsResult::lookup sip:192.168.5.25:5060;lr
DEBUG | 20081103-113224.375 | TEST UA | RESIP:DNS | 3152 | DnsResult.cxx:398 | Numeric result so return immediately: [ V4 192.168.5.25:5060 UDP target domain=192.168.5.25 mFlowKey=0 ]
DEBUG | 20081103-113224.375 | TEST UA | RESIP:TRANSACTION | 3152 | TimerQueue.cxx:85 | Adding timer: Timer A tid=1a13c2433178a747 ms=500
DEBUG | 20081103-113224.375 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:562 | Looked up source for destination: [ V4 192.168.5.25:5060 UDP target domain=192.168.5.25 mFlowKey=0 ] -> [ V4 192.168.5.138:0 UDP target domain=192.168.5.25 mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20081103-113224.375 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:1107 | findTransportBySource([ V4 192.168.5.138:0 UDP target domain=192.168.5.25 mFlowKey=0 ])
DEBUG | 20081103-113224.375 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:1219 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5311 UDP target domain=unspecified mFlowKey=1816 ]
DEBUG | 20081103-113224.375 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:940 | Transmitting to [ V4 192.168.5.25:5060 UDP target domain=192.168.5.25 mFlowKey=0 ] tlsDomain= via [ V4 192.168.5.138:5311 UDP target domain=192.168.5.25 mFlowKey=0 ]
 
INVITE sip:201@xxxxxxxxxxxx SIP/2.0
Via: SIP/2.0/UDP 192.168.5.138:5311;branch=z9hG4bK-d8754z-1a13c2433178a747-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:Admin@xxxxxxxxxxxxx:5311>
To: <sip:201@xxxxxxxxxxxx>
From: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, REGISTER, SUBSCRIBE, INFO
Content-Type: application/sdp
Supported: replaces
User-Agent: My Test UA
Content-Length: 360
 
v=0
o=- 61978938 61978938 IN IP4 192.168.5.138
s=A call
c=IN IP4 192.168.5.138
t=0 0
m=audio 21366 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=fmtp:101 0-15
m=video 41024 RTP/AVP 34
a=fmtp:34 QCIF=2
a=rtpmap:34 H263/90000

DEBUG | 20081103-113224.375 | TEST UA | RESIP:TRANSPORT | 3152 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 192.168.5.25:5060 UDP target domain=192.168.5.25 mFlowKey=0 ]
DEBUG | 20081103-113224.468 | TEST UA | RESIP:TRANSPORT | 3152 | Transport.cxx:287 | incoming from: [ V4 192.168.5.25:5060 UDP target domain=unspecified mFlowKey=1816 ]
DEBUG | 20081103-113224.468 | TEST UA | RESIP:DNS | 3152 | DnsResult.cxx:206 | Whitelisting 192.168.5.25(1): 192.168.5.25
DEBUG | 20081103-113224.468 | TEST UA | RESIP:DNS | 3152 | RRVip.cxx:129 | updating an existing vip: 192.168.5.25 with 192.168.5.25
DEBUG | 20081103-113224.468 | TEST UA | RESIP:TRANSACTION | 3152 | TransactionState.cxx:2108 | Send to TU: TU: DialogUsageManager size=0
 
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.5.138:5311;branch=z9hG4bK-d8754z-1a13c2433178a747-1---d8754z-;rport=5311
Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
To: <sip:201@xxxxxxxxxxxx>;tag=7b530979
From: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 1 INVITE
Session-Expires: 600;refresher=uas
Min-SE: 90
Allow: ACK, BYE, CANCEL, INVITE, REGISTER, INFO, REFER, SUBSCRIBE, NOTIFY, MESSAGE, UPDATE, REGISTER
Content-Type: application/sdp
Supported: replaces, timer
Content-Length: 356
 
v=0
o=rtp 243 1 IN IP4 192.168.5.138
s=A call
c=IN IP4 1.1.1.1
t=0 0
a=sendonly
m=audio 21366 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=fmtp:18 annexb=no
a=fmtp:101 0-15
m=video 41024 RTP/AVP 34
a=rtpmap:34 H263/90000
a=fmtp:34 QCIF=2

DEBUG | 20081103-113224.468 | TEST UA | RESIP:TRANSACTION | 3152 | TimerQueue.cxx:85 | Adding timer: Timer StaleClient tid=1a13c2433178a747 ms=32000
INFO | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | DialogUsageManager.cxx:1324 | Got: SipResp: 200 tid=1a13c2433178a747 cseq=INVITE contact=201@xxxxxxxxxxxxx:5083 / 1 from(wire)
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | DialogUsageManager.cxx:1832 | DialogUsageManager::processResponse:
 
SipResp: 200 tid=1a13c2433178a747 cseq=INVITE contact=201@xxxxxxxxxxxxx:5083 / 1 from(wire)
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | DialogId.cxx:50 | DialogId::DialogId: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.-ca7c4849-7b530979
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | DialogSet.cxx:791 | mState == 3 Creating a new Dialog from msg:
 
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.5.138:5311;branch=z9hG4bK-d8754z-1a13c2433178a747-1---d8754z-;rport=5311
Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
To: <sip:201@xxxxxxxxxxxx>;tag=7b530979
From: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 1 INVITE
Session-Expires: 600;refresher=uas
Min-SE: 90
Allow: ACK, BYE, CANCEL, INVITE, REGISTER, INFO, REFER, SUBSCRIBE, NOTIFY, MESSAGE, UPDATE, REGISTER
Content-Type: application/sdp
Supported: replaces, timer
Content-Length: 356
 
v=0
o=rtp 243 1 IN IP4 192.168.5.138
s=A call
c=IN IP4 1.1.1.1
t=0 0
a=sendonly
m=audio 21366 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=fmtp:18 annexb=no
a=fmtp:101 0-15
m=video 41024 RTP/AVP 34
a=rtpmap:34 H263/90000
a=fmtp:34 QCIF=2

DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | DialogId.cxx:50 | DialogId::DialogId: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.-ca7c4849-7b530979
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:232 | ************** Created Dialog as UAC **************
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:233 | mRemoteNameAddr: <sip:201@xxxxxxxxxxxx>;tag=7b530979
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:234 | mLocalNameAddr: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:235 | mLocalContact: <sip:Admin>
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:236 | mRemoteTarget: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:239 | Dialog::Dialog MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.-ca7c4849-7b530979
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | DialogSet.cxx:838 | ### Calling CreateAppDialog ###:
 
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.5.138:5311;branch=z9hG4bK-d8754z-1a13c2433178a747-1---d8754z-;rport=5311
Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
To: <sip:201@xxxxxxxxxxxx>;tag=7b530979
From: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 1 INVITE
Session-Expires: 600;refresher=uas
Min-SE: 90
Allow: ACK, BYE, CANCEL, INVITE, REGISTER, INFO, REFER, SUBSCRIBE, NOTIFY, MESSAGE, UPDATE, REGISTER
Content-Type: application/sdp
Supported: replaces, timer
Content-Length: 356
 
v=0
o=rtp 243 1 IN IP4 192.168.5.138
s=A call
c=IN IP4 1.1.1.1
t=0 0
a=sendonly
m=audio 21366 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=fmtp:18 annexb=no
a=fmtp:101 0-15
m=video 41024 RTP/AVP 34
a=rtpmap:34 H263/90000
a=fmtp:34 QCIF=2

DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:370 | Dialog::dispatch: SipResp: 200 tid=1a13c2433178a747 cseq=INVITE contact=201@xxxxxxxxxxxxx:5083 / 1 from(wire)
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | KeepAliveManager.cxx:40 | Association added for [ V4 192.168.5.25:5060 UDP target domain=unspecified mFlowKey=1816 ]
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:640 | Dialog::dispatch  --  Created new client invite sessionSipResp: 200 tid=1a13c2433178a747 cseq=INVITE contact=201@xxxxxxxxxxxxx:5083 / 1 from(wire)
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | InviteSession.cxx:76 | ^^^ InviteSession::InviteSession 0E85D960
DEBUG | 20081103-113224.484 | TEST UA | RESIP | 5432 | SipMessage.cxx:990 | SipMessage::getContents: application/sdp
DEBUG | 20081103-113224.484 | TEST UA | RESIP | 5432 | Helper.cxx:2131 | Got sdp

INFO | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | InviteSession.cxx:2561 | Transition UAC_Start -> InviteSession::Connected
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:1004 | Dialog::makeRequest:
 
ACK sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-6518530a7b2af07a-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:Admin>
To: <sip:201@xxxxxxxxxxxx>;tag=7b530979
From: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 0 ACK
Content-Length: 0
 

DEBUG | 20081103-113224.484 | TEST UA | RESIP:TRANSACTION | 5432 | TimerQueue.cxx:105 | Adding application timer: DumTimeout::CanDiscardAck INVITE: 855 InviteSession::Connected ADDR=<sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849 PEER=<sip:201@xxxxxxxxxxxx>;tag=7b530979: duration=32000 seq=1
INFO | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | InviteSession.cxx:2879 | Sending SipReq:  ACK 201@xxxxxxxxxxxxx:5083 tid=6518530a7b2af07a cseq=ACK contact=Admin / 1 from(tu)
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | DialogUsageManager.cxx:857 | SEND:
 
ACK sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-6518530a7b2af07a-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:Admin>
To: <sip:201@xxxxxxxxxxxx>;tag=7b530979
From: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 1 ACK
User-Agent: My Test UA
Content-Length: 0
 

DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | DialogId.cxx:50 | DialogId::DialogId: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.-ca7c4849-7b530979
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | DialogUsageManager.cxx:990 | Send: SipReq:  ACK 201@xxxxxxxxxxxxx:5083 tid=6518530a7b2af07a cseq=ACK contact=Admin / 1 from(tu)
DEBUG | 20081103-113224.484 | TEST UA | RESIP | 5432 | SipStack.cxx:314 | SEND: SipReq:  ACK 201@xxxxxxxxxxxxx:5083 tid=6518530a7b2af07a cseq=ACK contact=Admin / 1 from(tu)
DEBUG | 20081103-113224.500 | TEST UA | RESIP:TRANSACTION | 3152 | TimerQueue.cxx:85 | Adding timer: Timer Stateless tid=6518530a7b2af07a ms=32000
DEBUG | 20081103-113224.500 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:316 | Looking up dns entries for sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68
DEBUG | 20081103-113224.500 | TEST UA | RESIP:DNS | 3152 | DnsResult.cxx:214 | DnsResult::lookup sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68
DEBUG | 20081103-113224.500 | TEST UA | RESIP:DNS | 3152 | DnsResult.cxx:398 | Numeric result so return immediately: [ V4 192.168.5.138:5083 UDP target domain=192.168.5.138 mFlowKey=0 ]
DEBUG | 20081103-113224.500 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:562 | Looked up source for destination: [ V4 192.168.5.138:5083 UDP target domain=192.168.5.138 mFlowKey=0 ] -> [ V4 127.0.0.1:0 UDP target domain=192.168.5.138 mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20081103-113224.500 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:1107 | findTransportBySource([ V4 127.0.0.1:0 UDP target domain=192.168.5.138 mFlowKey=0 ])
DEBUG | 20081103-113224.500 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:1219 | findTransport (any port, any interface) => Transport: [ V4 0.0.0.0:5311 UDP target domain=unspecified mFlowKey=1816 ]
DEBUG | 20081103-113224.500 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:940 | Transmitting to [ V4 192.168.5.138:5083 UDP target domain=192.168.5.138 mFlowKey=0 ] tlsDomain= via [ V4 127.0.0.1:5311 UDP target domain=192.168.5.138 mFlowKey=0 ]
 
ACK sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68 SIP/2.0
Via: SIP/2.0/UDP 127.0.0.1:5311;branch=z9hG4bK-d8754z-6518530a7b2af07a-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:Admin@xxxxxxxxx:5311>
To: <sip:201@xxxxxxxxxxxx>;tag=7b530979
From: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 1 ACK
User-Agent: My Test UA
Content-Length: 0
 

DEBUG | 20081103-113224.500 | TEST UA | RESIP:TRANSPORT | 3152 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 192.168.5.138:5083 UDP target domain=192.168.5.138 mFlowKey=0 ]
DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSPORT | 3152 | Transport.cxx:287 | incoming from: [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ]
DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSACTION | 3152 | TransactionUser.cxx:66 | Checking if SipReq:  INVITE Admin@xxxxxxxxxxxxx:5311 tid=fd77575b072c9a13 cseq=INVITE contact=201@xxxxxxxxxxxxx:5083 / 2 from(wire) is for me
DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSACTION | 3152 | TransactionUser.cxx:71 | Checking rule...
DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSACTION | 3152 | MessageFilterRule.cxx:42 | Matching rule for:
 
INVITE sip:Admin@xxxxxxxxxxxxx:5311 SIP/2.0
Via: SIP/2.0/UDP 192.168.5.138:5083;branch=z9hG4bK-d8754z-fd77575b072c9a13-1---d8754z-
Max-Forwards: 70
Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 2 INVITE
Session-Expires: 600;refresher=uac
Min-SE: 90
Allow: ACK, BYE, CANCEL, INVITE, REGISTER, INFO, REFER, SUBSCRIBE, NOTIFY, MESSAGE, UPDATE, REGISTER
Content-Type: application/sdp
Supported: replaces, timer
Content-Length: 275
 
v=0
o=rtp 243 2 IN IP4 192.168.5.138
s=session
c=IN IP4 1.1.1.1
b=CT:1000
t=0 0
a=sendonly
m=audio 33344 RTP/AVP 8 0 18 111
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:111 X-nt-inforeq/8000
a=encryption:rejected
a=fmtp:18 annexb=no

DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSACTION | 3152 | TransactionUser.cxx:74 | Match!
DEBUG | 20081103-113224.859 | TEST UA | RESIP | 3152 | Helper.cxx:375 | Helper::makeResponse(SipReq:  INVITE Admin@xxxxxxxxxxxxx:5311 tid=fd77575b072c9a13 cseq=INVITE contact=201@xxxxxxxxxxxxx:5083 / 2 from(wire) code=100 reason=
DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSACTION | 3152 | TimerQueue.cxx:85 | Adding timer: Timer Trying tid=fd77575b072c9a13 ms=80
DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSACTION | 3152 | TransactionState.cxx:2108 | Send to TU: TU: DialogUsageManager size=0
 
INVITE sip:Admin@xxxxxxxxxxxxx:5311 SIP/2.0
Via: SIP/2.0/UDP 192.168.5.138:5083;branch=z9hG4bK-d8754z-fd77575b072c9a13-1---d8754z-
Max-Forwards: 70
Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 2 INVITE
Session-Expires: 600;refresher=uac
Min-SE: 90
Allow: ACK, BYE, CANCEL, INVITE, REGISTER, INFO, REFER, SUBSCRIBE, NOTIFY, MESSAGE, UPDATE, REGISTER
Content-Type: application/sdp
Supported: replaces, timer
Content-Length: 275
 
v=0
o=rtp 243 2 IN IP4 192.168.5.138
s=session
c=IN IP4 1.1.1.1
b=CT:1000
t=0 0
a=sendonly
m=audio 33344 RTP/AVP 8 0 18 111
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:111 X-nt-inforeq/8000
a=encryption:rejected
a=fmtp:18 annexb=no

DEBUG | 20081103-113224.953 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:562 | Looked up source for destination: [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ] -> [ V4 127.0.0.1:0 UDP target domain=unspecified mFlowKey=1816 ] sent-by=192.168.5.138 sent-port=5083
DEBUG | 20081103-113224.953 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:940 | Transmitting to [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ] tlsDomain= via [ V4 127.0.0.1:5311 UDP target domain=unspecified mFlowKey=1816 ]
 
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.5.138:5083;branch=z9hG4bK-d8754z-fd77575b072c9a13-1---d8754z-
To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 2 INVITE
Content-Length: 0
 

DEBUG | 20081103-113224.953 | TEST UA | RESIP:TRANSPORT | 3152 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ]
INFO | 20081103-113250.421 | TEST UA | RESIP:TRANSACTION | 3152 | TuSelector.cxx:70 | Stats message
WARNING | 20081103-113250.421 | TEST UA | RESIP:STATS | 3152 | StatisticsMessage.cxx:152 | RESIP:TRANSACTION
TU summary: 22 TRANSPORT 0 TRANSACTION 0 CLIENTTX 2 SERVERTX 1 TIMERS 4
Transaction summary: reqi 596 reqo 866 rspi 750 rspo 491
Details: INVi 122/S121/F0 INVo 243/S243/F0 ACKi 121 ACKo 243 BYEi 231/S126/F0 BYEo 121/S121/F0 CANi 0/S126/F0 CANo 0/S0/F0 MSGi 0/S0/F0 MSGo 0/S0/F0 OPTi 0/S0/F0 OPTo 0/S0/F0 REGi 0/S0/F0 REGo 16/S16/F0 PUBi 0/S0/F0 PUBo 0/S0/F0 SUBi 1/S0/F1 SUBo 1/S0/F1 NOTi 0/S0/F0 NOTo 242/S242/F0
Retransmissions: INVx 0 BYEx 0 CANx 0 MSGx 0 OPTx 0 REGx 0 finx 0 nonx 0 PUBx 0 SUBx 0 NOTx 0
DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSPORT | 3152 | Transport.cxx:287 | incoming from: [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ]
DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 | TransactionUser.cxx:66 | Checking if SipReq:  BYE Admin@xxxxxxxxxxxxx:5311 tid=277f716e3f07722c cseq=BYE contact=201@xxxxxxxxxxxxx:5083 / 3 from(wire) is for me
DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 | TransactionUser.cxx:71 | Checking rule...
DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 | MessageFilterRule.cxx:42 | Matching rule for:
 
BYE sip:Admin@xxxxxxxxxxxxx:5311 SIP/2.0
Via: SIP/2.0/UDP 192.168.5.138:5083;branch=z9hG4bK-d8754z-277f716e3f07722c-1---d8754z-
Max-Forwards: 70
Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 3 BYE
Reason: SIP;description="StateReInvite"
Content-Length: 0
 

DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 | TransactionUser.cxx:74 | Match!
DEBUG | 20081103-113304.859 | TEST UA | RESIP | 3152 | Helper.cxx:375 | Helper::makeResponse(SipReq:  BYE Admin@xxxxxxxxxxxxx:5311 tid=277f716e3f07722c cseq=BYE contact=201@xxxxxxxxxxxxx:5083 / 3 from(wire) code=100 reason=
DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 | TimerQueue.cxx:85 | Adding timer: Timer Trying tid=277f716e3f07722c ms=3500
DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 | TransactionState.cxx:2108 | Send to TU: TU: DialogUsageManager size=26
 
BYE sip:Admin@xxxxxxxxxxxxx:5311 SIP/2.0
Via: SIP/2.0/UDP 192.168.5.138:5083;branch=z9hG4bK-d8754z-277f716e3f07722c-1---d8754z-
Max-Forwards: 70
Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 3 BYE
Reason: SIP;description="StateReInvite"
Content-Length: 0
 

DEBUG | 20081103-113305.359 | TEST UA | RESIP:TRANSPORT | 3152 | Transport.cxx:287 | incoming from: [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ]
DEBUG | 20081103-113306.359 | TEST UA | RESIP:TRANSPORT | 3152 | Transport.cxx:287 | incoming from: [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ]
DEBUG | 20081103-113308.359 | TEST UA | RESIP:TRANSPORT | 3152 | Transport.cxx:287 | incoming from: [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ]
DEBUG | 20081103-113308.359 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:562 | Looked up source for destination: [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ] -> [ V4 127.0.0.1:0 UDP target domain=unspecified mFlowKey=1816 ] sent-by=192.168.5.138 sent-port=5083
DEBUG | 20081103-113308.359 | TEST UA | RESIP:TRANSPORT | 3152 | TransportSelector.cxx:940 | Transmitting to [ V4 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ] tlsDomain= via [ V4 127.0.0.1:5311 UDP target domain=unspecified mFlowKey=1816 ]
 
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.5.138:5083;branch=z9hG4bK-d8754z-277f716e3f07722c-1---d8754z-
To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 3 BYE
Content-Length: 0
 
 
 


 
On Wed, Nov 19, 2008 at 1:27 AM, Scott Godin <sgodin@xxxxxxxxxxxxxxx> wrote:
Oddly enough I do not see any DUM logs in the log you included - are
you calling dum->process in a timely manner?  If dum does not get
cycles to be able to respond to the BYE - the stack will send out a
100 response.

On Tue, Nov 18, 2008 at 10:19 AM, Karlsson <boost.regex@xxxxxxxxx> wrote:
> Hi Soctt, thanks for your email, yes, I'm using Dum, I was wrote a UA base
> on the Dum,
> about the brach parameter, I will double check my server.
>
>
> On Tue, Nov 18, 2008 at 11:15 PM, Scott Godin <sgodin@xxxxxxxxxxxxxxx>
> wrote:
>>
>> Can you explain your situation in more detail?  Are you using DUM?
>> What is the application that is running ontop of the stack or dum?
>>
>> Note:  The branch parameter in the BYE message does not match the
>> Invite.  This is likely the cause of your issue.
>>
>> Scott
>>
>>
>> On Tue, Nov 18, 2008 at 9:24 AM, Karlsson <boost.regex@xxxxxxxxx> wrote:
>> > Hi, I'm using 1.4.0, and I enctounered a strange issue, the reSIProcate
>> > replied 100 trying for BYE and does not send 200 for BYE
>> >
>> > Please check the Logs below:
>> >
>> >
>> >
>> > DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > TransactionUser.cxx:74 | Match!
>> > DEBUG | 20081103-113224.859 | TEST UA | RESIP | 3152 | Helper.cxx:375 |
>> > Helper::makeResponse(SipReq:  INVITE Admin@xxxxxxxxxxxxx:5311
>> > tid=fd77575b072c9a13 cseq=INVITE contact=201@xxxxxxxxxxxxx:5083 / 2
>> > from(wire) code=100 reason=
>> > DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > TimerQueue.cxx:85 | Adding timer: Timer Trying tid=fd77575b072c9a13
>> > ms=80
>> > DEBUG | 20081103-113224.859 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > TransactionState.cxx:2108 | Send to TU: TU: DialogUsageManager size=0
>> >
>> > INVITE sip:Admin@xxxxxxxxxxxxx:5311 SIP/2.0
>> > Via: SIP/2.0/UDP
>> > 192.168.5.138:5083;branch=z9hG4bK-d8754z-fd77575b072c9a13-1---d8754z-
>> > Max-Forwards: 70
>> > Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
>> > To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
>> > From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
>> > Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
>> > CSeq: 2 INVITE
>> > Session-Expires: 600;refresher=uac
>> > Min-SE: 90
>> > Allow: ACK, BYE, CANCEL, INVITE, REGISTER, INFO, REFER, SUBSCRIBE,
>> > NOTIFY,
>> > MESSAGE, UPDATE, REGISTER
>> > Content-Type: application/sdp
>> > Supported: replaces, timer
>> > Content-Length: 275
>> >
>> > v=0
>> > o=rtp 243 2 IN IP4 192.168.5.138
>> > s=session
>> > c=IN IP4 1.1.1.1
>> > b=CT:1000
>> > t=0 0
>> > a=sendonly
>> > m=audio 33344 RTP/AVP 8 0 18 111
>> > a=rtpmap:8 PCMA/8000
>> > a=rtpmap:0 PCMU/8000
>> > a=rtpmap:18 G729/8000
>> > a=rtpmap:111 X-nt-inforeq/8000
>> > a=encryption:rejected
>> > a=fmtp:18 annexb=no
>> > DEBUG | 20081103-113224.953 | TEST UA | RESIP:TRANSPORT | 3152 |
>> > TransportSelector.cxx:562 | Looked up source for destination: [ V4
>> > 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ] -> [ V4
>> > 127.0.0.1:0 UDP target domain=unspecified mFlowKey=1816 ]
>> > sent-by=192.168.5.138 sent-port=5083
>> > DEBUG | 20081103-113224.953 | TEST UA | RESIP:TRANSPORT | 3152 |
>> > TransportSelector.cxx:940 | Transmitting to [ V4 192.168.5.138:5083 UDP
>> > target domain=unspecified mFlowKey=1816 ] tlsDomain= via [ V4
>> > 127.0.0.1:5311
>> > UDP target domain=unspecified mFlowKey=1816 ]
>> >
>> > SIP/2.0 100 Trying
>> > Via: SIP/2.0/UDP
>> > 192.168.5.138:5083;branch=z9hG4bK-d8754z-fd77575b072c9a13-1---d8754z-
>> > To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
>> > From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
>> > Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
>> > CSeq: 2 INVITE
>> > Content-Length: 0
>> >
>> > DEBUG | 20081103-113224.953 | TEST UA | RESIP:TRANSPORT | 3152 |
>> > Transport.cxx:213 | Adding message to tx buffer to: [ V4
>> > 192.168.5.138:5083
>> > UDP target domain=unspecified mFlowKey=1816 ]
>> > INFO | 20081103-113250.421 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > TuSelector.cxx:70 | Stats message
>> > WARNING | 20081103-113250.421 | TEST UA | RESIP:STATS | 3152 |
>> > StatisticsMessage.cxx:152 | RESIP:TRANSACTION
>> > TU summary: 22 TRANSPORT 0 TRANSACTION 0 CLIENTTX 2 SERVERTX 1 TIMERS 4
>> > Transaction summary: reqi 596 reqo 866 rspi 750 rspo 491
>> > Details: INVi 122/S121/F0 INVo 243/S243/F0 ACKi 121 ACKo 243 BYEi
>> > 231/S126/F0 BYEo 121/S121/F0 CANi 0/S126/F0 CANo 0/S0/F0 MSGi 0/S0/F0
>> > MSGo
>> > 0/S0/F0 OPTi 0/S0/F0 OPTo 0/S0/F0 REGi 0/S0/F0 REGo 16/S16/F0 PUBi
>> > 0/S0/F0
>> > PUBo 0/S0/F0 SUBi 1/S0/F1 SUBo 1/S0/F1 NOTi 0/S0/F0 NOTo 242/S242/F0
>> > Retransmissions: INVx 0 BYEx 0 CANx 0 MSGx 0 OPTx 0 REGx 0 finx 0 nonx 0
>> > PUBx 0 SUBx 0 NOTx 0
>> > DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSPORT | 3152 |
>> > Transport.cxx:287 | incoming from: [ V4 192.168.5.138:5083 UDP target
>> > domain=unspecified mFlowKey=1816 ]
>> > DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > TransactionUser.cxx:66 | Checking if SipReq:  BYE
>> > Admin@xxxxxxxxxxxxx:5311
>> > tid=277f716e3f07722c cseq=BYE contact=201@xxxxxxxxxxxxx:5083 / 3
>> > from(wire)
>> > is for me
>> > DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > TransactionUser.cxx:71 | Checking rule...
>> > DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > MessageFilterRule.cxx:42 | Matching rule for:
>> >
>> > BYE sip:Admin@xxxxxxxxxxxxx:5311 SIP/2.0
>> > Via: SIP/2.0/UDP
>> > 192.168.5.138:5083;branch=z9hG4bK-d8754z-277f716e3f07722c-1---d8754z-
>> > Max-Forwards: 70
>> > Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
>> > To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
>> > From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
>> > Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
>> > CSeq: 3 BYE
>> > Reason: SIP;description="StateReInvite"
>> > Content-Length: 0
>> >
>> > DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > TransactionUser.cxx:74 | Match!
>> > DEBUG | 20081103-113304.859 | TEST UA | RESIP | 3152 | Helper.cxx:375 |
>> > Helper::makeResponse(SipReq:  BYE Admin@xxxxxxxxxxxxx:5311
>> > tid=277f716e3f07722c cseq=BYE contact=201@xxxxxxxxxxxxx:5083 / 3
>> > from(wire)
>> > code=100 reason=
>> > DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > TimerQueue.cxx:85 | Adding timer: Timer Trying tid=277f716e3f07722c
>> > ms=3500
>> > DEBUG | 20081103-113304.859 | TEST UA | RESIP:TRANSACTION | 3152 |
>> > TransactionState.cxx:2108 | Send to TU: TU: DialogUsageManager size=26
>> >
>> > BYE sip:Admin@xxxxxxxxxxxxx:5311 SIP/2.0
>> > Via: SIP/2.0/UDP
>> > 192.168.5.138:5083;branch=z9hG4bK-d8754z-277f716e3f07722c-1---d8754z-
>> > Max-Forwards: 70
>> > Contact: <sip:201@xxxxxxxxxxxxx:5083;rinstance=371636721b176a68>
>> > To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
>> > From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
>> > Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
>> > CSeq: 3 BYE
>> > Reason: SIP;description="StateReInvite"
>> > Content-Length: 0
>> >
>> > DEBUG | 20081103-113305.359 | TEST UA | RESIP:TRANSPORT | 3152 |
>> > Transport.cxx:287 | incoming from: [ V4 192.168.5.138:5083 UDP target
>> > domain=unspecified mFlowKey=1816 ]
>> > DEBUG | 20081103-113306.359 | TEST UA | RESIP:TRANSPORT | 3152 |
>> > Transport.cxx:287 | incoming from: [ V4 192.168.5.138:5083 UDP target
>> > domain=unspecified mFlowKey=1816 ]
>> > DEBUG | 20081103-113308.359 | TEST UA | RESIP:TRANSPORT | 3152 |
>> > Transport.cxx:287 | incoming from: [ V4 192.168.5.138:5083 UDP target
>> > domain=unspecified mFlowKey=1816 ]
>> > DEBUG | 20081103-113308.359 | TEST UA | RESIP:TRANSPORT | 3152 |
>> > TransportSelector.cxx:562 | Looked up source for destination: [ V4
>> > 192.168.5.138:5083 UDP target domain=unspecified mFlowKey=1816 ] -> [ V4
>> > 127.0.0.1:0 UDP target domain=unspecified mFlowKey=1816 ]
>> > sent-by=192.168.5.138 sent-port=5083
>> > DEBUG | 20081103-113308.359 | TEST UA | RESIP:TRANSPORT | 3152 |
>> > TransportSelector.cxx:940 | Transmitting to [ V4 192.168.5.138:5083 UDP
>> > target domain=unspecified mFlowKey=1816 ] tlsDomain= via [ V4
>> > 127.0.0.1:5311
>> > UDP target domain=unspecified mFlowKey=1816 ]
>> >
>> > SIP/2.0 100 Trying
>> > Via: SIP/2.0/UDP
>> > 192.168.5.138:5083;branch=z9hG4bK-d8754z-277f716e3f07722c-1---d8754z-
>> > To: <sip:Admin@xxxxxxxxxxxx>;tag=ca7c4849
>> > From: <sip:201@xxxxxxxxxxxx>;tag=7b530979
>> > Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
>> > CSeq: 3 BYE
>> > Content-Length: 0
>> > _______________________________________________
>> > resiprocate-devel mailing list
>> > resiprocate-devel@xxxxxxxxxxxxxxx
>> > https://list.resiprocate.org/mailman/listinfo/resiprocate-devel
>> >
>
>