JustAGlitch
Programmer
We have a customer in Mumbai India that complains about losing calls all the time (Dropped Calls). I have so many monitor captures from monitor and a rough time frame of the lost calls reported. I am trying to understand the release codes we are getting on a regular basis. also on the B179 conference phones we sem to get a lot of register 401 Unauthorized messages as well. Any help would be appreciated. Below are snip it off the release codes.
The first one has a cause code =124
015-03-20T09:23:18 1406181003mS CMCallPkt: v=0
CMInformation
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:23:18 1406181164mS ISDNL1Rx: v=1 peb=1
0000 02 01 48 0e 08 02 80 05 02 18 03 a9 83 82 ..H...........
2015-03-20T09:23:18 1406181164mS ISDNL2Rx: v=1 peb=1
0000 02 01 48 0e 08 02 80 05 02 18 03 a9 83 82 ..H...........
2015-03-20T09:23:18 1406181165mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0005(Local)
Message Type = CallProceeding
InformationElement = CHI
0000 18 03 a9 83 82 .....
2015-03-20T09:23:18 1406181165mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=Initiated id=131952
2015-03-20T09:23:18 1406181165mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 4a ...J
2015-03-20T09:23:18 1406181166mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 4a ...J
2015-03-20T09:23:18 1406181166mS CMCallPkt: v=0
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=131952 in=0
BChan: slot=0 chan=2
2015-03-20T09:23:18 1406181166mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=131952 in=0
BChan: slot=0 chan=2
2015-03-20T09:23:18 1406181167mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
2015-03-20T09:23:18 1406181167mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
2015-03-20T09:23:18 1406181167mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.131951.0 37537 ARS for ISD - Call State: CMCSOverlapRecv
2015-03-20T09:23:18 1406181168mS CMCallEvt: 0.131951.0 -1 ARS for ISD: StateChange: END=X CMCSOverlapRecv->CMCSDelete
2015-03-20T09:23:18 1406181169mS CMCallEvt: 0.131952.0 37537 Q931 Trunk:1 CHAN=2: StateChange: END=B CMCSOffering->CMCSAccept
2015-03-20T09:23:18 1406181169mS CMCallEvt: 0.131951.0 -1 BaseEP: DELETE CMEndpoint f5258600 TOTAL NOW=2 CALL_LIST=1
2015-03-20T09:23:18 1406181170mS CD: CALL: 264.131949.0 BState=Ringing Cut=3 Music=0.0 Aend="Swaminathan B(3152)" (0.0) Bend="Line 1" [Line 1] (1.3) CalledNum=07506727753 () CallingNum=3152 (Swaminathan B) Internal=0 Time=250 AState=Dialled
2015-03-20T09:23:18 1406181170mS CD2: CALL:S 264.131949.0,0.131950.0,8,1,1,0,1,0,Swaminathan B(3152),Line 1,,0.0,1.3,100.100,07506727753,100.101,3152,,,100,007506727753,100,,0,16,0,1,16.0,,,,,,,0,250,0,0,0,0,eng,,0,,0,0,0,0,,37537,,,,,,,,,,,,,,,,0,0,Swami
nathan B,0,,3,0,0,0,0,0,0,1,618,100
2015-03-20T09:23:18 1406181504mS DTM: 20/03/2015 09:23:18 (Fri 20 Mar 2015) [172.27.9.213 (DLL Mumbai)]
2015-03-20T09:23:19 1406182826mS ISDNL1Rx: v=1 peb=1
0000 02 01 4a 0e 08 02 80 05 01 1e 02 82 88 27 01 e0 ..J..........'..
2015-03-20T09:23:19 1406182826mS ISDNL2Rx: v=1 peb=1
0000 02 01 4a 0e 08 02 80 05 01 1e 02 82 88 27 01 e0 ..J..........'..
2015-03-20T09:23:19 1406182827mS ISDNL3Rx: v=1 peb=1
This one has a cause code=Unspecified
015-03-20T09:22:51 1406154334mS CMTARGET: 1.29.1 37534 Q931 Trunk:1 CHAN=1: CancelTimer CMTCCoverageTimeout
2015-03-20T09:22:51 1406154335mS CMLOGGING: CALL:2015/03/2009:22,00:00:00,001,07506727753,I,3152,3152,,,,0,,""n/a,0
2015-03-20T09:22:51 1406154336mS CD: CALL: 1.29.1 BState=Ringing Cut=0 Music=0.0 Aend="LSwaminathan B(3152)" (1.2) Bend="Swaminathan B(3152)" [Swaminathan B(3152)] (0.0) CalledNum=3152 (Swaminathan B) CallingNum=07506727753 () Internal=0 Time=1539 AState=
Idle
2015-03-20T09:22:51 1406154336mS CD2: CALL:S 1.29.1,0.131941.0,0,1,0,0,0,0,L,,Swaminathan B(3152),1.2,0.0,100.0,3152,0.2,07506727753,,,0,3152,100,,0,0,0,1,0.0,,,Swaminathan B,,Swaminathan B,,0,1539,0,0,0,1526,eng,,0,,0,0,0,0,,37534,0,0,,0,,3,0,0,0,0,0,0,1
,618,1,,,,,,,,,,,,,,,
2015-03-20T09:22:51 1406154336mS CD: CALL: 1.29.1 Deleted
2015-03-20T09:22:51 1406154336mS CMCallEvt: 1.29.1 -1 Q931 Trunk:1 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:22:51 1406154336mS CMCallEvt: 0.131941.0 -1 BaseEP: DELETE CMEndpoint f5103b68 TOTAL NOW=2 CALL_LIST=0
2015-03-20T09:22:51 1406154337mS CMExtnEvt: Swaminathan B: CALL LOST (CMCauseUnspecified)
2015-03-20T09:22:51 1406154337mS CMExtnEvt: Swaminathan B: Extn(3152) Calling Party Number(07506727753) Type(CMNTypeNational)
2015-03-20T09:22:51 1406154337mS CMCallEvt: 264.131942.0 -1 Swaminathan B.0: StateChange: END=X CMCSRinging->CMCSCompleted
2015-03-20T09:22:51 1406154338mS CMExtnEvt: v=1606 State, new=PortRecoverDelay old=Ringing,0,0,Swaminathan B
2015-03-20T09:22:51 1406154339mS CMExtnTx: v=3152, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=264 id=131942 in=0
Called[3152] Type=Unknown (0) Reason=CMDRdirect Calling[07506727753] Type=National Plan=ISDN Pres=Allowed (0)
Cause=31, Normal, unspecified
Timed: 20/03/15 09:22
2015-03-20T09:22:51 1406154339mS CMExtnTx: v=3152, p1=8013
CMFacility
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:22:51 1406154339mS CMExtnEvt: Swaminathan B: CMExtnHandler::SetCurrent( id: 131942->0 )
2015-03-20T09:22:51 1406154340mS CMCallEvt: 264.131942.0 -1 Swaminathan B.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:22:51 1406154341mS CMCallEvt: 264.131942.0 -1 BaseEP: DELETE CMEndpoint f5264448 TOTAL NOW=1 CALL_LIST=0
2015-03-20T09:22:51 1406154341mS CMCallEvt: END CALL:37534 (f5173028)
2015-03-20T09:22:51 1406154342mS CD2: CALL
1.29.1,0.131941.0,37534,,,,,,,,,,,,,,,,0,0,,0,,3,0,0,0,0,0,0,1,618,1
2015-03-20T09:22:51 1406154342mS CMTARGET: 1.29.1 -1 BaseEP: ~CMTargetHandler f51e7050 ep f5173600
2015-03-20T09:22:51 1406154342mS CMCallEvt: 1.29.1 -1 BaseEP: DELETE CMEndpoint f5173600 TOTAL NOW=0 CALL_LIST=0
2015-03-20T09:22:51 1406154343mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 42 ...B
This one shows cause code=Normal
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:23:03 1406166780mS CMExtnTx: v=3152, p1=8013
CMFacility
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:23:03 1406166781mS CMExtnRx: v=3152, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=264 id=131943 in=0
2015-03-20T09:23:03 1406166781mS CMCallEvt: 264.131943.0 37535 Swaminathan B.0: StateChange: END=A CMCSICDisconnecting->CMCSCompleted
2015-03-20T09:23:03 1406166781mS CMExtnEvt: v=1606 State, new=PortRecoverDelay old=CMESCompleted,0,0,Swaminathan B
2015-03-20T09:23:03 1406166783mS CMExtnTx: v=3152, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=264 id=131943 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 20/03/15 09:23
2015-03-20T09:23:03 1406166784mS CMLOGGING: CALL:2015/03/2009:22,00:00:00,000,3152,O,7506727753,07506727753,SwaminathanB,,,0,,""n/a,0
2015-03-20T09:23:03 1406166784mS CD: CALL: 264.131943.0 BState=Disconnecting Cut=2 Music=0.0 Aend="SLine 1" (0.0) Bend="Line 1" [Line 1] (1.5) CalledNum=7506727753 () CallingNum=3152 (Swaminathan B) Internal=0 Time=5864 AState=Idle
2015-03-20T09:23:03 1406166784mS CD2: CALL:S 264.131943.0,0.131944.0,0,3,0,0,1,0,SLine 1,Line 1,,0.0,1.5,100.100,7506727753,100.101,3152,,,100,07506727753,100,,0,17,0,1,16.0,,,,,,,0,5864,0,0,0,0,eng,,0,,0,0,0,0,,37535,,,,,,,,,,,,,,,,0,0,Swaminathan B,0,,3
,0,0,0,0,0,0,1,618,100
2015-03-20T09:23:03 1406166784mS CD: CALL: 264.131943.0 Deleted
2015-03-20T09:23:03 1406166784mS CMExtnEvt: Swaminathan B: CALL LOST (CMCauseNormal)
2015-03-20T09:23:03 1406166784mS CMExtnEvt: Swaminathan B: Extn(3152) Calling Party Number(3152) Type(CMNTypeInternal)
2015-03-20T09:23:03 1406166785mS CMExtnEvt: Swaminathan B: CMExtnHandler::SetCurrent( id: 131943->0 )
2015-03-20T09:23:03 1406166786mS CMCallEvt: 264.131943.0 -1 Swaminathan B.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:23:03 1406166787mS CMLineTx: v=1
CMRelease
Line: type=Q931Line 1 Call: lid=0 id=131946 in=0
Cause=16, Normal call clearing
2015-03-20T09:23:03 1406166787mS CMCallEvt: 0.131946.0 -1 Q931 Trunk:1 CHAN=4: StateChange: END=X CMCSDisconnecting->CMCSDelete
2015-03-20T09:23:03 1406166788mS CD2: CALL
264.131943.0,0.131944.0,37535,,,,,,,,,,,,,,,,0,0,Swaminathan B,0,,3,0,0,0,0,0,0,1,618,100
2015-03-20T09:23:03 1406166788mS CMCallEvt: 0.131946.0 -1 BaseEP: DELETE CMEndpoint f5173600 TOTAL NOW=1 CALL_LIST=0
2015-03-20T09:23:03 1406166788mS CMCallEvt: END CALL:37535 (f5173028)
This one says release code =16
0000 6c 0c 00 80 32 32 32 34 38 32 33 31 35 32 l...2224823152
InformationElement = CalledPartyNumber
0000 70 0b 80 37 35 30 36 37 32 37 37 35 33 p..7506727753
InformationElement = Sending Complete
2015-03-20T09:24:01 1406224214mS ISDNL2Tx: v=1 peb=1
0000 00 01 18 54 08 02 00 06 05 04 03 90 90 a3 6c 0c ...T..........l.
0010 00 80 32 32 32 34 38 32 33 31 35 32 70 0b 80 37 ..2224823152p..7
0020 35 30 36 37 32 37 37 35 33 a1 506727753.
2015-03-20T09:24:01 1406224215mS PRN: Config Write Wake Up
2015-03-20T09:24:01 1406224216mS ISDNL1Tx: v=1 peb=1
0000 00 01 18 54 08 02 00 06 05 04 03 90 90 a3 6c 0c ...T..........l.
0010 00 80 32 32 32 34 38 32 33 31 35 32 70 0b 80 37 ..2224823152p..7
0020 35 30 36 37 32 37 37 35 33 a1 506727753.
2015-03-20T09:24:01 1406224225mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 1a ....
2015-03-20T09:24:01 1406224225mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 1a ....
2015-03-20T09:24:01 1406224323mS CMCallPkt: v=0
CMInformation
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:24:01 1406224324mS CMExtnTx: v=3152, p1=8013
CMFacility
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:24:01 1406224325mS CMExtnRx: v=3152, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=264 id=131958 in=0
2015-03-20T09:24:01 1406224325mS CMCallEvt: 264.131958.0 37540 Swaminathan B.0: StateChange: END=A CMCSDialled->CMCSCompleted
2015-03-20T09:24:01 1406224326mS CMExtnEvt: v=1606 State, new=PortRecoverDelay old=Proceeding,0,0,Swaminathan B
2015-03-20T09:24:01 1406224327mS CMLOGGING: CALL:2015/03/2009:24,00:00:00,000,3152,O,07506727753,07506727753,SwaminathanB,,,1,,""n/a,0
2015-03-20T09:24:01 1406224327mS CD: CALL: 264.131958.0 BState=Idle Cut=2 Music=0.0 Aend="SLine 1" (0.0) Bend="" [Line 1] (0.0) CalledNum=07506727753 () CallingNum=3152 (Swaminathan B) Internal=1 Time=131 AState=Idle
2015-03-20T09:24:01 1406224327mS CD2: CALL:S 264.131958.0,0.131959.0,0,0,0,0,1,0,S,,Line 1,0.0,0.0,100.100,07506727753,100.101,3152,,,100,07506727753,100,,0,16,0,1,0.0,,,,,,,1,131,0,0,0,0,eng,,0,,0,0,0,0,,37540,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
2015-03-20T09:24:01 1406224327mS CD: CALL: 264.131958.0 Deleted
2015-03-20T09:24:01 1406224327mS CD2: CALL
264.131958.0,0.131959.0,37540,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
2015-03-20T09:24:01 1406224327mS CMExtnEvt: Swaminathan B: CALL LOST (CMCauseNormal)
2015-03-20T09:24:01 1406224328mS CMExtnEvt: Swaminathan B: Extn(3152) Calling Party Number(3152) Type(CMNTypeInternal)
2015-03-20T09:24:01 1406224328mS CMExtnEvt: Swaminathan B: CMExtnHandler::SetCurrent( id: 131958->0 )
2015-03-20T09:24:01 1406224328mS CMCallEvt: 264.131958.0 -1 Swaminathan B.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:24:01 1406224329mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.131960.0 37540 ARS for ISD - Call State: CMCSOverlapRecv
2015-03-20T09:24:01 1406224329mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=131961 in=0
Cause=16, Normal call clearing
2015-03-20T09:24:01 1406224330mS CMCallEvt: 0.131961.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSOffering->CMCSCompleted
2015-03-20T09:24:01 1406224330mS CMCallEvt: 0.131960.0 -1 ARS for ISD: StateChange: END=X CMCSOverlapRecv->CMCSDelete
2015-03-20T09:24:01 1406224330mS CMCallEvt: 0.131960.0 -1 BaseEP: DELETE CMEndpoint f5258600 TOTAL NOW=2 CALL_LIST=0
2015-03-20T09:24:01 1406224330mS CMCallEvt: END CALL:37540 (f5173028)
2015-03-20T09:24:01 1406224331mS CMTARGET: 264.131958.0 -1 BaseEP: ~CMTargetHandler f51e7050 ep f5264448
2015-03-20T09:24:01 1406224331mS CMCallEvt: 264.131958.0 -1 BaseEP: DELETE CMEndpoint f5264448 TOTAL NOW=1 CALL_LIST=0
2015-03-20T09:24:01 1406224333mS CMMap: a=2.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 0, total 32
2015-03-20T09:24:01 1406224334mS ISDNL3Evt: v=1 stacknum=1 State, new=DiscReq, old=Initiated id=131961
2015-03-20T09:24:01 1406224334mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0006(Remote)
Message Type = Disconnect
InformationElement = CAUSE
0000 08 02 80 90 ....
2015-03-20T09:24:01 1406224334mS ISDNL2Tx: v=1 peb=1
0000 00 01 1a 54 08 02 00 06 45 08 02 80 90 ...T....E....
2015-03-20T09:24:01 1406224335mS ISDNL1Tx: v=1 peb=1
0000 00 01 1a 54 08 02 00 06 45 08 02 80 90 ...T....E....
2015-03-20T09:24:01 1406224340mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 1c ....
2015-03-20T09:24:01 1406224340mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 1c ....
2015-03-20T09:24:01 1406224508mS ISDNL1Rx: v=1 peb=1
0000 02 01 54 1c 08 02 80 06 4d ..T.....M
2015-03-20T09:24:01 1406224508mS ISDNL2Rx: v=1 peb=1
0000 02 01 54 1c 08 02 80 06 4d ..T.....M
2015-03-20T09:24:01 1406224508mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0006(Local)
Message Type = Release
2015-03-20T09:24:01 1406224509mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0006(Remote)
Message Type = ReleaseComplete
2015-03-20T09:24:01 1406224509mS ISDNL2Tx: v=1 peb=1
0000 00 01 1c 56 08 02 00 06 5a ...V....Z
2015-03-20T09:24:01 1406224509mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=DiscReq id=131961
2015-03-20T09:24:01 1406224510mS CMCallPkt: v=0
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=131961 in=0
2015-03-20T09:24:01 1406224510mS CMLineRx: v=1
CMReleaseComp
This one gets unallocated number:
CMInformation
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:39:58 1407181178mS PRN: 3148: Digit Pressed 1
2015-03-20T09:39:58 1407181178mS CMExtnRx: v=3148, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=260 id=131978 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
2015-03-20T09:39:58 1407181180mS CMMap: a=0.0 b=0.0 pcp[952]b0r1 RTPD0
2015-03-20T09:39:58 1407181588mS DTM: 20/03/2015 09:39:58 (Fri 20 Mar 2015) [172.27.9.213 (DLL Mumbai)]
2015-03-20T09:39:59 1407182178mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: TimerExpired cause=CMTCDelayedProcessing
2015-03-20T09:39:59 1407182178mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: LOOKUP CALL ROUTE: type=100 called_party=1 sub= calling=3148 dir=out complete=0 ses=0
2015-03-20T09:39:59 1407182178mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: ADD TARGET (N): number=1 type=100 depth=1 nobar=1 setorig=1 ses=0
2015-03-20T09:39:59 1407182179mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: TARGET: Not in dial plan
2015-03-20T09:39:59 1407182179mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: CancelTimer CMTCDialToneTimeout
2015-03-20T09:39:59 1407182180mS CMLOGGING: CALL:2015/03/2009:39,00:00:00,000,3148,O,1,1,GauriShah,,,1,,""n/a,0
2015-03-20T09:39:59 1407182180mS CD: CALL: 260.131978.0 BState=Idle Cut=0 Music=0.0 Aend="G" (0.0) Bend="" [] (0.0) CalledNum=1 () CallingNum=3148 (Gauri Shah) Internal=1 Time=2666 AState=Dialling
2015-03-20T09:39:59 1407182180mS CD2: CALL:S 260.131978.0,0.131979.0,7,0,0,0,0,0,G,,,0.0,0.0,100.100,,100.101,3148,,,100,,100,,0,16,0,0,0.0,,,,,,,1,2666,0,0,0,0,eng,,0,,0,0,0,0,,37546,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
2015-03-20T09:39:59 1407182180mS CD: CALL: 260.131978.0 Deleted
2015-03-20T09:39:59 1407182180mS CD2: CALL
260.131978.0,0.131979.0,37546,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
2015-03-20T09:39:59 1407182180mS CMExtnEvt: Gauri Shah: CALL LOST (CMCauseUnallocatedNumber)
2015-03-20T09:39:59 1407182180mS CMExtnEvt: Gauri Shah: Extn(3148) Calling Party Number(3148) Type(CMNTypeInternal)
2015-03-20T09:39:59 1407182181mS CMCallEvt: 260.131978.0 -1 Gauri Shah.0: StateChange: END=X CMCSDialling->CMCSCompletedTone
2015-03-20T09:39:59 1407182181mS CMExtnEvt: v=1586 State, new=CMESCompleted old=Dialling,0,0,Gauri Shah
2015-03-20T09:39:59 1407182182mS CMCallEvt: 0.131979.0 -1 BaseEP: DELETE CMEndpoint f5103b68 TOTAL NOW=3 CALL_LIST=1
2015-03-20T09:39:59 1407182182mS CMCallEvt: END CALL:37546 (f517cf90)
2015-03-20T09:39:59 1407182183mS CMMap: PCG::AddToneGenerator g B3[6] for cp[952]b0r1 append pcp[954]b0r1 (total 1)
2015-03-20T09:39:59 1407182183mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[954]b0r1 Configure 0.0
2015-03-20T09:39:59 1407182183mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[954]b0r1 ConnectIndication 0.0
2015-03-20T09:39:59 1407182184mS CMMap: a=0.0 b=0.0 pcp[952]b0r1 RTPB3
2015-03-20T09:39:59 1407182919mS SIP Tx: UDP 172.27.9.213:5060 -> 172.27.157.66:5060
OPTIONS sip:3165@172.27.9.213 SIP/2.0
Via: SIP/2.0/UDP 172.27.9.213:5060;rport;branch=z9hG4bK4bbe29fa61c1a2077fe69193de315108
From: <sip:Unknown@172.27.9.213>;tag=f2a2b328c5fae948
To: <sip:3165@172.27.9.213>
Call-ID: 43a5692efc52df8ce4a5f2ababfb8ce8
CSeq: 1863032310 OPTIONS
Contact: <sip:Unknown@172.27.9.213:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, SUBSCRIBE, REGISTER, PUBLISH, UPDATE
Supported: timer,100rel
User-Agent: IP Office 8.1 (81)
Content-Length: 0
2015-03-20T09:39:59 1407182923mS SIP Rx: UDP 172.27.157.66:5060 -> 172.27.9.213:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.27.9.213:5060;rport=5060;received=172.27.9.213;branch=z9hG4bK4bbe29fa61c1a2077fe69193de315108;alias
Call-ID: 43a5692efc52df8ce4a5f2ababfb8ce8
From: <sip:Unknown@172.27.9.213>;tag=f2a2b328c5fae948
To: <sip:3165@172.27.9.213>
CSeq: 1863032310 OPTIONS
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Accept: application/sdp, application/pidf+xml, application/xpidf+xml, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain
Supported: replaces, 100rel, norefersub
Allow-Events: presence, refer
User-Agent: Avaya B179 2.3.0
Content-Type: application/sdp
Content-Length: 338
This one gets Force Clear:
2015-03-20T09:40:00 1407183543mS CMCallPkt: v=0
CMInformation
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:40:00 1407183544mS CMExtnTx: v=3148, p1=8009
CMFacility
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:40:00 1407183545mS CMExtnRx: v=3148, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=260 id=131978 in=0
2015-03-20T09:40:00 1407183545mS CMCallEvt: 260.131978.0 -1 Gauri Shah.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
2015-03-20T09:40:00 1407183546mS CMExtnTx: v=3148, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=260 id=131978 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 20/03/15 09:40
2015-03-20T09:40:00 1407183547mS CMExtnEvt: v=1586 State, new=PortRecoverDelay old=CMESCompleted,0,0,Gauri Shah
2015-03-20T09:40:00 1407183547mS CMExtnEvt: Gauri Shah: CALL LOST (CMCauseForceClear)
2015-03-20T09:40:00 1407183547mS CMExtnEvt: Gauri Shah: Extn(3148) Calling Party Number(3148) Type(CMNTypeInternal)
2015-03-20T09:40:00 1407183547mS CMExtnEvt: Gauri Shah: CMExtnHandler::SetCurrent( id: 131978->0 )
2015-03-20T09:40:00 1407183548mS CMCallEvt: 260.131978.0 -1 Gauri Shah.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:40:00 1407183549mS CMTARGET: 260.131978.0 -1 BaseEP: ~CMTargetHandler f51e7050 ep f5170ac0
2015-03-20T09:40:00 1407183549mS CMCallEvt: 260.131978.0 -1 BaseEP: DELETE CMEndpoint f5170ac0 TOTAL NOW=2 CALL_LIST=1
2015-03-20T09:40:00 1407183551mS CMMap: a=0.0 b=0.0 pcp[952]b0r1 RTPB0
2015-03-20T09:40:00 1407183681mS CMCallPkt: v=0
CMInformation
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:40:00 1407183683mS CMCallEvt: 0.131980.0 -1 BaseEP: NEW CMEndpoint f5170ac0 TOTAL NOW=3 CALL_LIST=1
2015-03-20T09:40:00 1407183683mS CMCallEvt: 0.131980.0 -1 Gauri Shah.-1: NEW CMExtnEndpoint f5170ac0, Name=Gauri Shah, Extn=3148, Phys Extn=3148
2015-03-20T09:40:00 1407183683mS CMCallEvt: CREATE CALL:37547 (f517cf90)
2015-03-20T09:40:00 1407183684mS CMCallEvt: 0.131981.0 -1 BaseEP: NEW CMEndpoint f5103b68 TOTAL NOW=4 CALL_LIST=1
2015-03-20T09:40:00 1407183685mS CMExtnEvt: Gauri Shah: CMExtnHandler::SetCurrent( id: 0->131980 )
2015-03-20T09:40:00 1407183686mS CMExtnEvt: v=1586 State, new=Idle old=PortRecoverDelay,0,0,Gauri Shah
2015-03-20T09:40:00 1407183687mS CMExtnRx: v=3148, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=260 id=131980 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[3148] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 12 item(s)
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Gauri Shah
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Gauri Shah
2015-03-20T09:40:00 1407183687mS CMCallEvt: 260.131980.0 37547 Gauri Shah.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
2015-03-20T09:40:00 1407183687mS CMExtnEvt: v=1586 State, new=Dialling old=Idle,0,0,Gauri Shah
2015-03-20T09:40:00 1407183687mS CMTARGET: 260.131980.0 37547 Gauri Shah.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=3148 dir=out complete=0 ses=0
2015-03-20T09:40:00 1407183688mS CMTARGET: 260.131980.0 37547 Gauri Shah.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
2015-03-20T09:40:00 1407183688mS CMCallEvt: 260.131980.0 37547 Gauri Shah.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
2015-03-20T09:40:00 1407183691mS CMExtnTx: v=3148, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=260 id=131980 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
Timed: 20/03/15 09:40
2015-03-20T09:40:00 1407183691mS CMExtnTx: v=3148, p1=8009
CMFacility
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:40:00 1407183692mS CMExtnTx: v=3148, p1=0
CMSetupAck
Now this is the fairly frequent SIP 401 unauthorized messages we get out there as well.
015-03-20T09:39:05 1407128890mS SIP Tx: UDP 172.27.9.213:5060 -> 172.27.157.66:5060
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 172.27.157.66:5060;rport;branch=z9hG4bKPjdxCRgj1Drw2iNKhShUkSk0duGMlE4o1q;alias
From: <sip:3165@172.27.9.213>;tag=q6rXPZZISj5w-2Ekk.11Cxxi7Z1zEDbR
To: <sip:3165@172.27.9.213>;tag=01fd50d62520dcd9
Call-ID: .SvV7Y2X74hsHSOF.zky6bBUBO0lGPV1
CSeq: 6447 REGISTER
User-Agent: IP Office 8.1 (81)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, SUBSCRIBE, REGISTER, PUBLISH
Digest nonce="eb035c2ccb3416e56bf1",realm="ipoffice",algorithm=MD5
Supported: timer
Server: IP Office 8.1 (81)
Content-Length: 0
2015-03-20T09:39:05 1407128894mS SIP Rx: UDP 172.27.157.66:5060 -> 172.27.9.213:5060
REGISTER sip:172.27.9.213;transport=udp SIP/2.0
Via: SIP/2.0/UDP 172.27.157.66:5060;rport;branch=z9hG4bKPjbbRM.5zPUH-8d7eH.9l0aU66Lzk4EUuv;alias
Max-Forwards: 70
From: <sip:3165@172.27.9.213>;tag=q6rXPZZISj5w-2Ekk.11Cxxi7Z1zEDbR
To: <sip:3165@172.27.9.213>
Call-ID: .SvV7Y2X74hsHSOF.zky6bBUBO0lGPV1
CSeq: 6448 REGISTER
User-Agent: Avaya B179 2.3.0
Contact: <sip:3165@172.27.157.66:5060;ob>
Expires: 1800
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="3165", realm="ipoffice", nonce="eb035c2ccb3416e56bf1", uri="sip:172.27.9.213;transport=udp", response="18f6c5c88485940002b2376cf4fec992", algorithm=MD5
Content-Length: 0
2015-03-20T09:39:05 1407128899mS SIP Tx: UDP 172.27.9.213:5060 -> 172.27.157.66:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.27.157.66:5060;rport;branch=z9hG4bKPjbbRM.5zPUH-8d7eH.9l0aU66Lzk4EUuv;alias
From: <sip:3165@172.27.9.213>;tag=q6rXPZZISj5w-2Ekk.11Cxxi7Z1zEDbR
To: <sip:3165@172.27.9.213>;tag=7bc040ddc7a83a07
Call-ID: .SvV7Y2X74hsHSOF.zky6bBUBO0lGPV1
CSeq: 6448 REGISTER
User-Agent: IP Office 8.1 (81)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, SUBSCRIBE, REGISTER, PUBLISH
Contact: <sip:3165@172.27.157.66:5060;ob>
Date: Fri, 20 Mar 2015 04:09:06 GMT
Expires: 180
Supported: timer
Server: IP Office 8.1 (81)
Content-Length: 0
If It's Not Broken...Don't Fix It!
Stephen Webb
Norstar, BCM and IP Office Engineer
The first one has a cause code =124
015-03-20T09:23:18 1406181003mS CMCallPkt: v=0
CMInformation
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:23:18 1406181164mS ISDNL1Rx: v=1 peb=1
0000 02 01 48 0e 08 02 80 05 02 18 03 a9 83 82 ..H...........
2015-03-20T09:23:18 1406181164mS ISDNL2Rx: v=1 peb=1
0000 02 01 48 0e 08 02 80 05 02 18 03 a9 83 82 ..H...........
2015-03-20T09:23:18 1406181165mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0005(Local)
Message Type = CallProceeding
InformationElement = CHI
0000 18 03 a9 83 82 .....
2015-03-20T09:23:18 1406181165mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=Initiated id=131952
2015-03-20T09:23:18 1406181165mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 4a ...J
2015-03-20T09:23:18 1406181166mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 4a ...J
2015-03-20T09:23:18 1406181166mS CMCallPkt: v=0
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=131952 in=0
BChan: slot=0 chan=2
2015-03-20T09:23:18 1406181166mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=131952 in=0
BChan: slot=0 chan=2
2015-03-20T09:23:18 1406181167mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
2015-03-20T09:23:18 1406181167mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
2015-03-20T09:23:18 1406181167mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.131951.0 37537 ARS for ISD - Call State: CMCSOverlapRecv
2015-03-20T09:23:18 1406181168mS CMCallEvt: 0.131951.0 -1 ARS for ISD: StateChange: END=X CMCSOverlapRecv->CMCSDelete
2015-03-20T09:23:18 1406181169mS CMCallEvt: 0.131952.0 37537 Q931 Trunk:1 CHAN=2: StateChange: END=B CMCSOffering->CMCSAccept
2015-03-20T09:23:18 1406181169mS CMCallEvt: 0.131951.0 -1 BaseEP: DELETE CMEndpoint f5258600 TOTAL NOW=2 CALL_LIST=1
2015-03-20T09:23:18 1406181170mS CD: CALL: 264.131949.0 BState=Ringing Cut=3 Music=0.0 Aend="Swaminathan B(3152)" (0.0) Bend="Line 1" [Line 1] (1.3) CalledNum=07506727753 () CallingNum=3152 (Swaminathan B) Internal=0 Time=250 AState=Dialled
2015-03-20T09:23:18 1406181170mS CD2: CALL:S 264.131949.0,0.131950.0,8,1,1,0,1,0,Swaminathan B(3152),Line 1,,0.0,1.3,100.100,07506727753,100.101,3152,,,100,007506727753,100,,0,16,0,1,16.0,,,,,,,0,250,0,0,0,0,eng,,0,,0,0,0,0,,37537,,,,,,,,,,,,,,,,0,0,Swami
nathan B,0,,3,0,0,0,0,0,0,1,618,100
2015-03-20T09:23:18 1406181504mS DTM: 20/03/2015 09:23:18 (Fri 20 Mar 2015) [172.27.9.213 (DLL Mumbai)]
2015-03-20T09:23:19 1406182826mS ISDNL1Rx: v=1 peb=1
0000 02 01 4a 0e 08 02 80 05 01 1e 02 82 88 27 01 e0 ..J..........'..
2015-03-20T09:23:19 1406182826mS ISDNL2Rx: v=1 peb=1
0000 02 01 4a 0e 08 02 80 05 01 1e 02 82 88 27 01 e0 ..J..........'..
2015-03-20T09:23:19 1406182827mS ISDNL3Rx: v=1 peb=1
This one has a cause code=Unspecified
015-03-20T09:22:51 1406154334mS CMTARGET: 1.29.1 37534 Q931 Trunk:1 CHAN=1: CancelTimer CMTCCoverageTimeout
2015-03-20T09:22:51 1406154335mS CMLOGGING: CALL:2015/03/2009:22,00:00:00,001,07506727753,I,3152,3152,,,,0,,""n/a,0
2015-03-20T09:22:51 1406154336mS CD: CALL: 1.29.1 BState=Ringing Cut=0 Music=0.0 Aend="LSwaminathan B(3152)" (1.2) Bend="Swaminathan B(3152)" [Swaminathan B(3152)] (0.0) CalledNum=3152 (Swaminathan B) CallingNum=07506727753 () Internal=0 Time=1539 AState=
Idle
2015-03-20T09:22:51 1406154336mS CD2: CALL:S 1.29.1,0.131941.0,0,1,0,0,0,0,L,,Swaminathan B(3152),1.2,0.0,100.0,3152,0.2,07506727753,,,0,3152,100,,0,0,0,1,0.0,,,Swaminathan B,,Swaminathan B,,0,1539,0,0,0,1526,eng,,0,,0,0,0,0,,37534,0,0,,0,,3,0,0,0,0,0,0,1
,618,1,,,,,,,,,,,,,,,
2015-03-20T09:22:51 1406154336mS CD: CALL: 1.29.1 Deleted
2015-03-20T09:22:51 1406154336mS CMCallEvt: 1.29.1 -1 Q931 Trunk:1 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:22:51 1406154336mS CMCallEvt: 0.131941.0 -1 BaseEP: DELETE CMEndpoint f5103b68 TOTAL NOW=2 CALL_LIST=0
2015-03-20T09:22:51 1406154337mS CMExtnEvt: Swaminathan B: CALL LOST (CMCauseUnspecified)
2015-03-20T09:22:51 1406154337mS CMExtnEvt: Swaminathan B: Extn(3152) Calling Party Number(07506727753) Type(CMNTypeNational)
2015-03-20T09:22:51 1406154337mS CMCallEvt: 264.131942.0 -1 Swaminathan B.0: StateChange: END=X CMCSRinging->CMCSCompleted
2015-03-20T09:22:51 1406154338mS CMExtnEvt: v=1606 State, new=PortRecoverDelay old=Ringing,0,0,Swaminathan B
2015-03-20T09:22:51 1406154339mS CMExtnTx: v=3152, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=264 id=131942 in=0
Called[3152] Type=Unknown (0) Reason=CMDRdirect Calling[07506727753] Type=National Plan=ISDN Pres=Allowed (0)
Cause=31, Normal, unspecified
Timed: 20/03/15 09:22
2015-03-20T09:22:51 1406154339mS CMExtnTx: v=3152, p1=8013
CMFacility
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:22:51 1406154339mS CMExtnEvt: Swaminathan B: CMExtnHandler::SetCurrent( id: 131942->0 )
2015-03-20T09:22:51 1406154340mS CMCallEvt: 264.131942.0 -1 Swaminathan B.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:22:51 1406154341mS CMCallEvt: 264.131942.0 -1 BaseEP: DELETE CMEndpoint f5264448 TOTAL NOW=1 CALL_LIST=0
2015-03-20T09:22:51 1406154341mS CMCallEvt: END CALL:37534 (f5173028)
2015-03-20T09:22:51 1406154342mS CD2: CALL
2015-03-20T09:22:51 1406154342mS CMTARGET: 1.29.1 -1 BaseEP: ~CMTargetHandler f51e7050 ep f5173600
2015-03-20T09:22:51 1406154342mS CMCallEvt: 1.29.1 -1 BaseEP: DELETE CMEndpoint f5173600 TOTAL NOW=0 CALL_LIST=0
2015-03-20T09:22:51 1406154343mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 42 ...B
This one shows cause code=Normal
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:23:03 1406166780mS CMExtnTx: v=3152, p1=8013
CMFacility
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:23:03 1406166781mS CMExtnRx: v=3152, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=264 id=131943 in=0
2015-03-20T09:23:03 1406166781mS CMCallEvt: 264.131943.0 37535 Swaminathan B.0: StateChange: END=A CMCSICDisconnecting->CMCSCompleted
2015-03-20T09:23:03 1406166781mS CMExtnEvt: v=1606 State, new=PortRecoverDelay old=CMESCompleted,0,0,Swaminathan B
2015-03-20T09:23:03 1406166783mS CMExtnTx: v=3152, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=264 id=131943 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 20/03/15 09:23
2015-03-20T09:23:03 1406166784mS CMLOGGING: CALL:2015/03/2009:22,00:00:00,000,3152,O,7506727753,07506727753,SwaminathanB,,,0,,""n/a,0
2015-03-20T09:23:03 1406166784mS CD: CALL: 264.131943.0 BState=Disconnecting Cut=2 Music=0.0 Aend="SLine 1" (0.0) Bend="Line 1" [Line 1] (1.5) CalledNum=7506727753 () CallingNum=3152 (Swaminathan B) Internal=0 Time=5864 AState=Idle
2015-03-20T09:23:03 1406166784mS CD2: CALL:S 264.131943.0,0.131944.0,0,3,0,0,1,0,SLine 1,Line 1,,0.0,1.5,100.100,7506727753,100.101,3152,,,100,07506727753,100,,0,17,0,1,16.0,,,,,,,0,5864,0,0,0,0,eng,,0,,0,0,0,0,,37535,,,,,,,,,,,,,,,,0,0,Swaminathan B,0,,3
,0,0,0,0,0,0,1,618,100
2015-03-20T09:23:03 1406166784mS CD: CALL: 264.131943.0 Deleted
2015-03-20T09:23:03 1406166784mS CMExtnEvt: Swaminathan B: CALL LOST (CMCauseNormal)
2015-03-20T09:23:03 1406166784mS CMExtnEvt: Swaminathan B: Extn(3152) Calling Party Number(3152) Type(CMNTypeInternal)
2015-03-20T09:23:03 1406166785mS CMExtnEvt: Swaminathan B: CMExtnHandler::SetCurrent( id: 131943->0 )
2015-03-20T09:23:03 1406166786mS CMCallEvt: 264.131943.0 -1 Swaminathan B.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:23:03 1406166787mS CMLineTx: v=1
CMRelease
Line: type=Q931Line 1 Call: lid=0 id=131946 in=0
Cause=16, Normal call clearing
2015-03-20T09:23:03 1406166787mS CMCallEvt: 0.131946.0 -1 Q931 Trunk:1 CHAN=4: StateChange: END=X CMCSDisconnecting->CMCSDelete
2015-03-20T09:23:03 1406166788mS CD2: CALL
2015-03-20T09:23:03 1406166788mS CMCallEvt: 0.131946.0 -1 BaseEP: DELETE CMEndpoint f5173600 TOTAL NOW=1 CALL_LIST=0
2015-03-20T09:23:03 1406166788mS CMCallEvt: END CALL:37535 (f5173028)
This one says release code =16
0000 6c 0c 00 80 32 32 32 34 38 32 33 31 35 32 l...2224823152
InformationElement = CalledPartyNumber
0000 70 0b 80 37 35 30 36 37 32 37 37 35 33 p..7506727753
InformationElement = Sending Complete
2015-03-20T09:24:01 1406224214mS ISDNL2Tx: v=1 peb=1
0000 00 01 18 54 08 02 00 06 05 04 03 90 90 a3 6c 0c ...T..........l.
0010 00 80 32 32 32 34 38 32 33 31 35 32 70 0b 80 37 ..2224823152p..7
0020 35 30 36 37 32 37 37 35 33 a1 506727753.
2015-03-20T09:24:01 1406224215mS PRN: Config Write Wake Up
2015-03-20T09:24:01 1406224216mS ISDNL1Tx: v=1 peb=1
0000 00 01 18 54 08 02 00 06 05 04 03 90 90 a3 6c 0c ...T..........l.
0010 00 80 32 32 32 34 38 32 33 31 35 32 70 0b 80 37 ..2224823152p..7
0020 35 30 36 37 32 37 37 35 33 a1 506727753.
2015-03-20T09:24:01 1406224225mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 1a ....
2015-03-20T09:24:01 1406224225mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 1a ....
2015-03-20T09:24:01 1406224323mS CMCallPkt: v=0
CMInformation
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:24:01 1406224324mS CMExtnTx: v=3152, p1=8013
CMFacility
Line: type=IPLine 250 Call: lid=264 id=1606 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:24:01 1406224325mS CMExtnRx: v=3152, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=264 id=131958 in=0
2015-03-20T09:24:01 1406224325mS CMCallEvt: 264.131958.0 37540 Swaminathan B.0: StateChange: END=A CMCSDialled->CMCSCompleted
2015-03-20T09:24:01 1406224326mS CMExtnEvt: v=1606 State, new=PortRecoverDelay old=Proceeding,0,0,Swaminathan B
2015-03-20T09:24:01 1406224327mS CMLOGGING: CALL:2015/03/2009:24,00:00:00,000,3152,O,07506727753,07506727753,SwaminathanB,,,1,,""n/a,0
2015-03-20T09:24:01 1406224327mS CD: CALL: 264.131958.0 BState=Idle Cut=2 Music=0.0 Aend="SLine 1" (0.0) Bend="" [Line 1] (0.0) CalledNum=07506727753 () CallingNum=3152 (Swaminathan B) Internal=1 Time=131 AState=Idle
2015-03-20T09:24:01 1406224327mS CD2: CALL:S 264.131958.0,0.131959.0,0,0,0,0,1,0,S,,Line 1,0.0,0.0,100.100,07506727753,100.101,3152,,,100,07506727753,100,,0,16,0,1,0.0,,,,,,,1,131,0,0,0,0,eng,,0,,0,0,0,0,,37540,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
2015-03-20T09:24:01 1406224327mS CD: CALL: 264.131958.0 Deleted
2015-03-20T09:24:01 1406224327mS CD2: CALL
2015-03-20T09:24:01 1406224327mS CMExtnEvt: Swaminathan B: CALL LOST (CMCauseNormal)
2015-03-20T09:24:01 1406224328mS CMExtnEvt: Swaminathan B: Extn(3152) Calling Party Number(3152) Type(CMNTypeInternal)
2015-03-20T09:24:01 1406224328mS CMExtnEvt: Swaminathan B: CMExtnHandler::SetCurrent( id: 131958->0 )
2015-03-20T09:24:01 1406224328mS CMCallEvt: 264.131958.0 -1 Swaminathan B.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:24:01 1406224329mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.131960.0 37540 ARS for ISD - Call State: CMCSOverlapRecv
2015-03-20T09:24:01 1406224329mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=131961 in=0
Cause=16, Normal call clearing
2015-03-20T09:24:01 1406224330mS CMCallEvt: 0.131961.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSOffering->CMCSCompleted
2015-03-20T09:24:01 1406224330mS CMCallEvt: 0.131960.0 -1 ARS for ISD: StateChange: END=X CMCSOverlapRecv->CMCSDelete
2015-03-20T09:24:01 1406224330mS CMCallEvt: 0.131960.0 -1 BaseEP: DELETE CMEndpoint f5258600 TOTAL NOW=2 CALL_LIST=0
2015-03-20T09:24:01 1406224330mS CMCallEvt: END CALL:37540 (f5173028)
2015-03-20T09:24:01 1406224331mS CMTARGET: 264.131958.0 -1 BaseEP: ~CMTargetHandler f51e7050 ep f5264448
2015-03-20T09:24:01 1406224331mS CMCallEvt: 264.131958.0 -1 BaseEP: DELETE CMEndpoint f5264448 TOTAL NOW=1 CALL_LIST=0
2015-03-20T09:24:01 1406224333mS CMMap: a=2.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 0, total 32
2015-03-20T09:24:01 1406224334mS ISDNL3Evt: v=1 stacknum=1 State, new=DiscReq, old=Initiated id=131961
2015-03-20T09:24:01 1406224334mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0006(Remote)
Message Type = Disconnect
InformationElement = CAUSE
0000 08 02 80 90 ....
2015-03-20T09:24:01 1406224334mS ISDNL2Tx: v=1 peb=1
0000 00 01 1a 54 08 02 00 06 45 08 02 80 90 ...T....E....
2015-03-20T09:24:01 1406224335mS ISDNL1Tx: v=1 peb=1
0000 00 01 1a 54 08 02 00 06 45 08 02 80 90 ...T....E....
2015-03-20T09:24:01 1406224340mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 1c ....
2015-03-20T09:24:01 1406224340mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 1c ....
2015-03-20T09:24:01 1406224508mS ISDNL1Rx: v=1 peb=1
0000 02 01 54 1c 08 02 80 06 4d ..T.....M
2015-03-20T09:24:01 1406224508mS ISDNL2Rx: v=1 peb=1
0000 02 01 54 1c 08 02 80 06 4d ..T.....M
2015-03-20T09:24:01 1406224508mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0006(Local)
Message Type = Release
2015-03-20T09:24:01 1406224509mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0006(Remote)
Message Type = ReleaseComplete
2015-03-20T09:24:01 1406224509mS ISDNL2Tx: v=1 peb=1
0000 00 01 1c 56 08 02 00 06 5a ...V....Z
2015-03-20T09:24:01 1406224509mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=DiscReq id=131961
2015-03-20T09:24:01 1406224510mS CMCallPkt: v=0
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=131961 in=0
2015-03-20T09:24:01 1406224510mS CMLineRx: v=1
CMReleaseComp
This one gets unallocated number:
CMInformation
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:39:58 1407181178mS PRN: 3148: Digit Pressed 1
2015-03-20T09:39:58 1407181178mS CMExtnRx: v=3148, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=260 id=131978 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
2015-03-20T09:39:58 1407181180mS CMMap: a=0.0 b=0.0 pcp[952]b0r1 RTPD0
2015-03-20T09:39:58 1407181588mS DTM: 20/03/2015 09:39:58 (Fri 20 Mar 2015) [172.27.9.213 (DLL Mumbai)]
2015-03-20T09:39:59 1407182178mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: TimerExpired cause=CMTCDelayedProcessing
2015-03-20T09:39:59 1407182178mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: LOOKUP CALL ROUTE: type=100 called_party=1 sub= calling=3148 dir=out complete=0 ses=0
2015-03-20T09:39:59 1407182178mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: ADD TARGET (N): number=1 type=100 depth=1 nobar=1 setorig=1 ses=0
2015-03-20T09:39:59 1407182179mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: TARGET: Not in dial plan
2015-03-20T09:39:59 1407182179mS CMTARGET: 260.131978.0 37546 Gauri Shah.0: CancelTimer CMTCDialToneTimeout
2015-03-20T09:39:59 1407182180mS CMLOGGING: CALL:2015/03/2009:39,00:00:00,000,3148,O,1,1,GauriShah,,,1,,""n/a,0
2015-03-20T09:39:59 1407182180mS CD: CALL: 260.131978.0 BState=Idle Cut=0 Music=0.0 Aend="G" (0.0) Bend="" [] (0.0) CalledNum=1 () CallingNum=3148 (Gauri Shah) Internal=1 Time=2666 AState=Dialling
2015-03-20T09:39:59 1407182180mS CD2: CALL:S 260.131978.0,0.131979.0,7,0,0,0,0,0,G,,,0.0,0.0,100.100,,100.101,3148,,,100,,100,,0,16,0,0,0.0,,,,,,,1,2666,0,0,0,0,eng,,0,,0,0,0,0,,37546,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
2015-03-20T09:39:59 1407182180mS CD: CALL: 260.131978.0 Deleted
2015-03-20T09:39:59 1407182180mS CD2: CALL
2015-03-20T09:39:59 1407182180mS CMExtnEvt: Gauri Shah: CALL LOST (CMCauseUnallocatedNumber)
2015-03-20T09:39:59 1407182180mS CMExtnEvt: Gauri Shah: Extn(3148) Calling Party Number(3148) Type(CMNTypeInternal)
2015-03-20T09:39:59 1407182181mS CMCallEvt: 260.131978.0 -1 Gauri Shah.0: StateChange: END=X CMCSDialling->CMCSCompletedTone
2015-03-20T09:39:59 1407182181mS CMExtnEvt: v=1586 State, new=CMESCompleted old=Dialling,0,0,Gauri Shah
2015-03-20T09:39:59 1407182182mS CMCallEvt: 0.131979.0 -1 BaseEP: DELETE CMEndpoint f5103b68 TOTAL NOW=3 CALL_LIST=1
2015-03-20T09:39:59 1407182182mS CMCallEvt: END CALL:37546 (f517cf90)
2015-03-20T09:39:59 1407182183mS CMMap: PCG::AddToneGenerator g B3[6] for cp[952]b0r1 append pcp[954]b0r1 (total 1)
2015-03-20T09:39:59 1407182183mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[954]b0r1 Configure 0.0
2015-03-20T09:39:59 1407182183mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[954]b0r1 ConnectIndication 0.0
2015-03-20T09:39:59 1407182184mS CMMap: a=0.0 b=0.0 pcp[952]b0r1 RTPB3
2015-03-20T09:39:59 1407182919mS SIP Tx: UDP 172.27.9.213:5060 -> 172.27.157.66:5060
OPTIONS sip:3165@172.27.9.213 SIP/2.0
Via: SIP/2.0/UDP 172.27.9.213:5060;rport;branch=z9hG4bK4bbe29fa61c1a2077fe69193de315108
From: <sip:Unknown@172.27.9.213>;tag=f2a2b328c5fae948
To: <sip:3165@172.27.9.213>
Call-ID: 43a5692efc52df8ce4a5f2ababfb8ce8
CSeq: 1863032310 OPTIONS
Contact: <sip:Unknown@172.27.9.213:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, SUBSCRIBE, REGISTER, PUBLISH, UPDATE
Supported: timer,100rel
User-Agent: IP Office 8.1 (81)
Content-Length: 0
2015-03-20T09:39:59 1407182923mS SIP Rx: UDP 172.27.157.66:5060 -> 172.27.9.213:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.27.9.213:5060;rport=5060;received=172.27.9.213;branch=z9hG4bK4bbe29fa61c1a2077fe69193de315108;alias
Call-ID: 43a5692efc52df8ce4a5f2ababfb8ce8
From: <sip:Unknown@172.27.9.213>;tag=f2a2b328c5fae948
To: <sip:3165@172.27.9.213>
CSeq: 1863032310 OPTIONS
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Accept: application/sdp, application/pidf+xml, application/xpidf+xml, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain
Supported: replaces, 100rel, norefersub
Allow-Events: presence, refer
User-Agent: Avaya B179 2.3.0
Content-Type: application/sdp
Content-Length: 338
This one gets Force Clear:
2015-03-20T09:40:00 1407183543mS CMCallPkt: v=0
CMInformation
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:40:00 1407183544mS CMExtnTx: v=3148, p1=8009
CMFacility
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:40:00 1407183545mS CMExtnRx: v=3148, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=260 id=131978 in=0
2015-03-20T09:40:00 1407183545mS CMCallEvt: 260.131978.0 -1 Gauri Shah.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
2015-03-20T09:40:00 1407183546mS CMExtnTx: v=3148, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=260 id=131978 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 20/03/15 09:40
2015-03-20T09:40:00 1407183547mS CMExtnEvt: v=1586 State, new=PortRecoverDelay old=CMESCompleted,0,0,Gauri Shah
2015-03-20T09:40:00 1407183547mS CMExtnEvt: Gauri Shah: CALL LOST (CMCauseForceClear)
2015-03-20T09:40:00 1407183547mS CMExtnEvt: Gauri Shah: Extn(3148) Calling Party Number(3148) Type(CMNTypeInternal)
2015-03-20T09:40:00 1407183547mS CMExtnEvt: Gauri Shah: CMExtnHandler::SetCurrent( id: 131978->0 )
2015-03-20T09:40:00 1407183548mS CMCallEvt: 260.131978.0 -1 Gauri Shah.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2015-03-20T09:40:00 1407183549mS CMTARGET: 260.131978.0 -1 BaseEP: ~CMTargetHandler f51e7050 ep f5170ac0
2015-03-20T09:40:00 1407183549mS CMCallEvt: 260.131978.0 -1 BaseEP: DELETE CMEndpoint f5170ac0 TOTAL NOW=2 CALL_LIST=1
2015-03-20T09:40:00 1407183551mS CMMap: a=0.0 b=0.0 pcp[952]b0r1 RTPB0
2015-03-20T09:40:00 1407183681mS CMCallPkt: v=0
CMInformation
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIECCMSMessage (5) unknown
2015-03-20T09:40:00 1407183683mS CMCallEvt: 0.131980.0 -1 BaseEP: NEW CMEndpoint f5170ac0 TOTAL NOW=3 CALL_LIST=1
2015-03-20T09:40:00 1407183683mS CMCallEvt: 0.131980.0 -1 Gauri Shah.-1: NEW CMExtnEndpoint f5170ac0, Name=Gauri Shah, Extn=3148, Phys Extn=3148
2015-03-20T09:40:00 1407183683mS CMCallEvt: CREATE CALL:37547 (f517cf90)
2015-03-20T09:40:00 1407183684mS CMCallEvt: 0.131981.0 -1 BaseEP: NEW CMEndpoint f5103b68 TOTAL NOW=4 CALL_LIST=1
2015-03-20T09:40:00 1407183685mS CMExtnEvt: Gauri Shah: CMExtnHandler::SetCurrent( id: 0->131980 )
2015-03-20T09:40:00 1407183686mS CMExtnEvt: v=1586 State, new=Idle old=PortRecoverDelay,0,0,Gauri Shah
2015-03-20T09:40:00 1407183687mS CMExtnRx: v=3148, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=260 id=131980 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[3148] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 12 item(s)
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Gauri Shah
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Gauri Shah
2015-03-20T09:40:00 1407183687mS CMCallEvt: 260.131980.0 37547 Gauri Shah.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
2015-03-20T09:40:00 1407183687mS CMExtnEvt: v=1586 State, new=Dialling old=Idle,0,0,Gauri Shah
2015-03-20T09:40:00 1407183687mS CMTARGET: 260.131980.0 37547 Gauri Shah.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=3148 dir=out complete=0 ses=0
2015-03-20T09:40:00 1407183688mS CMTARGET: 260.131980.0 37547 Gauri Shah.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
2015-03-20T09:40:00 1407183688mS CMCallEvt: 260.131980.0 37547 Gauri Shah.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
2015-03-20T09:40:00 1407183691mS CMExtnTx: v=3148, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=260 id=131980 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
Timed: 20/03/15 09:40
2015-03-20T09:40:00 1407183691mS CMExtnTx: v=3148, p1=8009
CMFacility
Line: type=IPLine 250 Call: lid=260 id=1586 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
2015-03-20T09:40:00 1407183692mS CMExtnTx: v=3148, p1=0
CMSetupAck
Now this is the fairly frequent SIP 401 unauthorized messages we get out there as well.
015-03-20T09:39:05 1407128890mS SIP Tx: UDP 172.27.9.213:5060 -> 172.27.157.66:5060
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 172.27.157.66:5060;rport;branch=z9hG4bKPjdxCRgj1Drw2iNKhShUkSk0duGMlE4o1q;alias
From: <sip:3165@172.27.9.213>;tag=q6rXPZZISj5w-2Ekk.11Cxxi7Z1zEDbR
To: <sip:3165@172.27.9.213>;tag=01fd50d62520dcd9
Call-ID: .SvV7Y2X74hsHSOF.zky6bBUBO0lGPV1
CSeq: 6447 REGISTER
User-Agent: IP Office 8.1 (81)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, SUBSCRIBE, REGISTER, PUBLISH
Digest nonce="eb035c2ccb3416e56bf1",realm="ipoffice",algorithm=MD5
Supported: timer
Server: IP Office 8.1 (81)
Content-Length: 0
2015-03-20T09:39:05 1407128894mS SIP Rx: UDP 172.27.157.66:5060 -> 172.27.9.213:5060
REGISTER sip:172.27.9.213;transport=udp SIP/2.0
Via: SIP/2.0/UDP 172.27.157.66:5060;rport;branch=z9hG4bKPjbbRM.5zPUH-8d7eH.9l0aU66Lzk4EUuv;alias
Max-Forwards: 70
From: <sip:3165@172.27.9.213>;tag=q6rXPZZISj5w-2Ekk.11Cxxi7Z1zEDbR
To: <sip:3165@172.27.9.213>
Call-ID: .SvV7Y2X74hsHSOF.zky6bBUBO0lGPV1
CSeq: 6448 REGISTER
User-Agent: Avaya B179 2.3.0
Contact: <sip:3165@172.27.157.66:5060;ob>
Expires: 1800
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="3165", realm="ipoffice", nonce="eb035c2ccb3416e56bf1", uri="sip:172.27.9.213;transport=udp", response="18f6c5c88485940002b2376cf4fec992", algorithm=MD5
Content-Length: 0
2015-03-20T09:39:05 1407128899mS SIP Tx: UDP 172.27.9.213:5060 -> 172.27.157.66:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.27.157.66:5060;rport;branch=z9hG4bKPjbbRM.5zPUH-8d7eH.9l0aU66Lzk4EUuv;alias
From: <sip:3165@172.27.9.213>;tag=q6rXPZZISj5w-2Ekk.11Cxxi7Z1zEDbR
To: <sip:3165@172.27.9.213>;tag=7bc040ddc7a83a07
Call-ID: .SvV7Y2X74hsHSOF.zky6bBUBO0lGPV1
CSeq: 6448 REGISTER
User-Agent: IP Office 8.1 (81)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, SUBSCRIBE, REGISTER, PUBLISH
Contact: <sip:3165@172.27.157.66:5060;ob>
Date: Fri, 20 Mar 2015 04:09:06 GMT
Expires: 180
Supported: timer
Server: IP Office 8.1 (81)
Content-Length: 0
If It's Not Broken...Don't Fix It!
Stephen Webb
Norstar, BCM and IP Office Engineer