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

[reSIProcate-users] help with resiprocate log


Hi,

I'm trying to understand the log of an attempted call from Resiprocate to a Cisco device. Attached is the wireshark capture of the call and the corresponding Resiprocate log file (unfortunately its only at the info level - I can try and reproduce it at a higher level if necessary.

Anyway, there are two main things that puzzle me in the Resiprocate log file:

The first is that following the "180 Ringing" message from Cisco, the log shows:
"Cannot create a dialog, no Contact or To tag in 1xx."

Which is strange since in the Wireshark capture it can be seen that the To tag does exist in the 180 message.

The next thing that I found strange is the fact that although from the log file it seems as though an ACK is sent back to the Cisco:

"InviteSession.cxx:2817 | Sending SipReq:  ACK 102@xxxxxxxxxxxxxxx:5060"

There is no indication that this actually happens from looking at the Wireshark capture. There might be other issues here as well but I find these two the most puzzling. Any help would be appreciated.

The third file is a capture of the same call using SJPhone to Cisco in which there seems to be no problem

Thanks,
Amnon




Attachment: SJphoneToCisco.pcap
Description: Binary data

Attachment: resip2cisco.pcap
Description: Binary data

INFO | 20080306-154359.333 | Resiprogate | RESIP:TRANSPORT | 716 | 
UdpTransport.cxx:42 | Creating UDP transport host= port=5060 ipv4=1
INFO | 20080306-154459.309 | Resiprogate | RESIP:TRANSACTION | 3736 | 
TuSelector.cxx:70 | Stats message 
WARNING | 20080306-154459.309 | Resiprogate | RESIP:STATS | 3736 | 
StatisticsMessage.cxx:152 | RESIP:TRANSACTION
TU summary: 0 TRANSPORT 0 TRANSACTION 0 CLIENTTX 0 SERVERTX 0 TIMERS 0
Transaction summary: reqi 0 reqo 0 rspi 0 rspo 0
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 
0/S0/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
INFO | 20080306-154559.326 | Resiprogate | RESIP:TRANSACTION | 3736 | 
TuSelector.cxx:70 | Stats message 
WARNING | 20080306-154559.326 | Resiprogate | RESIP:STATS | 3736 | 
StatisticsMessage.cxx:152 | RESIP:TRANSACTION
TU summary: 0 TRANSPORT 0 TRANSACTION 0 CLIENTTX 0 SERVERTX 0 TIMERS 0
Transaction summary: reqi 0 reqo 0 rspi 0 rspo 0
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 
0/S0/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
INFO | 20080306-154631.482 | Resiprogate | RESIP:DNS | 776 | DnsUtil.cxx:145 | 
local hostname does not contain a domain part hypergateway499
INFO | 20080306-154631.592 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 100 tid=8b1a0f66fc49052c 
cseq=INVITE / 1 from(wire)
INFO | 20080306-154631.592 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 180 tid=8b1a0f66fc49052c 
cseq=INVITE / 1 from(wire)
INFO | 20080306-154631.592 | Resiprogate | RESIP:DUM | 716 | DialogSet.cxx:751 
| Cannot create a dialog, no Contact or To tag in 1xx.
INFO | 20080306-154634.897 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 200 tid=8b1a0f66fc49052c 
cseq=INVITE contact=102@xxxxxxxxxxxxxxx:5060 / 1 from(wire)
INFO | 20080306-154634.917 | Resiprogate | RESIP:DUM | 716 | 
InviteSession.cxx:2499 | Transition UAC_Start -> InviteSession::Connected
INFO | 20080306-154634.917 | Resiprogate | RESIP:DUM | 716 | 
InviteSession.cxx:2817 | Sending SipReq:  ACK 102@xxxxxxxxxxxxxxx:5060 
tid=6c5042173e5a4c4a cseq=ACK contact=HG4000@xxxxxxxxxxxxxxx / 1 from(tu)
INFO | 20080306-154635.387 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 200 tid=8b1a0f66fc49052c 
cseq=INVITE contact=102@xxxxxxxxxxxxxxx:5060 / 1 from(wire)
INFO | 20080306-154636.349 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 200 tid=8b1a0f66fc49052c 
cseq=INVITE contact=102@xxxxxxxxxxxxxxx:5060 / 1 from(wire)
INFO | 20080306-154638.362 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 200 tid=8b1a0f66fc49052c 
cseq=INVITE contact=102@xxxxxxxxxxxxxxx:5060 / 1 from(wire)
ERR | 20080306-154639.003 | Resiprogate | RESIP:DNS | 3736 | DnsStub.cxx:450 | 
Unknown error Timeout while contacting DNS servers for 
INFO | 20080306-154639.003 | Resiprogate | RESIP:TRANSACTION | 3736 | 
TransactionState.cxx:1376 | Ran out of dns entries for . Send 503
INFO | 20080306-154639.013 | Resiprogate | RESIP:DNS | 3736 | DnsUtil.cxx:145 | 
local hostname does not contain a domain part hypergateway499
INFO | 20080306-154639.063 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 503 tid=6c5042173e5a4c4a cseq=ACK / 
1 from(wire)
INFO | 20080306-154640.375 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 200 tid=8b1a0f66fc49052c 
cseq=INVITE contact=102@xxxxxxxxxxxxxxx:5060 / 1 from(wire)
INFO | 20080306-154642.397 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 200 tid=8b1a0f66fc49052c 
cseq=INVITE contact=102@xxxxxxxxxxxxxxx:5060 / 1 from(wire)
INFO | 20080306-154644.360 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 200 tid=8b1a0f66fc49052c 
cseq=INVITE contact=102@xxxxxxxxxxxxxxx:5060 / 1 from(wire)
INFO | 20080306-154646.363 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipResp: 200 tid=8b1a0f66fc49052c 
cseq=INVITE contact=102@xxxxxxxxxxxxxxx:5060 / 1 from(wire)
INFO | 20080306-154648.396 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1297 | Got: SipReq:  BYE HG4000@xxxxxxxxxxxxxxx 
tid=a5cb12501ccce455406f61840ed63414 cseq=BYE / 1 from(wire)
INFO | 20080306-154648.396 | Resiprogate | RESIP:DUM | 716 | 
DialogUsageManager.cxx:1675 | Handling in-dialog request: SipReq:  BYE 
HG4000@xxxxxxxxxxxxxxx tid=a5cb12501ccce455406f61840ed63414 cseq=BYE / 1 
from(wire)
INFO | 20080306-154648.396 | Resiprogate | RESIP:DUM | 716 | 
InviteSession.cxx:1959 | Received SipReq:  BYE HG4000@xxxxxxxxxxxxxxx 
tid=a5cb12501ccce455406f61840ed63414 cseq=BYE / 1 from(wire)
INFO | 20080306-154648.396 | Resiprogate | RESIP:DUM | 716 | 
InviteSession.cxx:2499 | Transition InviteSession::Connected -> 
InviteSession::Terminated
INFO | 20080306-154659.332 | Resiprogate | RESIP:TRANSACTION | 3736 | 
TuSelector.cxx:70 | Stats message 
WARNING | 20080306-154659.332 | Resiprogate | RESIP:STATS | 3736 | 
StatisticsMessage.cxx:152 | RESIP:TRANSACTION
TU summary: 0 TRANSPORT 0 TRANSACTION 0 CLIENTTX 2 SERVERTX 1 TIMERS 4
Transaction summary: reqi 1 reqo 9 rspi 10 rspo 1