[reSIProcate] Bug report

Wed Nov 19 00:03:34 CST 2008

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 at 192.168.5.25 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 at 192.168.5.25 <sip%3A201 at 192.168.5.25> 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 at 192.168.5.138:5311>
To: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>
From: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;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:5060UDP 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 at 192.168.5.138:5083;rinstance=371636721b176a68>
To: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;tag=7b530979
From: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;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 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68>
To: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;tag=7b530979
From: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;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 at 192.168.5.25 <sip%3A201 at 192.168.5.25>
>;tag=7b530979
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 | Dialog.cxx:234 |
mLocalNameAddr: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>
>;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 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68>
To: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;tag=7b530979
From: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;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 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;tag=7b530979
From: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;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 at 192.168.5.25<sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
PEER=<sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;tag=7b530979:
duration=32000 seq=1
INFO | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 |
InviteSession.cxx:2879 | Sending SipReq:  ACK
201 at 192.168.5.138:5083tid=6518530a7b2af07a cseq=ACK contact=Admin / 1
from(tu)
DEBUG | 20081103-113224.484 | TEST UA | RESIP:DUM | 5432 |
DialogUsageManager.cxx:857 | SEND:

ACK sip:201 at 192.168.5.138: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 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;tag=7b530979
From: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;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 at 192.168.5.138:5083tid=6518530a7b2af07a cseq=ACK contact=Admin / 1
from(tu)
DEBUG | 20081103-113224.484 | TEST UA | RESIP | 5432 | SipStack.cxx:314 |
SEND: SipReq:  ACK 201 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68
DEBUG | 20081103-113224.500 | TEST UA | RESIP:DNS | 3152 | DnsResult.cxx:214
| DnsResult::lookup sip:201 at 192.168.5.138: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:5311UDP target domain=
192.168.5.138 mFlowKey=0 ]

ACK sip:201 at 192.168.5.138: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 at 127.0.0.1:5311>
To: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;tag=7b530979
From: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;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:5083UDP 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 at 192.168.5.138:5311 tid=fd77575b072c9a13 cseq=INVITE
contact=201 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68>
To: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;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 at 192.168.5.138:5311tid=fd77575b072c9a13 cseq=INVITE
contact=201 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68>
To: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;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:5311UDP 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 at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;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:5083UDP 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 at 192.168.5.138:5311tid=277f716e3f07722c cseq=BYE
contact=201 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68>
To: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;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 at 192.168.5.138:5311tid=277f716e3f07722c cseq=BYE
contact=201 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68>
To: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;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:5311UDP 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 at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;tag=7b530979
Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
CSeq: 3 BYE
Content-Length: 0

On Wed, Nov 19, 2008 at 1:27 AM, Scott Godin <sgodin at sipspectrum.com> 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 at gmail.com> 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 at sipspectrum.com>
> > 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 at gmail.com>
> 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 at 192.168.5.138:5311
> >> > tid=fd77575b072c9a13 cseq=INVITE contact=201 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68>
> >> > To: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
> >> > From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;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:5083UDP
> >> > 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 at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
> >> > From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;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 at 192.168.5.138:5311
> >> > tid=277f716e3f07722c cseq=BYE contact=201 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68>
> >> > To: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
> >> > From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;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 at 192.168.5.138:5311
> >> > tid=277f716e3f07722c cseq=BYE contact=201 at 192.168.5.138: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 at 192.168.5.138: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 at 192.168.5.138:5083;rinstance=371636721b176a68>
> >> > To: <sip:Admin at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
> >> > From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;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:5083UDP
> >> > 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 at 192.168.5.25 <sip%3AAdmin at 192.168.5.25>>;tag=ca7c4849
> >> > From: <sip:201 at 192.168.5.25 <sip%3A201 at 192.168.5.25>>;tag=7b530979
> >> > Call-ID: MTEwYTVjZWFkYmMxNjQ5MDhmMTJjY2NmNjQ1ZTI1ODc.
> >> > CSeq: 3 BYE
> >> > Content-Length: 0
> >> > _______________________________________________
> >> > resiprocate-devel mailing list
> >> > resiprocate-devel at resiprocate.org
> >> > https://list.resiprocate.org/mailman/listinfo/resiprocate-devel
> >> >
> >
> >
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://list.resiprocate.org/pipermail/resiprocate-devel/attachments/20081119/68298373/attachment.htm>