[reSIProcate] On Tarball release 0.5.0

Thu Nov 3 01:57:20 CST 2005

Hi,

	I don't know if this is the correct place to ask this question.  Can anyone
of you people who are doing the version control of Resip tell me as to when
is the next main release of Resip targeted ? I remember the last one being
on 7th July, 2005.  And was is the difference between Tarball release and
branch release?  Can 0.5.0 be considered a main release?  I think my
question is whats the difference between tarball and branch release ?

- best regards
Asheesh.

-----Original Message-----
From: resiprocate-devel-bounces at list.sipfoundry.org
[mailto:resiprocate-devel-bounces at list.sipfoundry.org]On Behalf Of Clemens
Fischer (VI/SEA)
Sent: Thursday, November 03, 2005 12:06 PM
To: resiprocate-devel at list.sipfoundry.org
Subject: [reSIProcate] resiprocate 0.9.0 - UAC problem

Hi Gurus,

I`m using resiprocate 0.9.0 and encountered a strange problem running
under Windows. Acting as UAS everything works quite well and I can make
hundreds of calls, but acting as UAC the first call is working and the
second attempts somehow (dead)locks the stack (find the complete trace
below).

First call attempt:

[snip]

DEBUG | 20051102-075742.891 | diogenes | RESIP | 1692 | SipStack.cxx:269
| SEND: SipReq:  INVITE 2460 at 159.107.113.163:2051 tid=f16d4916bb41f15a
cseq=INVITE contact=2401 / 1 from(tu)
DEBUG | 20051102-075742.901 | diogenes | RESIP:TRANSACTION | 2784 |
TimerQueue.cxx:85 | Adding timer: Timer B tid=f16d4916bb41f15a ms=32000
DEBUG | 20051102-075742.931 | diogenes | RESIP:TRANSPORT | 2784 |
TransportSelector.cxx:243 | Looking up dns entries for
sip:2460 at 159.107.113.163:2051;line=4y5sxtis
DEBUG | 20051102-075742.931 | diogenes | RESIP:DNS | 2784 |
DnsResult.cxx:136 | DnsResult::lookup
sip:2460 at 159.107.113.163:2051;line=4y5sxtis
DEBUG | 20051102-075742.941 | diogenes | RESIP:DNS | 2784 |
DnsResult.cxx:255 | Numeric result so return immediately: [ V4
159.107.113.163:2051 UDP connectionId=0 ]
DEBUG | 20051102-075742.941 | diogenes | RESIP:TRANSACTION | 2784 |
TimerQueue.cxx:85 | Adding timer: Timer A tid=f16d4916bb41f15a ms=500
DEBUG | 20051102-075742.941 | diogenes | RESIP:TRANSPORT | 2784 |
TransportSelector.cxx:514 | Looked up source for destination: [ V4
159.107.113.163:2051 UDP connectionId=0 ] -> [ V4 159.107.111.157:0 UDP
connectionId=0 ] sent-by= sent-port=0
DEBUG | 20051102-075742.941 | diogenes | RESIP:TRANSPORT | 2784 |
TransportSelector.cxx:740 | findTransport([ V4 159.107.111.157:0 UDP
connectionId=0 ])
DEBUG | 20051102-075742.941 | diogenes | RESIP:TRANSPORT | 2784 |
TransportSelector.cxx:782 | findTransport (any port, any interface) =>
Transport: [ V4 0.0.0.0:5060 UDP connectionId=0 ]
DEBUG | 20051102-075742.941 | diogenes | RESIP:TRANSPORT | 2784 |
TransportSelector.cxx:620 | !sipit! Populated Contact:
<sip:2401 at 159.107.111.157:5060>

[snip: INVITE sent !!]

Second attempt:

[snip]

DEBUG | 20051102-075755.189 | diogenes | RESIP | 1692 | SipStack.cxx:269
| SEND: SipReq:  INVITE 2460 at 159.107.113.163:2051
tid=f16d4916bb41f15a cseq=INVITE contact=2401 / 1 from(tu)

[snip: thats all, no timers added no debug messages from transport
selector, etc .... !!]

I've checked my UA implementation and I'm almost sure (any hints how to
check again) that my code is not (dead-)locking the stack. Some already
had a similar problem or any hints what could cause this behaviour ?

Regards,
// Clemens

*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~
*~*~*~*~*

INFO | 20051102-075638.008 | diogenes | RESIP:TRANSPORT | 1116 |
UdpTransport.cxx:30 | Creating UDP transport host= port=5060 ipv4=1
DEBUG | 20051102-075638.018 | diogenes | RESIP:TRANSPORT | 1116 |
InternalTransport.cxx:87 | Creating fd=2536 V4/UDP
DEBUG | 20051102-075638.018 | diogenes | RESIP:TRANSPORT | 1116 |
InternalTransport.cxx:95 | Binding to 0.0.0.0
DEBUG | 20051102-075638.048 | diogenes | RESIP:TRANSPORT | 1116 |
ConnectionBase.cxx:29 | ConnectionBase::ConnectionBase, no params:
016C9A98
DEBUG | 20051102-075638.048 | diogenes | RESIP:TRANSPORT | 1116 |
InternalTransport.cxx:87 | Creating fd=2504 V4/TCP
DEBUG | 20051102-075638.048 | diogenes | RESIP:TRANSPORT | 1116 |
InternalTransport.cxx:95 | Binding to 0.0.0.0
INFO | 20051102-075638.048 | diogenes | RESIP:TRANSPORT | 1116 |
TcpTransport.cxx:27 | Creating TCP transport host= port=5060 ipv4=1
DEBUG | 20051102-075729.642 | diogenes | RESIP:TRANSPORT | 2784 |
Transport.cxx:209 | incoming from: [ V4 159.107.113.163:2051 UDP
received on: Transport: [ V4 0.0.0.0:5060 UDP connectionId=0 ]
connectionId=0 ]
DEBUG | 20051102-075729.652 | diogenes | RESIP:TRANSACTION | 2784 |
TransactionUser.cxx:56 | Checking if SipReq:  REGISTER 159.107.111.157
tid=-q8h9sgokbup5 cseq=REGISTER contact=2460 at 159.107.113.163:2051 / 45
from(wire) is for me
DEBUG | 20051102-075729.662 | diogenes | RESIP:TRANSACTION | 2784 |
TransactionUser.cxx:61 | Checking rule...
DEBUG | 20051102-075729.662 | diogenes | RESIP:TRANSACTION | 2784 |
MessageFilterRule.cxx:42 | Matching rule for REGISTER
sip:159.107.111.157 SIP/2.0

To: "2460" <sip:2460 at 159.107.111.157>

From: "2460" <sip:2460 at 159.107.111.157>;tag=3hh342yv6r

Via: SIP/2.0/UDP
159.107.113.163:2051;branch=z9hG4bK-q8h9sgokbup5;rport=2051

Call-ID: 3c26700be09c-qlkhmm9e90gt at snom190

CSeq: 45 REGISTER

Contact:
<sip:2460 at 159.107.113.163:2051;line=4y5sxtis>;q=1;+sip.instance="<urn:uu
id:4a45039b-1c3c-4572-9dbb-25ed35cf6586>";mobility="fixed";description="
snom190";actor="principal";events="dialog";methods="INVITE,ACK,CANCEL,BY
E,REFER,OPTIONS,NOTIFY,SUBSCRIBE,PRACK,MESSAGE,INFO";audio;duplex="full"

Expires: 60

Max-Forwards: 70

Supported: gruu

User-Agent: snom190/3.60k

Allow-Events: dialog

Content-Length: 0

X-Real-IP: 159.107.113.163

WWW-Contact: <http://159.107.113.163:80 <http://159.107.113.163/> >,
<https://159.107.113.163:443 <https://159.107.113.163/> >

DEBUG | 20051102-075729.662 | diogenes | RESIP:TRANSACTION | 2784 |
TransactionUser.cxx:64 | Match!
DEBUG | 20051102-075729.662 | diogenes | RESIP:TRANSACTION | 2784 |
TransactionState.cxx:1535 | Send to TU: TU: DialogUsageManager size=0
REGISTER sip:159.107.111.157 SIP/2.0

To: "2460" <sip:2460 at 159.107.111.157>

From: "2460" <sip:2460 at 159.107.111.157>;tag=3hh342yv6r

Via: SIP/2.0/UDP
159.107.113.163:2051;branch=z9hG4bK-q8h9sgokbup5;rport=2051

Call-ID: 3c26700be09c-qlkhmm9e90gt at snom190

CSeq: 45 REGISTER

Contact:
<sip:2460 at 159.107.113.163:2051;line=4y5sxtis>;q=1;+sip.instance="<urn:uu
id:4a45039b-1c3c-4572-9dbb-25ed35cf6586>";mobility="fixed";description="
snom190";actor="principal";events="dialog";methods="INVITE,ACK,CANCEL,BY
E,REFER,OPTIONS,NOTIFY,SUBSCRIBE,PRACK,MESSAGE,INFO";audio;duplex="full"

Expires: 60

Max-Forwards: 70

Supported: gruu

User-Agent: snom190/3.60k

Allow-Events: dialog

Content-Length: 0

X-Real-IP: 159.107.113.163

WWW-Contact: <http://159.107.113.163:80 <http://159.107.113.163/> >,
<https://159.107.113.163:443 <https://159.107.113.163/> >

INFO | 20051102-075729.672 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:830 | Got: SipReq:  REGISTER 159.107.111.157
tid=-q8h9sgokbup5 cseq=REGISTER contact=2460 at 159.107.113.163:2051 / 45
from(wire)
DEBUG | 20051102-075729.672 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1212 | DialogUsageManager::processRequest:
SipReq:  REGISTER 159.107.111.157 tid=-q8h9sgokbup5 cseq=REGISTER
contact=2460 at 159.107.113.163:2051 / 45 from(wire)
DEBUG | 20051102-075729.672 | diogenes | RESIP:DUM | 2388 |
DialogSetId.cxx:31 | ********** Generated Local Tag ***********
DEBUG | 20051102-075729.672 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1493 | Looking for dialogSet:
3c26700be09c-qlkhmm9e90gt at snom190-e0541b12 in map:
DEBUG | 20051102-075729.672 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1494 | []
DEBUG | 20051102-075729.672 | diogenes | RESIP:DUM | 2388 |
DialogSetId.cxx:31 | ********** Generated Local Tag ***********
DEBUG | 20051102-075729.682 | diogenes | RESIP:DUM | 2388 |
DialogSetId.cxx:31 | ********** Generated Local Tag ***********
DEBUG | 20051102-075729.702 | diogenes | RESIP:DUM | 2388 |
DialogSet.cxx:81 |  ************* Created DialogSet(UAS)  --
3c26700be09c-qlkhmm9e90gt at snom190-737c304b*************
DEBUG | 20051102-075729.702 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1342 | *********** Calling AppDialogSetFactory
*************
DEBUG | 20051102-075729.702 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1348 | ************* Adding DialogSet
***************
DEBUG | 20051102-075729.702 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1349 | Before: []
DEBUG | 20051102-075729.702 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1351 | After:
Req[3c26700be09c-qlkhmm9e90gt at snom190-737c304b -> 016CAC90]
DEBUG | 20051102-075729.702 | diogenes | RESIP:DUM | 2388 |
DialogSetId.cxx:31 | ********** Generated Local Tag ***********
DEBUG | 20051102-075729.702 | diogenes | RESIP:DUM | 2388 |
DialogId.cxx:50 | DialogId::DialogId:
3c26700be09c-qlkhmm9e90gt at snom190-da6eb04b-3hh342yv6r
DEBUG | 20051102-075729.702 | diogenes | RESIP:DUM | 2388 |
DialogSet.cxx:665 | findDialog:
3c26700be09c-qlkhmm9e90gt at snom190-da6eb04b-3hh342yv6r in []
DEBUG | 20051102-075729.712 | diogenes | RESIP:DUM | 2388 |
ServerRegistration.cxx:97 | got a registration
DEBUG | 20051102-075729.712 | diogenes | RESIP | 2388 | Helper.cxx:296 |
Helper::makeResponse(SipReq:  REGISTER 159.107.111.157 tid=-q8h9sgokbup5
cseq=REGISTER contact=2460 at 159.107.113.163:2051 / 45 from(wire) code=200
reason=
INFO | 20051102-075729.712 | diogenes | RESIP:DUM | 2388 |
ServerRegistration.cxx:39 | accepted a registration
sip:2460 at 159.107.111.157
DEBUG | 20051102-075729.712 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1493 | Looking for dialogSet:
3c26700be09c-qlkhmm9e90gt at snom190-656def64 in map:
DEBUG | 20051102-075729.712 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1494 |
[3c26700be09c-qlkhmm9e90gt at snom190-737c304b -> 016CAC90]
DEBUG | 20051102-075729.712 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:607 | SEND: SIP/2.0 200 OK

To: "2460"<sip:2460 at 159.107.111.157>;tag=656def64

From: "2460"<sip:2460 at 159.107.111.157>;tag=3hh342yv6r

Via: SIP/2.0/UDP
159.107.113.163:2051;branch=z9hG4bK-q8h9sgokbup5;rport=2051

Call-ID: 3c26700be09c-qlkhmm9e90gt at snom190

CSeq: 45 REGISTER

Contact: <sip:2460 at 159.107.113.163:2051;line=4y5sxtis>;expires=60

Content-Length: 0

DEBUG | 20051102-075729.712 | diogenes | RESIP | 2388 | SipStack.cxx:269
| SEND: SipResp: 200 tid=-q8h9sgokbup5 cseq=REGISTER
contact=2460 at 159.107.113.163:2051 / 45 from(tu)
INFO | 20051102-075729.722 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:830 | Got: DestroyDialogSet
3c26700be09c-qlkhmm9e90gt at snom190-737c304b
DEBUG | 20051102-075729.732 | diogenes | RESIP:TRANSACTION | 2784 |
TimerQueue.cxx:85 | Adding timer: Timer J tid=-q8h9sgokbup5 ms=32000
DEBUG | 20051102-075729.742 | diogenes | RESIP:TRANSPORT | 2784 |
TransportSelector.cxx:514 | Looked up source for destination: [ V4
159.107.113.163:2051 UDP received on: Transport: [ V4 0.0.0.0:5060 UDP
connectionId=0 ] connectionId=0 ] -> [ V4 159.107.111.157:2051 UDP
connectionId=0 ] sent-by=159.107.113.163 sent-port=2051
DEBUG | 20051102-075729.742 | diogenes | RESIP:TRANSPORT | 2784 |
TransportSelector.cxx:658 | Transmitting to [ V4 159.107.113.163:2051
UDP received on: Transport: [ V4 0.0.0.0:5060 UDP connectionId=0 ]
connectionId=0 ] via [ V4 159.107.111.157:2051 UDP connectionId=0
]SIP/2.0 200 OK

To: "2460"<sip:2460 at 159.107.111.157>;tag=656def64

From: "2460"<sip:2460 at 159.107.111.157>;tag=3hh342yv6r

Via: SIP/2.0/UDP
159.107.113.163:2051;branch=z9hG4bK-q8h9sgokbup5;rport=2051

Call-ID: 3c26700be09c-qlkhmm9e90gt at snom190

CSeq: 45 REGISTER

Contact: <sip:2460 at 159.107.113.163:2051;line=4y5sxtis>;expires=60

Content-Length: 0

DEBUG | 20051102-075729.742 | diogenes | RESIP:TRANSPORT | 2784 |
Transport.cxx:160 | Adding message to tx buffer to: [ V4
159.107.113.163:2051 UDP received on: Transport: [ V4 0.0.0.0:5060 UDP
connectionId=0 ] connectionId=0 ]
DEBUG | 20051102-075729.742 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:934 | Destroying usageDestroyDialogSet
3c26700be09c-qlkhmm9e90gt at snom190-737c304b
DEBUG | 20051102-075729.742 | diogenes | RESIP:DUM | 2388 |
DialogSet.cxx:119 |  ********** DialogSet::~DialogSet:
3c26700be09c-qlkhmm9e90gt at snom190-737c304b*************
DEBUG | 20051102-075729.742 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1524 | ************* Removing DialogSet
***************
DEBUG | 20051102-075729.742 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1525 | Before:
[3c26700be09c-qlkhmm9e90gt at snom190-737c304b -> 016CAC90]
DEBUG | 20051102-075729.742 | diogenes | RESIP:DUM | 2388 |
DialogUsageManager.cxx:1527 | After: []
INFO | 20051102-075737.884 | diogenes | RESIP:TRANSACTION | 2784 |
TuSelector.cxx:69 | Stats message
WARNING | 20051102-075737.884 | diogenes | RESIP:STATS | 2784 |
StatisticsMessage.cxx:152 | RESIP:TRANSACTION
TU summary: 0 TRANSPORT 0 TRANSACTION 0 CLIENTTX 0 SERVERTX 1 TIMERS 1
Transaction summary: reqi 1 reqo 0 rspi 0 rspo 1
Details: INVi 0/S0/F0 INVo 0/S0/F0 ACKi 0 ACKo 0 BYEi 0/S0/F0 BYEo
0/S0/F0 CANi 0/S0/F0 CANo 0/S0/F0 MSGi 0/S0/F0 MSGo 0/S0/F0 OPTi 0/S0/F0
OPTo 0/S0/F0 REGi 1/S1/F0 REGo 0/S0/F0 PUBi 0/S0/F0 PUBo 0/S0/F0 SUBi
0/S0/F0 SUBo 0/S0/F0 NOTi 0/S0/F0 NOTo 0/S0/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 | 20051102-075742.891 | diogenes | RESIP:DUM | 1692 |
BaseCreator.cxx:90 | BaseCreator::makeInitialRequest: INVITE
sip:2460 at 159.107.111.157 SIP/2.0

To: "2460"<sip:2460 at 159.107.111.157>

From: "2401"<sip:2401 at 159.107.111.157>;tag=23482900

Via: SIP/2.0/ ;branch=z9hG4bK-d87543-ae72d62c905f5269-1--d87543-;rport