[reSIProcate-users] BYE not sent right away
Hi,
I'm attaching Resiprocate Log which shows that BYE is not sent right away.
Look for "BYE" in the log and it shows that it takes a while to get sent.
Any guesses why this is happening ???? (I have added addition logs
statements in the code. I'm using Resiproctae 1.4)
Thanks,
Vasanthi
INFO | 20090130-193339.650 | TEMP | RESIP:TRANSPORT | 3086694624 |
UdpTransport.cxx:42 | Creating UDP transport host= port=5060 ipv4=1
INFO | 20090130-193347.029 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193347.029 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write
INFO | 20090130-193347.029 | TEMP | RESIP:DUM | 2885663664 |
DialogUsageManager.cxx:1324 | Got: SipReq: INVITE 123@xxxxxxxxxxxx
tid=-d87543-94405754541faf6c-1--d87543- cseq=INVITE
contact=vasanthi@xxxxxxxxxx:5160 / 1 from(wire)
INFO | 20090130-193347.030 | TEMP | RESIP:DUM | 2885663664 |
InviteSession.cxx:2561 | Transition UAS_Start -> UAS_Offer
INFO | 20090130-193347.030 | TEMP | VAPP-USERAGENT | 2885663664 |
ResipDialogHandler.cxx:93 | onNewSession() - new session created between 123 as
server and vasanthi as client
INFO | 20090130-193347.030 | TEMP | VAPP-USERAGENT | 2885663664 |
ResipDialogHandler.cxx:718 | onOffer() - offer from vasanthi to 123
INFO | 20090130-193347.032 | TEMP | RESIP:DUM | 2885663664 |
ServerInviteSession.cxx:131 | UAS_Offer: provisional(100)
INFO | 20090130-193347.032 | TEMP | RESIP:DUM | 2885663664 |
InviteSession.cxx:2561 | Transition UAS_Offer -> UAS_EarlyOffer
INFO | 20090130-193347.032 | TEMP | RESIP:TRANSACTION | 2885663664 |
TransactionController.cxx:120 | TransactionController::send
INFO | 20090130-193347.071 | TEMP | RESIP:DUM | 2885663664 |
ServerInviteSession.cxx:321 | UAS_EarlyOffer: provideAnswer
INFO | 20090130-193347.071 | TEMP | RESIP:DUM | 2885663664 |
InviteSession.cxx:2561 | Transition UAS_EarlyOffer -> UAS_EarlyProvidedAnswer
INFO | 20090130-193347.071 | TEMP | RESIP:DUM | 2885663664 |
ServerInviteSession.cxx:131 | UAS_EarlyProvidedAnswer: provisional(180)
INFO | 20090130-193347.071 | TEMP | RESIP:DUM | 2885663664 |
InviteSession.cxx:2561 | Transition UAS_EarlyProvidedAnswer ->
UAS_EarlyProvidedAnswer
INFO | 20090130-193347.072 | TEMP | RESIP:TRANSACTION | 2885663664 |
TransactionController.cxx:120 | TransactionController::send
INFO | 20090130-193347.072 | TEMP | RESIP:DUM | 2885663664 |
ServerInviteSession.cxx:513 | UAS_EarlyProvidedAnswer: accept(200)
INFO | 20090130-193347.072 | TEMP | RESIP:DUM | 2885663664 |
InviteSession.cxx:2561 | Transition UAS_EarlyProvidedAnswer -> UAS_Accepted
INFO | 20090130-193347.072 | TEMP | RESIP:TRANSACTION | 2885663664 |
TransactionController.cxx:120 | TransactionController::send
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:84 | Sent: SIP/2.0 100 Trying
Via: SIP/2.0/UDP
10.1.80.40:5160;branch=z9hG4bK-d87543-94405754541faf6c-1--d87543-;rport=5160
To: "123@xxxxxxxxxxxx"<sip:123@xxxxxxxxxxxx>;tag=540e7775
From: "vasanthi"<sip:vasanthi@vasanthi>;tag=1c6d532d
Call-ID: NGFiOGU3Nzg0M2FmYWFjMDk1MzBmY2U3YzczYmNkMzY.
CSeq: 1 INVITE
User-Agent: Vapps conference server
Content-Length: 0
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:85 | Sending message on udp.
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:84 | Sent: SIP/2.0 180 Ringing
Via: SIP/2.0/UDP
10.1.80.40:5160;branch=z9hG4bK-d87543-94405754541faf6c-1--d87543-;rport=5160
Contact: <sip:123@xxxxxxxxxxxx:5060>
To: "123@xxxxxxxxxxxx"<sip:123@xxxxxxxxxxxx>;tag=540e7775
From: "vasanthi"<sip:vasanthi@vasanthi>;tag=1c6d532d
Call-ID: NGFiOGU3Nzg0M2FmYWFjMDk1MzBmY2U3YzczYmNkMzY.
CSeq: 1 INVITE
Content-Type: application/sdp
User-Agent: Vapps conference server
Content-Length: 239
v=0
o=vapp0 3442332827 3442332827 IN IP4 192.168.1.22
s=VAPP_SESSION0
c=IN IP4 192.168.1.22
t=3442332827 0
m=audio 16328 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11
a=sendrecv
a=ptime:20
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:85 | Sending message on udp.
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:84 | Sent: SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.1.80.40:5160;branch=z9hG4bK-d87543-94405754541faf6c-1--d87543-;rport=5160
Contact: <sip:123@xxxxxxxxxxxx:5060>
To: "123@xxxxxxxxxxxx"<sip:123@xxxxxxxxxxxx>;tag=540e7775
From: "vasanthi"<sip:vasanthi@vasanthi>;tag=1c6d532d
Call-ID: NGFiOGU3Nzg0M2FmYWFjMDk1MzBmY2U3YzczYmNkMzY.
CSeq: 1 INVITE
Session-Expires: 1800;refresher=uas
Min-SE: 90
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, INFO
Content-Type: application/sdp
Supported: timer
User-Agent: Vapps conference server
Content-Length: 239
v=0
o=vapp0 3442332827 3442332827 IN IP4 192.168.1.22
s=VAPP_SESSION0
c=IN IP4 192.168.1.22
t=3442332827 0
m=audio 16328 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11
a=sendrecv
a=ptime:20
INFO | 20090130-193347.110 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:85 | Sending message on udp.
INFO | 20090130-193347.320 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193347.320 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write
INFO | 20090130-193347.320 | TEMP | RESIP:DUM | 2885663664 |
DialogUsageManager.cxx:1324 | Got: SipReq: ACK 123@xxxxxxxxxxxx:5060
tid=-d87543-a80c0341ef07ee7d-1--d87543- cseq=ACK
contact=vasanthi@xxxxxxxxxx:5160 / 1 from(wire)
INFO | 20090130-193347.320 | TEMP | RESIP:DUM | 2885663664 |
DialogUsageManager.cxx:1702 | Handling in-dialog request: SipReq: ACK
123@xxxxxxxxxxxx:5060 tid=-d87543-a80c0341ef07ee7d-1--d87543- cseq=ACK
contact=vasanthi@xxxxxxxxxx:5160 / 1 from(wire)
INFO | 20090130-193347.320 | TEMP | RESIP:DUM | 2885663664 |
ServerInviteSession.cxx:797 | dispatchAccepted: SipReq: ACK
123@xxxxxxxxxxxx:5060 tid=-d87543-a80c0341ef07ee7d-1--d87543- cseq=ACK
contact=vasanthi@xxxxxxxxxx:5160 / 1 from(wire)
INFO | 20090130-193347.320 | TEMP | RESIP:DUM | 2885663664 |
InviteSession.cxx:2561 | Transition UAS_Accepted -> InviteSession::Connected
INFO | 20090130-193347.572 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193347.572 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write
INFO | 20090130-193351.978 | TEMP | RESIP:DUM | 2885663664 |
ServerInviteSession.cxx:389 | InviteSession::Connected: end
INFO | 20090130-193351.978 | TEMP | RESIP:DUM | 2885663664 |
InviteSession.cxx:2901 | "123@xxxxxxxxxxxx"<sip:123@xxxxxxxxxxxx>;tag=540e7775
Sending BYE User Hung Up
INFO | 20090130-193351.978 | TEMP | RESIP:TRANSACTION | 2885663664 |
TransactionController.cxx:120 | TransactionController::send
INFO | 20090130-193351.978 | TEMP | RESIP:DUM | 2885663664 |
InviteSession.cxx:2561 | Transition InviteSession::Connected ->
InviteSession::Terminated
WARNING | 20090130-193351.978 | TEMP | VAPP-USERAGENT | 2885663664 |
ResipDialogHandler.cxx:565 | 123 call terminated with vasanthi reason=3
INFO | 20090130-193417.217 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193417.218 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write
INFO | 20090130-193417.218 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193417.218 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:84 | Sent: BYE sip:vasanthi@xxxxxxxxxx:5160 SIP/2.0
Via: SIP/2.0/UDP
192.168.1.22:5060;branch=z9hG4bK-d8754z-eda4c22a4222f875-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:123@xxxxxxxxxxxx:5060>
To: "vasanthi"<sip:vasanthi@vasanthi>;tag=1c6d532d
From: "123@xxxxxxxxxxxx"<sip:123@xxxxxxxxxxxx>;tag=540e7775
Call-ID: NGFiOGU3Nzg0M2FmYWFjMDk1MzBmY2U3YzczYmNkMzY.
CSeq: 2 BYE
User-Agent: Vapps conference server
Reason: SIP;description="User Hung Up"
Content-Length: 0
INFO | 20090130-193417.218 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:85 | Sending message on udp.
INFO | 20090130-193417.322 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193417.322 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write
INFO | 20090130-193417.322 | TEMP | RESIP:DUM | 2885663664 |
DialogUsageManager.cxx:1324 | Got: SipResp: 200 tid=eda4c22a4222f875 cseq=BYE
contact=vasanthi@xxxxxxxxxx:5160 / 2 from(wire)
INFO | 20090130-193417.322 | TEMP | RESIP:DUM | 2885663664 |
InviteSession.cxx:1878 | InviteSession::dispatchTerminated SipResp: 200
tid=eda4c22a4222f875 cseq=BYE contact=vasanthi@xxxxxxxxxx:5160 / 2 from(wire)
INFO | 20090130-193417.718 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193417.718 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write
INFO | 20090130-193419.072 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193419.072 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write
INFO | 20090130-193419.110 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193419.110 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write
INFO | 20090130-193422.322 | TEMP | RESIP:TRANSACTION | 2896153520 |
TransactionController.cxx:85 | TransactionController::process(FdSet& fdset)
INFO | 20090130-193422.322 | TEMP | RESIP:TRANSPORT | 2896153520 |
UdpTransport.cxx:143 | No message available or not ready to write