Вот кусок лога в момент когда был разрыв.
600-тые номера это внутренние. 10000-ые это аккаунты на аналоговом шлюзе d-link. (IP-шники заменены на левые, не обращайте внимания)
Если я не ошибаюсь, то разрыв инициировал как раз шлюз ?
Заранее благодарен...
14:34:13.405|.Transport.cxx(213)|Debug8|Resip|::ResipLogger:Adding message to tx buffer to: [ V4 10.20.30.40:57329 UDP target domain=unspecified mFlowKey=688 ]
14:34:14.108|.Transport.cxx(287)|Debug8|Resip|::ResipLogger:incoming from: [ V4 10.20.40.2:5060 UDP target domain=unspecified mFlowKey=688 ]
14:34:14.108|.TransactionUser.cxx(66)|Debug8|Resip|::ResipLogger:Checking if SipReq: BYE 10002 @10.20.40.1:5060 tid=443648aabfac69ad cseq=BYE / 1935 from(wire) is for me
14:34:14.108|.TransactionUser.cxx(71)|Debug8|Resip|::ResipLogger:Checking rule...
14:34:14.108|.MessageFilterRule.cxx(42)|Debug8|Resip|::ResipLogger:Matching rule for:
BYE sip:10002 @10.20.40.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.20.40.2:5060;branch=z9hG4bK443648aabfac69ad
Max-Forwards: 70
To: ;tag=be1f4906
From: "10002" ;tag=d81d9d51-225260
Call-ID: D421-4DBD-472252605FEE6AA5EA47-763@SipHost
CSeq: 1935 BYE
User-Agent: dlink 12-37-61928258-0.9.5.1.735
Content-Length: 0
14:34:14.108|.TransactionUser.cxx(74)|Debug8|Resip|::ResipLogger:Match!
14:34:14.108|.Helper.cxx(375)|Debug8|Resip|::ResipLogger:Helper::makeResponse(SipReq: BYE 10002 @10.20.40.1:5060 tid=443648aabfac69ad cseq=BYE / 1935 from(wire) code=100 reason=
14:34:14.108|.TimerQueue.cxx(85)|Debug8|Resip|::ResipLogger:Adding timer: Timer Trying tid=443648aabfac69ad ms=3500
14:34:14.123|.TransactionState.cxx(2112)|Debug8|Resip|::ResipLogger:Send to TU: TU: DialogUsageManager size=0
BYE sip:10002 @10.20.40.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.20.40.2:5060;branch=z9hG4bK443648aabfac69ad
Max-Forwards: 70
To: ;tag=be1f4906
From: "10002";tag=d81d9d51-225260
Call-ID: D421-4DBD-472252605FEE6AA5EA47-763@SipHost
CSeq: 1935 BYE
User-Agent: dlink 12-37-61928258-0.9.5.1.735
Content-Length: 0
14:34:14.123|.DialogUsageManager.cxx(1333)|Trace5|Resip|::ResipLogger:Got: SipReq: BYE 10002 @10.20.40.1:5060 tid=443648aabfac69ad cseq=BYE / 1935 from(wire)
14:34:14.123|.DialogUsageManager.cxx(1651)|Debug8|Resip|::ResipLogger
ialogUsageManager:
rocessRequest: SipReq: BYE 10002 @10.20.40.1:5060 tid=443648aabfac69ad cseq=BYE / 1935 from(wire)
14:34:14.123|.DialogUsageManager.cxx(1711)|Trace5|Resip|::ResipLogger:Handling in-dialog request: SipReq: BYE 10002 @10.20.40.1:5060 tid=443648aabfac69ad cseq=BYE / 1935 from(wire)
14:34:14.123|.DialogId.cxx(50)|Debug8|Resip|::ResipLogger
ialogId:
ialogId: D421-4DBD-472252605FEE6AA5EA47-763@SipHost-be1f4906-d81d9d51-225260
14:34:14.123|.DialogSet.cxx(484)|Debug8|Resip|::ResipLogger:Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for
BYE sip:10002 @10.20.40.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.20.40.2:5060;branch=z9hG4bK443648aabfac69ad
Max-Forwards: 70
To: ;tag=be1f4906
From: "10002";tag=d81d9d51-225260
Call-ID: D421-4DBD-472252605FEE6AA5EA47-763@SipHost
CSeq: 1935 BYE
User-Agent: dlink 12-37-61928258-0.9.5.1.735
Content-Length: 0
14:34:14.123|.Dialog.cxx(370)|Debug8|Resip|::ResipLogger
ialog::dispatch: SipReq: BYE 10002 @10.20.40.1:5060 tid=443648aabfac69ad cseq=BYE / 1935 from(wire)
14:34:14.123|.InviteSession.cxx(2014)|Trace5|Resip|::ResipLogger:Received SipReq: BYE 10002 @10.20.40.1:5060 tid=443648aabfac69ad cseq=BYE / 1935 from(wire)
14:34:14.123|.Helper.cxx(375)|Debug8|Resip|::ResipLogger:Helper::makeResponse(SipReq: BYE 10002 @10.20.40.1:5060 tid=443648aabfac69ad cseq=BYE / 1935 from(wire) code=200 reason=
14:34:14.123|.Dialog.cxx(1067)|Debug8|Resip|::ResipLogger
ialog::makeResponse:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.20.40.2:5060;branch=z9hG4bK443648aabfac69ad
Contact:
To: ;tag=be1f4906
From: "10002";tag=d81d9d51-225260
Call-ID: D421-4DBD-472252605FEE6AA5EA47-763@SipHost
CSeq: 1935 BYE
Content-Length: 0
14:34:14.123|.InviteADS.cpp(272)|Trace5||InviteADS:
nReadyToSend:SendMsg from ;tag=be1f4906 to "10002";tag=d81d9d51-225260
14:34:14.123|.DialogUsageManager.cxx(866)|Debug8|Resip|::ResipLogger:SEND:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.20.40.2:5060;branch=z9hG4bK443648aabfac69ad
Contact:
To: ;tag=be1f4906
From: "10002";tag=d81d9d51-225260
Call-ID: D421-4DBD-472252605FEE6AA5EA47-763@SipHost
CSeq: 1935 BYE
User-Agent: 3CXPhoneSystem 8.0.9481.0
Content-Length: 0
14:34:14.123|.SipStack.cxx(296)|Debug8|Resip|::ResipLogger:SEND: SipResp: 200 tid=443648aabfac69ad cseq=BYE contact=10002 @10.20.40.1:5060 / 1935 from(tu)
14:34:14.123|.InviteSession.cxx(2561)|Trace5|Resip|::ResipLogger:Transition InviteSession::Connected -> InviteSession::Terminated
14:34:14.123|.InviteADS.cpp(228)|Trace5||InviteADS:
nTerminated:Terminated from "10002";tag=d81d9d51-225260 to ;tag=be1f4906; reason: RemoteBye
14:34:14.123|.DevsMgr.cpp(892)|Debug2||DevsMgr::setDevBusyStatus
evice (658646278) is used in 0 calls: []
14:34:14.123|.CallEvents.cpp(585)|Trace5||StatusEventHolder::StatusEventHolder:StatusNotification:C=303,L=1,Status=OnHook,Skip=0
14:34:14.123|.CallEvents.cpp(613)|Debug2||StatusEventHolder::StatusEventHolder:Issue: [pres:10002;2;call;6258]
14:34:14.123|.CallEvents.cpp(184)|Trace5||FireStatusEvent:Reported(Delete):303.1 (OnHook)
14:34:14.123|.CallLeg.cpp(512)|Trace5||CallLeg:
nTerminated:Session 10194 terminated; BYE; from IP:10.20.40.2:5060
14:34:14.123|.CallLeg.cpp(1533)|Trace5||CallLeg::terminateTargets:Reason: SIP ;cause=200 ;text="User hangup"
14:34:14.123|.Call.cpp(891)|Trace5||Call::Terminated:Removing leg C:303.1
14:34:14.123|.Call.cpp(896)|Trace5||Call::Terminated:Legs count: 1
14:34:14.123|.Call.cpp(937)|Trace5||Call::Terminate:Call(303) terminated
14:34:14.123|.DevsMgr.cpp(892)|Debug2||DevsMgr::setDevBusyStatus
evice (844580403) is used in 0 calls: []
14:34:14.123|.CallEvents.cpp(585)|Trace5||StatusEventHolder::StatusEventHolder:StatusNotification:C=303,L=2,Status=OnHook,Skip=0
14:34:14.123|.CallEvents.cpp(613)|Debug2||StatusEventHolder::StatusEventHolder:Issue: [pres:604;2;call;6259]
14:34:14.123|.CallEvents.cpp(184)|Trace5||FireStatusEvent:Reported(Delete):303.2 (OnHook)
14:34:14.123|.CallLeg.cpp(1533)|Trace5||CallLeg::terminateTargets:Reason: SIP ;cause=200 ;text="Call terminated on user request"
14:34:14.123|.Call.cpp(970)|Log2||Call::Terminate:[CM503008]: Call(303): Call is terminated
14:34:14.123|.ClientInviteSession.cxx(190)|Trace5|Resip|::ResipLogger:InviteSession::Connected: end
14:34:14.123|.CallEvents.cpp(530)|Trace5||StatusEventHolder::StatusEventHolder:StatusNotification:C=303,L=2,Status=OnHook,Skip=0
14:34:14.123|.Dialog.cxx(1004)|Debug8|Resip|::ResipLogger
ialog::makeRequest:
BYE sip:604 @10.20.30.40:57329;transport=udp SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-a742a32b15354665-1---d8754z-;rport
Max-Forwards: 70
Contact:
To: ;tag=4baf2d0b6b84d431
From: "10002";tag=d44e217a
Call-ID: YzdhMzU0YjZmZWMxMTMzZDM5ZjhkMDQ4NmM4ZjJmMGQ.
CSeq: 2 BYE
Content-Length: 0
14:34:14.123|.CallEvents.cpp(562)|Debug2||StatusEventHolder::StatusEventHolder:Issue: [pres:604;2;call;6260]
14:34:14.123|.InviteSession.cxx(2901)|Trace5|Resip|::ResipLogger:"10002";tag=d44e217a Sending BYE
14:34:14.123|.CallEvents.cpp(173)|Trace5||FireStatusEvent:Ignored:303.2 (OnHook)
14:34:14.123|.InviteADS.cpp(272)|Trace5||InviteADS:
nReadyToSend:SendMsg from "10002";tag=d44e217a to ;tag=4baf2d0b6b84d431
14:34:14.123|.Call.cpp(891)|Trace5||Call::Terminated:Removing leg C:303.2
14:34:14.123|.CallLeg.cpp(547)|Debug2||CallLeg:
nReadyToSend:Sending: SipReq: BYE 604 @10.20.30.40:57329 tid=a742a32b15354665 cseq=BYE
[email protected]:5060 / 2 from(tu)
14:34:14.123|.DialogUsageManager.cxx(866)|Debug8|Resip|::ResipLogger:SEND:
BYE sip:604 @10.20.30.40:57329;transport=udp SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-857794122153d763-1---d8754z-;rport
Max-Forwards: 70
Contact:
To: ;tag=4baf2d0b6b84d431
From: "10002";tag=d44e217a
Call-ID: YzdhMzU0YjZmZWMxMTMzZDM5ZjhkMDQ4NmM4ZjJmMGQ.
CSeq: 2 BYE
User-Agent: 3CXPhoneSystem 8.0.9481.0
Content-Length: 0
14:34:14.123|.DialogId.cxx(50)|Debug8|Resip|::ResipLogger
ialogId:
ialogId: YzdhMzU0YjZmZWMxMTMzZDM5ZjhkMDQ4NmM4ZjJmMGQ.-d44e217a-4baf2d0b6b84d431
14:34:14.123|.DialogUsageManager.cxx(999)|Debug8|Resip|::ResipLogger:Send: SipReq: BYE 604 @10.20.30.40:57329 tid=857794122153d763 cseq=BYE
[email protected]:5060 / 2 from(tu)
14:34:14.123|.SipStack.cxx(314)|Debug8|Resip|::ResipLogger:SEND: SipReq: BYE 604 @10.20.30.40:57329 tid=857794122153d763 cseq=BYE
[email protected]:5060 / 2 from(tu)
14:34:14.123|.InviteSession.cxx(2561)|Trace5|Resip|::ResipLogger:Transition InviteSession::Connected -> InviteSession::Terminated
14:34:14.123|.Call.cpp(896)|Trace5||Call::Terminated:Legs count: 0
14:34:14.123|.InviteADS.cpp(228)|Trace5||InviteADS:
nTerminated:Terminated from ;tag=4baf2d0b6b84d431 to "10002";tag=d44e217a; reason: LocalBye
14:34:14.123|.Call.cpp(920)|Trace5||Call::Terminate:Call(303) already terminated
14:34:14.123|.DevsMgr.cpp(892)|Debug2||DevsMgr::setDevBusyStatus
evice (844580403) is used in 0 calls: []
14:34:14.123|.CallEvents.cpp(585)|Trace5||StatusEventHolder::StatusEventHolder:StatusNotification:C=303,L=2,Status=OnHook,Skip=0
14:34:14.123|.CallEvents.cpp(613)|Debug2||StatusEventHolder::StatusEventHolder:Issue: [pres:604;2;call;6261]
14:34:14.123|.CallEvents.cpp(173)|Trace5||FireStatusEvent:Ignored:303.2 (OnHook)
14:34:14.123|.CallLeg.cpp(512)|Trace5||CallLeg:
nTerminated:Session 10197 terminated; unknown
14:34:14.123|.CallLeg.cpp(1533)|Trace5||CallLeg::terminateTargets:Reason:
14:34:14.123|.Call.cpp(891)|Trace5||Call::Terminated:Removing leg C:303.2
14:34:14.123|.Call.cpp(896)|Trace5||Call::Terminated:Legs count: 0
14:34:14.123|.Call.cpp(920)|Trace5||Call::Terminate:Call(303) already terminated
14:34:14.123|.Call.cpp(920)|Trace5||Call::Terminate:Call(303) already terminated
14:34:14.123|.InviteSession.cxx(82)|Debug8|Resip|::ResipLogger:^^^ InviteSession::~InviteSession 01740CC0
14:34:14.123|.Dialog.cxx(244)|Debug8|Resip|::ResipLogger
ialog::~Dialog()
14:34:14.123|.KeepAliveManager.cxx(49)|Debug8|Resip|::ResipLogger:Association removed for [ V4 10.20.40.2:5060 UDP target domain=unspecified mFlowKey=688 ]
14:34:14.123|.DialogUsageManager.cxx(2143)|Debug8|Resip|::ResipLogger:Got merged request removal request
14:34:14.123|.TimerQueue.cxx(105)|Debug8|Resip|::ResipLogger:Adding application timer:
14:34:14.123|.DialogSet.cxx(123)|Debug8|Resip|::ResipLogger: ********** DialogSet::~DialogSet: D421-4DBD-472252605FEE6AA5EA47-763@SipHost-be1f4906*************
14:34:14.123|.InviteADS.cpp(56)|Trace5||InviteADS::destroy:InviteADS::destroy:606
14:34:14.123|.CallLeg.cpp(1503)|Trace5||CallLeg::~CallLeg:~CallLeg(303.1)
14:34:14.233|.TimerQueue.cxx(85)|Debug8|Resip|::ResipLogger:Adding timer: Timer J tid=443648aabfac69ad ms=32000
14:34:14.233|.TransportSelector.cxx(940)|Debug8|Resip|::ResipLogger:Transmitting to [ V4 10.20.40.2:5060 UDP target domain=unspecified mFlowKey=688 ] tlsDomain= via [ V4 10.20.40.1:5060 UDP target domain=unspecified mFlowKey=688 ]
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.20.40.2:5060;branch=z9hG4bK443648aabfac69ad
Contact:
To: ;tag=be1f4906
From: "10002";tag=d81d9d51-225260
Call-ID: D421-4DBD-472252605FEE6AA5EA47-763@SipHost
CSeq: 1935 BYE
User-Agent: 3CXPhoneSystem 8.0.9481.0
Content-Length: 0
14:34:14.233|.Transport.cxx(213)|Debug8|Resip|::ResipLogger:Adding message to tx buffer to: [ V4 10.20.40.2:5060 UDP target domain=unspecified mFlowKey=688 ]
14:34:14.233|.TimerQueue.cxx(85)|Debug8|Resip|::ResipLogger:Adding timer: Timer F tid=857794122153d763 ms=32000
14:34:14.233|.TransportSelector.cxx(316)|Debug8|Resip|::ResipLogger:Looking up dns entries for sip:604 @10.20.30.40:57329;transport=udp
14:34:14.233|.DnsResult.cxx(214)|Debug8|Resip|::ResipLogger
nsResult::lookup sip:604 @10.20.30.40:57329;transport=udp
14:34:14.233|.DnsResult.cxx(256)|Debug8|Resip|::ResipLogger:Found immediate result: [ V4 10.20.30.40:57329 UDP target domain=10.20.30.40 mFlowKey=0 ]
14:34:14.233|.TimerQueue.cxx(85)|Debug8|Resip|::ResipLogger:Adding timer: Timer E1 tid=857794122153d763 ms=500
14:34:14.233|.TransportSelector.cxx(562)|Debug8|Resip|::ResipLogger:Looked up source for destination: [ V4 10.20.30.40:57329 UDP target domain=10.20.30.40 mFlowKey=0 ] -> [ V4 10.20.40.1:0 UDP target domain=10.20.30.40 mFlowKey=0 ] sent-by= sent-port=0
14:34:14.233|.TransportSelector.cxx(1107)|Debug8|Resip|::ResipLogger:findTransportBySource([ V4 10.20.40.1:0 UDP target domain=10.20.30.40 mFlowKey=0 ])
14:34:14.233|.TransportSelector.cxx(1208)|Debug8|Resip|::ResipLogger:findTransport (any port, specific interface) => Transport: [ V4 10.20.40.1:5060 UDP target domain=unspecified mFlowKey=688 ] on 10.20.40.1
14:34:14.233|.TransportSelector.cxx(940)|Debug8|Resip|::ResipLogger:Transmitting to [ V4 10.20.30.40:57329 UDP target domain=10.20.30.40 mFlowKey=0 ] tlsDomain= via [ V4 10.20.40.1:5060 UDP target domain=10.20.30.40 mFlowKey=0 ]
BYE sip:604 @10.20.30.40:57329;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.20.40.1:5060;branch=z9hG4bK-d8754z-857794122153d763-1---d8754z-;rport
Max-Forwards: 70
Contact:
To: ;tag=4baf2d0b6b84d431
From: "10002";tag=d44e217a
Call-ID: YzdhMzU0YjZmZWMxMTMzZDM5ZjhkMDQ4NmM4ZjJmMGQ.
CSeq: 2 BYE
User-Agent: 3CXPhoneSystem 8.0.9481.0
Content-Length: 0
14:34:14.233|.Transport.cxx(213)|Debug8|Resip|::ResipLogger:Adding message to tx buffer to: [ V4 10.20.30.40:57329 UDP target domain=10.20.30.40 mFlowKey=0 ]
14:34:14.264|.Transport.cxx(287)|Debug8|Resip|::ResipLogger:incoming from: [ V4 10.20.30.40:57329 UDP target domain=unspecified mFlowKey=688 ]
14:34:14.264|.DnsResult.cxx(206)|Debug8|Resip|::ResipLogger:Whitelisting 10.20.30.40(1): 10.20.30.40
14:34:14.264|.dnsRRVip.cxx(129)|Debug8|Resip|::ResipLogger:updating an existing vip: 10.20.30.40 with 10.20.30.40
14:34:14.264|.TransactionState.cxx(2112)|Debug8|Resip|::ResipLogger:Send to TU: TU: DialogUsageManager size=0
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.20.40.1:5060;branch=z9hG4bK-d8754z-857794122153d763-1---d8754z-;rport
Contact:
To: ;tag=4baf2d0b6b84d431
From: "10002";tag=d44e217a
Call-ID: YzdhMzU0YjZmZWMxMTMzZDM5ZjhkMDQ4NmM4ZjJmMGQ.
CSeq: 2 BYE
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, INFO, SUBSCRIBE, UPDATE, PRACK
Supported: replaces, timer
User-Agent: Grandstream BT200 1.1.6.46
Warning: 399 10.20.30.40 "detected NAT type is restricted cone"
Content-Length: 0
14:34:14.264|.TimerQueue.cxx(85)|Debug8|Resip|::ResipLogger:Adding timer: Timer K tid=857794122153d763 ms=5000
14:34:14.264|.DialogUsageManager.cxx(1333)|Trace5|Resip|::ResipLogger:Got: SipResp: 200 tid=857794122153d763 cseq=BYE contact=604 @10.20.30.40:57329 / 2 from(wire)
14:34:14.264|.DialogUsageManager.cxx(1841)|Debug8|Resip|::ResipLogger
ialogUsageManager:
rocessResponse:
SipResp: 200 tid=857794122153d763 cseq=BYE contact=604 @10.20.30.40:57329 / 2 from(wire)
14:34:14.264|.DialogId.cxx(50)|Debug8|Resip|::ResipLogger
ialogId:
ialogId: YzdhMzU0YjZmZWMxMTMzZDM5ZjhkMDQ4NmM4ZjJmMGQ.-d44e217a-4baf2d0b6b84d431
14:34:14.264|.DialogSet.cxx(484)|Debug8|Resip|::ResipLogger:Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.20.40.1:5060;branch=z9hG4bK-d8754z-857794122153d763-1---d8754z-;rport
Contact:
To: ;tag=4baf2d0b6b84d431
From: "10002";tag=d44e217a
Call-ID: YzdhMzU0YjZmZWMxMTMzZDM5ZjhkMDQ4NmM4ZjJmMGQ.
CSeq: 2 BYE
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, INFO, SUBSCRIBE, UPDATE, PRACK
Supported: replaces, timer
User-Agent: Grandstream BT200 1.1.6.46
Warning: 399 10.20.30.40 "detected NAT type is restricted cone"
Content-Length: 0