I am having an issue on one of our IP500V2 systems with calls being dropped after several minutes, ranging from 10-20 minutes. It's not every call but it is frequent. The carrier claims it's being disconnected from the IP 500 since it's seeing a normal call termination. Below is the trace for the call at disconnection, the pertinent call is (MCallEvt: 0.19877.0 6140 Omran Kazemi) I'm not terribly familiar with SIP conversations so I'm wondering if this shows normal call termination, or something going "wrong". We have 6 other systems with the same carrier that don't seem to have this issue and all are using the same SonicWall firewalls and the sam IP500V2s on the same software rev 9.0.1100.1003 Anyone have any suggestions?
484579368mS CMCallEvt: 109.67.1 -1 H323TrunkEP: StateChange: END=X CMCSConnected->CMCSDelete
484579369mS CMCallEvt: 109.67.1 -1 BaseEP: DELETE CMEndpoint f4a40a4c TOTAL NOW=3 CALL_LIST=1
484579369mS CMExtnEvt: Extn682: CALL LOST (CMCauseNormal)
484579369mS CMExtnEvt: Extn682: Extn(682) Calling Party Number(BA_New Call) Type(CMNTypeUnknown)
484579370mS CMExtnEvt: Extn682: CMExtnHandler::SetCurrent( id: 19876->0 )
484579370mS CMCallEvt: 0.19876.0 -1 Extn682.-1: StateChange: END=X CMCSCompleted->CMCSDelete
484579371mS CMCallEvt: END CALL:6139 (f4ae5474)
484579371mS CMCallEvt: 0.19876.0 -1 BaseEP: DELETE CMEndpoint f4b0919c TOTAL NOW=2 CALL_LIST=1
484579374mS H323Evt: SetOperational 2184 10.250.6.5:49154 to 0
484579375mS H323Evt: RTP(END): 10.250.6.5/49154 10.0.0.10/49158 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=off AGE=36142 SENT=1801 RECV=1797 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
484579379mS H323Evt: v=0 stacknum=109 State, new=ReleaseReq, old=Active id=67
484579380mS H323Evt: v=0 stacknum=109 State, new=NullState, old=ReleaseReq id=67
484581370mS CMExtnEvt: Extn682: Recover Timer reason=CMTRWrapUp
484581370mS CMExtnEvt: v=5 State, new=Idle old=PortRecoverDelay,0,0,Extn682
484597313mS CMExtnEvt: Omran Kazemi: No user activity
484599737mS PRN: Config Write Wake Up
484600237mS PRN: Updates IO list size 0 updated list size 1
484600237mS PRN: Config Write Completed
484601247mS CMCallEvt: 0.19877.0 6140 Omran Kazemi.0: StateChange: END=A CMCSConnected->CMCSCompleted
484601248mS Sip: 17.19880.0 6140 SIPTrunk Endpoint(f51b450c) received CMFacility
484601249mS CMExtnEvt: v=2 State, new=PortRecoverDelay old=Connected,0,0,Omran Kazemi
484601250mS CD: CALL: 0.19877.0 BState=Connected Cut=2 Music=0.0 Aend="Omran Kazemi(680)" (0.5) Bend="Line 17" [Line 17] (0.0) CalledNum=15107767531 () CallingNum=680 (Omran Kazemi) Internal=0 Time=63995 AState=Idle
484601250mS CD2: CALL:S 0.19877.0,0.19878.0,0,2,0,0,1,0,Omran Kazemi(680),Line 17,,0.5,0.0,100.100,15107767531,100.101,680,,,100,715107767531,100,,0,16,0,1,0.0,,,,,,,0,63995,0,0,56257,0,enu,,0,,0,0,0,0,,6140,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
484601251mS CD: CALL: 0.19877.0 Deleted
484601251mS CD2: CALL 0.19877.0,0.19878.0,6140,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
484601251mS CMExtnEvt: Omran Kazemi: CALL LOST (CMCauseNormal)
484601251mS CMExtnEvt: Omran Kazemi: Extn(680) Calling Party Number(680) Type(CMNTypeInternal)
484601251mS CMExtnEvt: Omran Kazemi: CMExtnHandler::SetCurrent( id: 19877->0 )
484601252mS CMCallEvt: 0.19877.0 -1 Omran Kazemi.-1: StateChange: END=X CMCSCompleted->CMCSDelete
484601253mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b450c) received CMReleaseComp
484601253mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b2eec) Terminating dialog f51b2eec, state SIPDialog::CALL_UP(16) for cause CMCauseNormal
484601254mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b2eec) BYE SENT TO 10.250.0.103 5060
484601255mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b2eec) UpdateSIPCallState SIPDialog::CALL_UP(16) -> SIPDialog::BYE_SENT(18)
484601255mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b2eec) KeepDlgOnCmCallLost SIPDialog::BYE_SENT
484601256mS Sip: SIPDialog f51b2eec deleted, dialogs 0 txn_keys 17
484601256mS CMCallEvt: 17.19880.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSConnected->CMCSDelete
484601256mS Sip: ~SipTrunkEndpoint 17.19880.0 -1 SIPTrunk Endpoint
484601257mS CMCallEvt: 17.19880.0 -1 BaseEP: DELETE CMEndpoint f51b450c TOTAL NOW=1 CALL_LIST=0
484601257mS CMCallEvt: END CALL:6140 (f4b20dcc)
484601257mS CMCallEvt: 0.19877.0 -1 BaseEP: DELETE CMEndpoint f4a0e444 TOTAL NOW=0 CALL_LIST=0
484601260mS H323Evt: SetOperational 2183 10.250.6.5:49152 to 0
484601261mS H323Evt: RTP(END): 10.250.6.5/49152 10.250.0.103/20062 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=on AGE=60087 SENT=2997 RECV=2998 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
484603252mS CMExtnEvt: Omran Kazemi: Recover Timer reason=CMTRWrapUp
484603252mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,Omran Kazemi
484606353mS Sip: sip_indicateTimeOut Timer 11
484606353mS Sip: Timer 11 callback didn't find dialog, method BYE, callid 049a4b6329838dd7d8a6aa9dbd5ee3d7
484606353mS Sip: sip_indicateTimeOut txn_keys 16
484579368mS CMCallEvt: 109.67.1 -1 H323TrunkEP: StateChange: END=X CMCSConnected->CMCSDelete
484579369mS CMCallEvt: 109.67.1 -1 BaseEP: DELETE CMEndpoint f4a40a4c TOTAL NOW=3 CALL_LIST=1
484579369mS CMExtnEvt: Extn682: CALL LOST (CMCauseNormal)
484579369mS CMExtnEvt: Extn682: Extn(682) Calling Party Number(BA_New Call) Type(CMNTypeUnknown)
484579370mS CMExtnEvt: Extn682: CMExtnHandler::SetCurrent( id: 19876->0 )
484579370mS CMCallEvt: 0.19876.0 -1 Extn682.-1: StateChange: END=X CMCSCompleted->CMCSDelete
484579371mS CMCallEvt: END CALL:6139 (f4ae5474)
484579371mS CMCallEvt: 0.19876.0 -1 BaseEP: DELETE CMEndpoint f4b0919c TOTAL NOW=2 CALL_LIST=1
484579374mS H323Evt: SetOperational 2184 10.250.6.5:49154 to 0
484579375mS H323Evt: RTP(END): 10.250.6.5/49154 10.0.0.10/49158 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=off AGE=36142 SENT=1801 RECV=1797 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
484579379mS H323Evt: v=0 stacknum=109 State, new=ReleaseReq, old=Active id=67
484579380mS H323Evt: v=0 stacknum=109 State, new=NullState, old=ReleaseReq id=67
484581370mS CMExtnEvt: Extn682: Recover Timer reason=CMTRWrapUp
484581370mS CMExtnEvt: v=5 State, new=Idle old=PortRecoverDelay,0,0,Extn682
484597313mS CMExtnEvt: Omran Kazemi: No user activity
484599737mS PRN: Config Write Wake Up
484600237mS PRN: Updates IO list size 0 updated list size 1
484600237mS PRN: Config Write Completed
484601247mS CMCallEvt: 0.19877.0 6140 Omran Kazemi.0: StateChange: END=A CMCSConnected->CMCSCompleted
484601248mS Sip: 17.19880.0 6140 SIPTrunk Endpoint(f51b450c) received CMFacility
484601249mS CMExtnEvt: v=2 State, new=PortRecoverDelay old=Connected,0,0,Omran Kazemi
484601250mS CD: CALL: 0.19877.0 BState=Connected Cut=2 Music=0.0 Aend="Omran Kazemi(680)" (0.5) Bend="Line 17" [Line 17] (0.0) CalledNum=15107767531 () CallingNum=680 (Omran Kazemi) Internal=0 Time=63995 AState=Idle
484601250mS CD2: CALL:S 0.19877.0,0.19878.0,0,2,0,0,1,0,Omran Kazemi(680),Line 17,,0.5,0.0,100.100,15107767531,100.101,680,,,100,715107767531,100,,0,16,0,1,0.0,,,,,,,0,63995,0,0,56257,0,enu,,0,,0,0,0,0,,6140,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
484601251mS CD: CALL: 0.19877.0 Deleted
484601251mS CD2: CALL 0.19877.0,0.19878.0,6140,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
484601251mS CMExtnEvt: Omran Kazemi: CALL LOST (CMCauseNormal)
484601251mS CMExtnEvt: Omran Kazemi: Extn(680) Calling Party Number(680) Type(CMNTypeInternal)
484601251mS CMExtnEvt: Omran Kazemi: CMExtnHandler::SetCurrent( id: 19877->0 )
484601252mS CMCallEvt: 0.19877.0 -1 Omran Kazemi.-1: StateChange: END=X CMCSCompleted->CMCSDelete
484601253mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b450c) received CMReleaseComp
484601253mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b2eec) Terminating dialog f51b2eec, state SIPDialog::CALL_UP(16) for cause CMCauseNormal
484601254mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b2eec) BYE SENT TO 10.250.0.103 5060
484601255mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b2eec) UpdateSIPCallState SIPDialog::CALL_UP(16) -> SIPDialog::BYE_SENT(18)
484601255mS Sip: 17.19880.0 -1 SIPTrunk Endpoint(f51b2eec) KeepDlgOnCmCallLost SIPDialog::BYE_SENT
484601256mS Sip: SIPDialog f51b2eec deleted, dialogs 0 txn_keys 17
484601256mS CMCallEvt: 17.19880.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSConnected->CMCSDelete
484601256mS Sip: ~SipTrunkEndpoint 17.19880.0 -1 SIPTrunk Endpoint
484601257mS CMCallEvt: 17.19880.0 -1 BaseEP: DELETE CMEndpoint f51b450c TOTAL NOW=1 CALL_LIST=0
484601257mS CMCallEvt: END CALL:6140 (f4b20dcc)
484601257mS CMCallEvt: 0.19877.0 -1 BaseEP: DELETE CMEndpoint f4a0e444 TOTAL NOW=0 CALL_LIST=0
484601260mS H323Evt: SetOperational 2183 10.250.6.5:49152 to 0
484601261mS H323Evt: RTP(END): 10.250.6.5/49152 10.250.0.103/20062 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=on AGE=60087 SENT=2997 RECV=2998 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
484603252mS CMExtnEvt: Omran Kazemi: Recover Timer reason=CMTRWrapUp
484603252mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,Omran Kazemi
484606353mS Sip: sip_indicateTimeOut Timer 11
484606353mS Sip: Timer 11 callback didn't find dialog, method BYE, callid 049a4b6329838dd7d8a6aa9dbd5ee3d7
484606353mS Sip: sip_indicateTimeOut txn_keys 16