Intermittent calls not ringing at reception - going straight to v/m

Wallysworld · May 12, 2015

Hello All;

ipo 8.1 with v/m pro

Live answer at reception with the AA picking up after hours.

reception 2221 - only user in h/g 2206
h/g - 2206 v/m on, no queuing, no overflow, fallback to 2207
h/g - 2207 v/m off, no queuing, no overflow, fallback to 2208 (they keep changing their mind if they want backups for recep)
OOS h/g - 2208 v/m off, no queuing, no overflow, no fallback, 1 user (2999 fwd'd to v/m)

This works 99% of the time. However, they are intermittently getting calls going straight to receptions v/m. She claims she is not on the phone and that her msg lights up with an outside callers voicemail.

I've attached the system monitor log, I'm not strong on deciphering what it's telling me and was hoping someone would be able to enlighten me if they see my issue.

Thank you, as always.

459679188mS RES: Thu 7/5/2015 13:00:55 FreeMem=57293296 55979752(1) CachedMem=1313544 CMMsg=7(9) Buff=5200 1425 1000 12403 5

Links=37765 BTree=0 CPU=1/4/3150/17587/68104/1
459679189mS RES2: IP 500 V2 8.1(95) Tasks=53 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=63 Poll=0 Ready=0 CMReady=0 CMQueue=0

VPNNQueue=0 Monitor=2 SSA=0 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
459679189mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
459689530mS PRN: SCNTFTP::SendPacket (3) seq=2219 len=53 retries=3 dest 192.168.x.x
459694713mS CMExtnRx: v=2221, p1=0
CMTransfer
Line: type=DigitalExtn 4 Call: lid=0 id=136043 in=0
459694713mS CMExtnEvt: Reception: CMExtnHandler::SetCurrent( id: 136043->0 )
459694714mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Connected,0,0,Reception
459694715mS CMCallEvt: 0.136044.0 -1 BaseEP: NEW CMEndpoint f4fa0d50 TOTAL NOW=3 CALL_LIST=1
459694715mS CMCallEvt: 0.136044.0 -1 Reception.-1: NEW CMExtnEndpoint f4fa0d50, Name=Reception, Extn=2221, Phys Extn=2221
459694715mS CMCallEvt: CREATE CALL:32832 (f4e5b078)
459694715mS CMCallEvt: 0.136045.0 -1 BaseEP: NEW CMEndpoint f4e831e8 TOTAL NOW=4 CALL_LIST=1
459694716mS CMExtnEvt: Reception: CMExtnHandler::SetCurrent( id: 0->136044 )
459694716mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Reception
459694718mS CMExtnRx: v=2221, p1=0
CMSetup
Line: type=DigitalExtn 4 Call: lid=0 id=136044 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[780xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Reception
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Reception
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2221
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceStdPhone type=109 number=49 channel=0 rx_gain=32 tx_gain=32

ep_callid=136044 ipaddr=192.168.x.x apps=0
IE CMIEMohSourceId (247) MOH Source = 1
Locale: enu
459694718mS CMCallEvt: 0.136044.0 32832 Reception.1: StateChange: END=A CMCSIdle->CMCSDialInitiated
459694718mS CMExtnEvt: v=1 State, new=Dialling old=Idle,0,0,Reception
459694718mS CMTARGET: 0.136044.0 32832 Reception.1: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=780xxxxxxx dir=out

complete=0 ses=0
459694718mS CMTARGET: 0.136044.0 32832 Reception.1: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
459694719mS CMCallEvt: 0.136044.0 32832 Reception.1: StateChange: END=A CMCSDialInitiated->CMCSDialling
459694720mS CMExtnTx: v=2221, p1=0
CMSetupAck
Line: type=DigitalExtn 4 Call: lid=0 id=136044 in=0
BChan: slot=4 chan=1
Timed: 07/05/15 13:01
459694720mS CD: CALL: 1.2.1 BState=Connected Cut=1 Music=1.0 Aend="Line 1" (1.3) Bend="" [Reception(2221)] (4.1) CalledNum=2206 (HG

Reception) CallingNum=780xxxxxxx () Internal=0 Time=30805 AState=Connected
459694720mS CD: CALL: 0.136044.0 BState=Idle Cut=1 Music=3.0 Aend="Reception(2221)" (4.1) Bend="" [] (0.0) CalledNum= ()

CallingNum=780xxxxxxx () Internal=1 Time=5 AState=DialInitiated
459694721mS CMMap: PCG::UnmapBChan pcp[78]b1r0 cp_b f585eb50 other_cp_b f503c5c8
459694721mS CMMap: a=4.1 b=1.3 M02
459694721mS CMMap: PCG::UnmapBChan pcp[175]b1r0 cp_b f503c5c8 other_cp_b 0
459694722mS CMMap: a=1.3 b=0.0 H1
459694722mS CMMap: a=4.1 b=0.0 D1
459695190mS RES: Thu 7/5/2015 13:01:11 FreeMem=57270976 55979752(1) CachedMem=1291224 CMMsg=7(9) Buff=5200 1425 1000 12404 5

Links=37765 BTree=0 CPU=1/3/3150/17590/68104/1
459695190mS RES2: IP 500 V2 8.1(95) Tasks=53 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=65 Poll=0 Ready=0 CMReady=0 CMQueue=0

VPNNQueue=0 Monitor=2 SSA=0 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
459695190mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
459695938mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,Reception: DSS Emulation DefinityUserBlf Key Pressed 34
459695938mS CMExtnEvt: Reception: CMExtnHandler::SetCurrent( id: 136044->0 )
459695939mS CMCallEvt: 0.136044.0 32832 Reception.1: StateChange: END=A CMCSDialling->CMCSCompleted
459695940mS CMLOGGING: CALL:2015/05/0713:01,00:00:00,000,780xxxxxxx,O,,,Reception,,,1,,""n/a,0
459695940mS CD: CALL: 0.136044.0 BState=Idle Cut=0 Music=0.0 Aend="Reception(2221)" (4.1) Bend="" [] (0.0) CalledNum= ()

CallingNum=780xxxxxxx () Internal=1 Time=1225 AState=Idle
459695940mS CD: CALL: 0.136044.0 Deleted
459695940mS CMExtnEvt: Reception: CALL LOST (CMCauseNormal)
459695940mS CMExtnEvt: Reception: Extn(2221) Calling Party Number(780xxxxxxx) Type(CMNTypeUnknown)
459695940mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Dialling,0,0,Reception
459695941mS CMCallEvt: 0.136044.0 -1 Reception.-1: StateChange: END=X CMCSCompleted->CMCSDelete
459695941mS CMCallEvt: 0.136045.0 -1 BaseEP: DELETE CMEndpoint f4e831e8 TOTAL NOW=3 CALL_LIST=1
459695942mS CMCallEvt: END CALL:32832 (f4e5b078)
459695942mS CMTARGET: 0.136044.0 -1 BaseEP: ~CMTargetHandler f4e6cc18 ep f4fa0d50
459695942mS CMCallEvt: 0.136044.0 -1 BaseEP: DELETE CMEndpoint f4fa0d50 TOTAL NOW=2 CALL_LIST=1
459695943mS CMCallEvt: 0.136046.0 -1 BaseEP: NEW CMEndpoint f4fa0d50 TOTAL NOW=3 CALL_LIST=1
459695943mS CMCallEvt: 0.136046.0 -1 Reception.-1: NEW CMExtnEndpoint f4fa0d50, Name=Reception, Extn=2221, Phys Extn=2221
459695943mS CMCallEvt: CREATE CALL:32833 (f4e5b078)
459695943mS CMCallEvt: 0.136047.0 -1 BaseEP: NEW CMEndpoint f4e831e8 TOTAL NOW=4 CALL_LIST=1
459695944mS CMExtnEvt: Reception: CMExtnHandler::SetCurrent( id: 0->136046 )
459695944mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Reception
459695946mS CMExtnRx: v=2221, p1=0
CMSetup
Line: type=DigitalExtn 4 Call: lid=0 id=136046 in=0
Called[2231] Type=Default (100) Reason=CMDRdirect Calling[780xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Reception
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Reception
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2221
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceStdPhone type=109 number=49 channel=0 rx_gain=32 tx_gain=32

ep_callid=136046 ipaddr=192.168.x.x apps=0
IE CMIEMohSourceId (247) MOH Source = 1
Locale: enu
459695946mS CMCallEvt: 0.136046.0 32833 Reception.1: StateChange: END=A CMCSIdle->CMCSDialInitiated
459695946mS CMExtnEvt: v=1 State, new=Dialling old=Idle,0,0,Reception
459695946mS CMTARGET: 0.136046.0 32833 Reception.1: Setting Hard Timer 4000
459695946mS CMTARGET: 0.136046.0 32833 Reception.1: LOOKUP CALL ROUTE: type=100 called_party=2231 sub= calling=780xxxxxxx dir=out

complete=0 ses=0
459695947mS CMTARGET: 0.136046.0 32833 Reception.1: ADD TARGET (N): number=2231 type=100 depth=1 nobar=1 setorig=1 ses=0
459695947mS CMTARGET: 0.136046.0 32833 Reception.1: SET USER: ZAK orig=1
459695947mS CMTARGET: 0.136046.0 32833 Reception.1: ADD USER: ZAK depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type

(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
459695947mS CMCallEvt: 0.136048.0 -1 BaseEP: NEW CMEndpoint f4ecbc08 TOTAL NOW=5 CALL_LIST=2
459695947mS CMCallEvt: 0.136048.0 -1 ZAK.-1: NEW CMExtnEndpoint f4ecbc08, Name=ZAK, Extn=2231, Phys Extn=2231
459695948mS CMTARGET: 0.136048.0 32833 ZAK.0: ADD TWINNED PRIMARY twinbusy=0
459695948mS CMCallEvt: 0.136049.0 -1 BaseEP: NEW CMEndpoint f4e541b0 TOTAL NOW=6 CALL_LIST=2
459695949mS CMCallEvt: CREATE CALL:32834 (f4f96b38)
459695949mS CMCallEvt: 0.136050.0 -1 BaseEP: NEW CMEndpoint f4fa4658 TOTAL NOW=7 CALL_LIST=2
459695951mS CMCallEvt: 0.136049.0 32834 GHOST:: StateChange: END=A CMCSIdle->CMCSDialInitiated
459695951mS CMTARGET: 0.136049.0 32834 GHOST:: LOOKUP CALL ROUTE: type=100 called_party=9416xxxxxxx sub= calling=780xxxxxxx

dir=out complete=1 ses=0
459695951mS CMTARGET: 0.136049.0 32834 GHOST:: ADD TARGET (N): number=9416xxxxxxx type=100 depth=1 nobar=1 setorig=1 ses=0
459695951mS CMTARGET: 0.136049.0 32834 GHOST:: SYS SC: 94165789225 2 416xxxxxxx sc=type=Dial3K1 code=9N, num=N callinfop-

>sending_complete=1 secondary_dialtone=
459695952mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
459695952mS CMARS: MakeCallTarget - Called Number: 416xxxxxxx
459695952mS CMCallEvt: 0.136051.0 -1 BaseEP: NEW CMEndpoint f4fc9de0 TOTAL NOW=8 CALL_LIST=3
459695952mS CMTARGET: 0.136049.0 32834 GHOST:: INITIAL TARGETING SUCCEEDED
459695953mS CMTARGET: 0.136049.0 32834 GHOST:: GetNoAnswerTimer:21
459695953mS CMCallEvt: 0.136049.0 32834 GHOST:: StateChange: END=A CMCSDialInitiated->CMCSDialled
459695953mS CMCallEvt: 0.136050.0 32834 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
459695954mS CMCallEvt: 0.136051.0 32834 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
459695954mS CMCallEvt: 0.136050.0 32834 TargetingEP: RequestEnd 0.136051.0 32834 ARS for Main
459695955mS CMTARGET: 0.136049.0 32834 GHOST:: CancelTimer CMTCNoAnswerTimeout
459695955mS CMCallEvt: 0.136051.0 32834 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
459695956mS CMARS: FORM: Main - Received Number: 416xxxxxxx
459695956mS CMARS: FOUND A SHORT CODE - short_code: XXXXXXXXXXN - Tel: N - Called_Party: 416xxxxxxx - Line Group Id: 2
459695956mS CMARS: FindActiveARSByGroupID GroupID=2 - Not Found
459695956mS CMLRQ: FindActiveLRQByGroupID GroupID=2 - Not Found
459695956mS CMCallEvt: 0.136052.0 -1 BaseEP: NEW CMEndpoint f4e4e7a0 TOTAL NOW=9 CALL_LIST=3
459695957mS CMTARGET: ISDN BChannel 15: in-service check = 1
459695957mS CMTARGET: ISDN BChannel 15: in-service check = 1
459695958mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 2 - Called Number: 416xxxxxxx - Calling Number: 780xxxxxxx
459695958mS CMARS: SEND Setup TO LINE
459695958mS CMCallEvt: 0.136052.0 32834 Q931 Trunk:1 CHAN=15: StateChange: END=child CMCSIdle->CMCSOffering
459695959mS CMCallEvt: 0.136050.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
459695959mS CMCallEvt: 0.136050.0 -1 BaseEP: DELETE CMEndpoint f4fa4658 TOTAL NOW=8 CALL_LIST=3
459695959mS CMExtnEvt: ZAK:2231 ExtnFault now 0
459695961mS CMCallEvt: 0.136053.0 -1 BaseEP: NEW CMEndpoint f4ee2098 TOTAL NOW=9 CALL_LIST=3
459695961mS CMCallEvt: 0.136053.0 -1 ZAK.-1: NEW CMExtnEndpoint f4ee2098, Name=ZAK, Extn=2231, Phys Extn=
459695962mS CMTARGET: 0.136053.0 32833 ZAK.0: ADD PRIMARY
459695962mS CMTARGET: 0.136053.0 32833 ZAK.0: ADD FNE TWINNED MOBILE: ZAK
459695962mS CMTARGET: 0.136046.0 32833 Reception.1: CancelTimer CMTCDelayedProcessing
459695962mS CMTARGET: 0.136046.0 32833 Reception.1: INITIAL TARGETING SUCCEEDED
459695962mS CMTARGET: 0.136046.0 32833 Reception.1: GetNoAnswerTimer:60
459695963mS CMCallEvt: 0.136046.0 32833 Reception.1: StateChange: END=A CMCSDialInitiated->CMCSDialling
459695963mS CMExtnTx: v=2221, p1=0
CMSetupAck
Line: type=DigitalExtn 4 Call: lid=0 id=136046 in=0
BChan: slot=4 chan=1
Timed: 07/05/15 13:01
459695963mS CMCallEvt: 0.136047.0 32833 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
459695964mS CMCallEvt: 0.136048.0 32833 ZAK.0: StateChange: END=T CMCSIdle->CMCSOffering
459695965mS CMExtnEvt: ZAK: CMExtnHandler::SetCurrent( id: 0->136048 )
459695965mS CMExtnTx: v=2231, p1=0
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=136048 in=0
Called[2231] Type=Default (100) Reason=CMDRdirect Calling[780xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=ZAK
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=ZAK
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Reception
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Reception
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2221
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceStdPhone type=109 number=49 channel=0 rx_gain=32 tx_gain=32

ep_callid=136046 ipaddr=192.168.x.x apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
Display [780xxxxxxx(Reception)>ZAK]
Timed: 07/05/15 13:01
Locale: enu
459695965mS CMExtnRx: v=2231, p1=0
CMAlerting
Line: type=DigitalExtn 6 Call: lid=0 id=136048 in=0
459695965mS CMCallEvt: 0.136048.0 32833 ZAK.0: StateChange: END=T CMCSOffering->CMCSRinging
459695966mS CMExtnEvt: v=8 State, new=Ringing old=Idle,0,0,ZAK
459695966mS CMCallEvt: 0.136047.0 32833 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
459695967mS CMCallEvt: 0.136046.0 32833 Reception.1: StateChange: END=A CMCSDialling->CMCSRingBack
459695967mS CMExtnEvt: v=1 State, new=Alerting old=Dialling,0,0,Reception
459695967mS CMExtnTx: v=2221, p1=0
CMAlerting
Line: type=DigitalExtn 4 Call: lid=0 id=136046 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=ZAK
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=ZAK
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2231
Timed: 07/05/15 13:01
459695969mS CMCallEvt: 0.136053.0 32833 ZAK.0: StateChange: END=T CMCSIdle->CMCSOffering
459695969mS CMExtnEvt: ZAK: CMExtnHandler::SetCurrent( id: 0->136053 )
459695969mS CMExtnTx: v=2231, p1=0
CMSetup
Line: type=NoLine 0 Call: lid=0 id=136053 in=0
Called[2231] Type=Default (100) Reason=CMDRdirect Calling[780xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=ZAK
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=ZAK
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Reception
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Reception
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2221
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceStdPhone type=109 number=49 channel=0 rx_gain=32 tx_gain=32

ep_callid=136046 ipaddr=192.168.x.x apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
Display [780xxxxxxx(Reception)>ZAK]
Timed: 07/05/15 13:01
Locale: enu
459695969mS CMExtnRx: v=2231, p1=0
CMAlerting
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
459695970mS CMCallEvt: 0.136053.0 32833 ZAK.0: StateChange: END=T CMCSOffering->CMCSRinging
459695970mS CMExtnEvt: v=-1 State, new=Ringing old=Idle,0,0,ZAK
459695971mS CD: CALL: 0.136046.0 BState=Ringing Cut=1 Music=2.0 Aend="Reception(2221)" (4.1) Bend="ZAK(2231)" [ZAK(2231),ZAK(2231)]

(0.0) CalledNum=2231 (ZAK) CallingNum=780xxxxxxx () Internal=1 Time=28 AState=Ringing
459695973mS CMMap: a=4.1 b=0.0 D0
459695973mS CMMap: a=4.1 b=0.0 R1
459696370mS CMExtnRx: v=2221, p1=0
CMTransfer
Line: type=DigitalExtn 4 Call: lid=0 id=136046 in=0
459696370mS CMCallEvt: CompleteTransferTo 'this' is consultation, 'tgep' is original
459696370mS CMTARGET: TransferProposal: X_call: ID=32833 Aend=2221 -> 2231
459696370mS CMTARGET: TransferProposal: Y_call: ID=32831 Aend=780xxxxxxx -> 2221
459696370mS CMTARGET: Surviving Call=32831
459696370mS CMTARGET: Goodbying Call=32833
459696371mS CMTARGET: AEND 780xxxxxxx -> 2231 BEND
459696371mS CMCallEvt: 0.136046.0 32833 Reception.1: CompleteTransfer to 0.136043.0 (0.136043.0 32831 Reception.0) (Return @ 0ms

-> null) ssdata 0
459696372mS CMExtnEvt: Reception: CALL LOST (CMCauseTransfer)
459696372mS CMExtnEvt: Reception: Extn(2221) Calling Party Number(780xxxxxxx) Type(CMNTypeUnknown)
459696373mS CMExtnTx: v=2221, p1=0
CMReleaseComp
Line: type=DigitalExtn 4 Call: lid=0 id=136043 in=0
Called[2221] Type=Unknown (0) Reason=CMDRdirect Calling[780xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
Cause=126, Transfer(IPO)
Timed: 07/05/15 13:01
459696374mS CMCallEvt: 0.136043.0 -1 Reception.-1: StateChange: END=X CMCSConnected->CMCSDelete
459696377mS CMTARGET: 0.136046.0 32833 Reception.0: ChangeOriginator: To 1.2.1 32831 Q931 Trunk:1 CHAN=2
459696378mS CMExtnTx: v=2231, p1=0
CMFacility
Line: type=DigitalExtn 6 Call: lid=0 id=136048 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[780xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
IE CMIERespondingPartyNumber (230)(P:0 S:1 T:0 N:1 R:4) number=780xxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=1 channel=2 rx_gain=32 tx_gain=32

ep_callid=2 ipaddr=192.168.x.x apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HG Reception
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=2206
Timed: 07/05/15 13:01
459696378mS CMExtnTx: v=2231, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=136053 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[780xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
IE CMIERespondingPartyNumber (230)(P:0 S:1 T:0 N:1 R:4) number=780xxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=1 channel=2 rx_gain=32 tx_gain=32

ep_callid=2 ipaddr=192.168.x.x apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HG Reception
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=2206
459696379mS CD: CALL: 1.2.1 BState=Ringing Cut=3 Music=2.0 Aend="Line 1" (1.3) Bend="ZAK(2231)" [ZAK(2231),ZAK(2231)] (0.0)

CalledNum=2231 (ZAK) CallingNum=780xxxxxxx () Internal=0 Time=32464 AState=Connected
459696379mS CMLOGGING: CALL:2015/05/0713:01,00:00:00,000,2221,O,2231,2231,Reception,,,1,,""n/a,0
459696379mS CD: CALL: 0.136046.0 BState=Idle Cut=3 Music=2.0 Aend="Reception(2221)" (4.1) Bend="ZAK(2231)" [] (0.0) CalledNum=2231

(ZAK) CallingNum=2221 (Reception) Internal=1 Time=436 AState=Ringing
459696379mS CD: CALL: 0.136046.0 Deleted
459696380mS CMExtnEvt: Reception: CALL LOST (CMCauseTransfer)
459696380mS CMExtnEvt: Reception: Extn(2221) Calling Party Number(2221) Type(CMNTypeInternal)
459696381mS CMCallEvt: 0.136046.0 -1 Reception.0: StateChange: END=X CMCSRingBack->CMCSCompleted
459696381mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Alerting,0,0,Reception
459696381mS CMExtnTx: v=2221, p1=0
CMReleaseComp
Line: type=DigitalExtn 4 Call: lid=0 id=136046 in=0
Called[2231] Type=Default (100) Reason=CMDRdirect Calling[2221] Type=Internal Plan=ISDN Pres=Allowed (0)
Cause=126, Transfer(IPO)
Timed: 07/05/15 13:01
459696382mS CMExtnEvt: Reception: CMExtnHandler::SetCurrent( id: 136046->0 )
459696382mS CMCallEvt: 0.136046.0 -1 Reception.-1: StateChange: END=X CMCSCompleted->CMCSDelete
459696383mS CMCallEvt: END CALL:32833 (f4e5b078)
459696384mS CMCallEvt: 0.136043.0 -1 BaseEP: DELETE CMEndpoint f4e42b60 TOTAL NOW=8 CALL_LIST=2
459696384mS CMCallEvt: 0.136046.0 -1 BaseEP: DELETE CMEndpoint f4fa0d50 TOTAL NOW=7 CALL_LIST=2
459696384mS CD: CALL: 1.2.1 BState=Ringing Cut=3 Music=2.0 Aend="Line 1" (1.3) Bend="ZAK(2231)" [ZAK(2231),ZAK(2231)] (0.0)

CalledNum=2231 (ZAK) CallingNum=780xxxxxxx () Internal=0 Time=32469 AState=Connected
459696385mS CMMap: a=1.3 b=0.0 H0
459696385mS CMMap: a=1.3 b=0.0 R1
459696386mS CMMap: a=4.1 b=0.0 R0
459696518mS CMTARGET: ISDN BChannel 1: in-service check = 1
459696518mS ISDNL3Evt: v=1 stacknum=1 State, new=Present, old=NullState id=9
459696520mS CMLineRx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=1 id=9 in=1
Called[xxxxxxx] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[416xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=1
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 94
user 'JUSTIN ' presentation Allowed
459696520mS PRN: Q931Trunk: Found QBChannel to match 0.1 --> 1.2
459696520mS CMCallEvt: 0.136054.0 -1 BaseEP: NEW CMEndpoint f4f8f0f8 TOTAL NOW=8 CALL_LIST=2
459696520mS CMTARGET: ISDN BChannel 1: in-service check = 1
459696520mS CMTARGET: ISDN BChannel 1: in-service check = 1
459696521mS CMCallEvt: CREATE CALL:32835 (f4e5b078)
459696521mS CMCallEvt: 0.136055.0 -1 BaseEP: NEW CMEndpoint f4fa4658 TOTAL NOW=9 CALL_LIST=2
459696523mS CD: CALL: 1.9.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (1.2) Bend="" [] (0.0) CalledNum=xxxxxxx ()

CallingNum=416xxxxxxx (JUSTIN ) Internal=0 Time=2 AState=Idle
459696523mS CMCallEvt: 1.9.1 32835 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSIdle->CMCSDialInitiated
459696523mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: LOOKUP CALL ROUTE: type=0 called_party=6781444 sub= calling=416xxxxxxx

dir=in complete=1 ses=0
459696523mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: SET BESTMATCH: length 7 vs -1 match=905xxxxxxx dest=HG Reception
459696523mS CMCallEvt: Priority hike: call 32835 priority 0->1
459696523mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: LOOKUP ICR: DDI= CGPN=416xxxxxxx Matched Time profile: Daytime

(Destination HG Reception ) => CDPN=HG Reception
459696524mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ADD TARGET (N): number=HG Reception type=0 depth=1 nobar=1 setorig=1 ses=0
459696524mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: HG(HG Reception,2206,192.168.x.x) Requires Routing To Master(1).

IsLocalExecutive(1)
459696524mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: HG call targeting occuring here
459696524mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: PrimeForHGTarget: HG Reception setorig=1 recall=0 resetExtnVars 1
459696524mS CMCallEvt: Priority hike: call 32835 priority 1->5
459696525mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: AddHGTarget HG Reception (depth=1) allowq=1 type=CMNTypeUnknown
459696525mS CMCallEvt: 0.136056.0 -1 BaseEP: NEW CMEndpoint f4fac858 TOTAL NOW=10 CALL_LIST=3
459696525mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: AddHGTargetRingGroup HG Reception starting at 0
459696525mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ADD USER: Reception depth=2 disallow_cw=0 dnd=0 real_call=1 group_call=1

type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000 simult 0 (0)
459696526mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: OV visable. VM NOT visable:Timer running
459696526mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: FindNextInServiceHgInHgIncludedList: HG Bkup Recep
459696526mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: PrimeForHGTarget: HG Bkup Recep setorig=0 recall=0 resetExtnVars 1
459696526mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: AddHGTarget HG Bkup Recep (depth=2) allowq=0 type=CMNTypeUnknown
459696526mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: AddHGTargetRingGroup HG Bkup Recep starting at 2
459696526mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ADD USER: Reception depth=3 disallow_cw=0 dnd=0 real_call=1 group_call=1

type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000 simult 0 (0)
459696527mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: OV visable. VM NOT visable:Timer running
459696527mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: FindNextInServiceHgInHgIncludedList: No Alternative!
459696527mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ADD HG HG Bkup Recep Complete search - no targets found
459696527mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: NO INITIAL TARGETS: HG Reception
459696527mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: AddHGTarget HG Reception (depth=1) allowq=0 type=CMNTypeUnknown
459696527mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: OV visable. VM NOT visable:Timer running
459696527mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: FindNextInServiceHgInHgIncludedList: No Alternative!
459696527mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: PrimeForHGTarget: HG Reception setorig=0 recall=0 resetExtnVars 1
459696528mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: AddHGTarget HG Reception (depth=2) allowq=0 type=CMNTypeUnknown
459696528mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: AddHGTargetRingGroup HG Reception starting at 0
459696528mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ADD USER: Reception depth=3 disallow_cw=0 dnd=0 real_call=1 group_call=1

type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000 simult 0 (0)
459696528mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: OV visable. VM NOT visable:Timer running
459696528mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: FindNextInServiceHgInHgIncludedList: HG Bkup Recep
459696528mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: PrimeForHGTarget: HG Bkup Recep setorig=0 recall=0 resetExtnVars 1
459696528mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: AddHGTarget HG Bkup Recep (depth=3) allowq=0 type=CMNTypeUnknown
459696529mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: AddHGTargetRingGroup HG Bkup Recep starting at 0
459696529mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ADD USER: Reception depth=4 disallow_cw=0 dnd=0 real_call=1 group_call=1

type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000 simult 0 (0)
459696529mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: OV visable. VM NOT visable:Timer running
459696529mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: FindNextInServiceHgInHgIncludedList: No Alternative!
459696529mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ADD HG HG Bkup Recep Complete search - no targets found
459696529mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: SELECT: TRY VOICEMAIL orig_hg(2206) orig_user()
459696530mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ADD VM TARGET
459696530mS CMTARGET: **** 1.9.1 32835 Q931 Trunk:1 CHAN=1: MakeVoicemailTarget pbx=<null> local=1 type=1
459696530mS CMCallEvt: 0.136057.0 -1 BaseEP: NEW CMEndpoint f4fa0d50 TOTAL NOW=11 CALL_LIST=3
459696530mS CMTARGET: 0.136057.0 32835 RAS.0: ADD PRIMARY
459696530mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ADD VM TARGET: SUCCEEDED
459696531mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: GetNoAnswerTimer:29
459696531mS CMCallEvt: 1.9.1 32835 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSDialInitiated->CMCSDialled
459696531mS CMLineTx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=1 id=9 in=1
BChan: slot=0 chan=1
459696532mS CMCallEvt: 0.136055.0 32835 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
459696533mS CMCallEvt: 0.136057.0 32835 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
459696533mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->136057 )
459696533mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#HG Reception] Type=Voicemail (102) Reason=107 SndComp Calling[416xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 94
user 'JUSTIN ' presentation Allowed
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=JUSTIN
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=HG Bkup Recep
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=JUSTIN
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=416xxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=1 channel=1 rx_gain=32 tx_gain=32

ep_callid=9 ipaddr=192.168.x.x apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HG Reception
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=2206
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=HG Reception
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:2) number=2206
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:1 R:4) number=xxxxxxx
IE CMIEMohSourceId (247) MOH Source = 1
Display [JUSTIN >HG Bkup Recep]
Timed: 07/05/15 13:01
Locale: enu
459696534mS CMMap: a=21.42 b=1.254 T
459696536mS CMExtnRx: v=RAS, p1=0
CMAlerting
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#HG Reception
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#HG Reception
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32

ep_callid=136057 ipaddr=192.168.x.x apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HG Reception
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=2206
459696536mS CMCallEvt: 0.136057.0 32835 RAS.0: StateChange: END=T CMCSOffering->CMCSRinging
459696536mS CMExtnEvt: v=1011 State, new=Ringing old=Idle,0,0,RAS
459696536mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: CancelTimer CMTCNoAnswerTimeout
459696536mS CMCallEvt: 0.136055.0 32835 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
459696537mS CMCallEvt: 1.9.1 32835 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSDialled->CMCSRingBack
459696537mS CMLineTx: v=1
CMAlerting
Line: type=Q931Line 1 Call: lid=1 id=9 in=1
BChan: slot=0 chan=1
459696538mS CD: CALL: 1.9.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.2) Bend="HG Reception(2206)" [RAS] (0.0) CalledNum=2207

(HG Bkup Recep) CallingNum=4166840241 (JUSTIN ) Internal=0 Time=17 AState=Ringing
459696539mS PRN: Config Write Wake Up
459696540mS ISDNL3Evt: v=1 stacknum=1 State, new=ICProceeding, old=Present id=9
459696540mS ISDNL3Evt: v=1 stacknum=1 State, new=Received, old=ICProceeding id=9
459696540mS CMMap: a=1.2 b=0.0 R1
459696541mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
459696541mS CMExtnRx: v=RAS, p1=0
CMVoiceMailMsg
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[LeaveMessage] Type=Default (100) Reason=CMDRdirect
Tag type=Text flags=0x1 [1.] [0x31 0x00 ]
459696541mS CMCallEvt: 0.1001.0 -1 BaseEP CMEndpoint does nothing with msg - 38 CMVoiceMailMsg
459697040mS PRN: Updates IO list size 1 updated list size 1
459697040mS PRN: Sending Updates out to f4fdcba8 started
459697040mS PRN: Sending Updates out to f4fdcba8 finished
459697040mS PRN: Config Write Completed
459697104mS PRN: Config Write Wake Up
459697604mS PRN: Updates IO list size 1 updated list size 1
459697604mS PRN: Sending Updates out to f4fdcba8 started
459697605mS PRN: Sending Updates out to f4fdcba8 finished
459697605mS PRN: Config Write Completed
459697959mS CMCallEvt: 0.136052.0 32834 Q931 Trunk:1 CHAN=15: Twin Mobile Delay Timer Expired - Initiate call to Mobile
459697959mS PRN: Setting configured voice gain for ch 15.
459697959mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=136052 in=0
Called[416xxxxxxx] Type=National (2) Reason=CMDRdirect Calling[780xxxxxxx] Type=National Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=15
459697960mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=136052
459698109mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=Initiated id=136052
459698110mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=136052 in=0
BChan: slot=0 chan=15
459698110mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
459698111mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
459698111mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.136051.0 32834 ARS for Main - Call State: CMCSOverlapRecv
459698111mS CMCallEvt: 0.136051.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
459698112mS CMCallEvt: 0.136052.0 32834 Q931 Trunk:1 CHAN=15: StateChange: END=B CMCSOffering->CMCSAccept
459698112mS CMCallEvt: 0.136051.0 -1 BaseEP: DELETE CMEndpoint f4fc9de0 TOTAL NOW=10 CALL_LIST=3
459698382mS CMExtnEvt: Reception: Recover Timer reason=CMTRWrapUp
459698382mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Reception
459698382mS CMExtnTx: v=2221, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 4 Call: lid=0 id=-1 in=0
Called[Reception Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Reception Msgs=0]
Timed: 07/05/15 13:01
459698430mS CMExtnRx: v=2231, p1=0
CMConnect
Line: type=DigitalExtn 6 Call: lid=0 id=136048 in=0
459698430mS CMCallEvt: 0.136048.0 32831 ZAK.0: StateChange: END=T CMCSRinging->CMCSConnReq
459698430mS CMCallEvt: 0.136047.0 32831 TargetingEP: RequestEnd 0.136048.0 32831 ZAK.0
459698431mS CMTARGET: 1.2.1 32831 Q931 Trunk:1 CHAN=2: CancelTimer CMTCCoverageTimeout
459698431mS CMCallEvt: 0.136047.0 -1 BaseEP: DELETE CMEndpoint f4e831e8 TOTAL NOW=9 CALL_LIST=3
459698432mS CMExtnEvt: ZAK: CALL LOST (CMCauseAnsweredByOther)
459698432mS CMExtnEvt: ZAK: Extn(2231) Calling Party Number(780xxxxxxx) Type(CMNTypeUnknown)
459698432mS CMCallEvt: 0.136053.0 -1 ZAK.0: StateChange: END=X CMCSRinging->CMCSCompleted
459698432mS CMExtnEvt: v=-1 State, new=PortRecoverDelay old=Ringing,0,0,ZAK
459698433mS CMExtnTx: v=2231, p1=0
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=136053 in=0
Called[2231] Type=Default (100) Reason=CMDRdirect Calling[780xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
Cause=124, Answered By Other(IPO)
459698433mS CMExtnEvt: ZAK: CMExtnHandler::SetCurrent( id: 136053->0 )
459698433mS CMCallEvt: 0.136053.0 -1 ZAK.-1: StateChange: END=X CMCSCompleted->CMCSDelete
459698434mS CMCallEvt: 0.136049.0 32834 GHOST:: StateChange: END=A CMCSDialled->CMCSCompleted
459698434mS CMLOGGING: CALL:2015/05/0713:01,00:00:00,000,780xxxxxxx,O,416xxxxxxx,9416xxxxxxx,ZAK,,,0,,""n/a,0
459698435mS CMCallEvt: 0.136049.0 -1 GHOST:: CallLost (Ghost @) cause=CMCauseAnsweredByOther
459698435mS CMCallEvt: 0.136049.0 -1 GHOST:: StateChange: END=X CMCSCompleted->CMCSDelete
459698435mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=136052 in=0
Cause=124, Answered By Other(IPO)
459698436mS CMCallEvt: 0.136052.0 -1 Q931 Trunk:1 CHAN=15: StateChange: END=X CMCSAccept->CMCSCompleted
459698436mS CMCallEvt: END CALL:32834 (f4f96b38)
459698437mS CMTARGET: 0.136049.0 -1 BaseEP: ~CMTargetHandler f4e48c78 ep f4e541b0
459698437mS CMCallEvt: 0.136049.0 -1 BaseEP: DELETE CMEndpoint f4e541b0 TOTAL NOW=8 CALL_LIST=2
459698437mS CMCallEvt: 0.136053.0 -1 BaseEP: DELETE CMEndpoint f4ee2098 TOTAL NOW=7 CALL_LIST=2
459698438mS CMExtnEvt: v=8 State, new=Connected old=Ringing,0,0,ZAK
459698438mS CMCallEvt: 0.136048.0 32831 ZAK.0: StateChange: END=B CMCSConnReq->CMCSConnected
459698438mS CMTARGET: 1.2.1 32831 Q931 Trunk:1 CHAN=2: ~CMTargetHandler f4e6cc18 ep f4fb07f8
459698439mS CMExtnTx: v=2231, p1=0
CMConnectAck
Line: type=DigitalExtn 6 Call: lid=0 id=136048 in=0
IE CMIERespondingPartyNumber (230)(P:0 S:1 T:0 N:1 R:4) number=780xxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=1 channel=2 rx_gain=32 tx_gain=32

ep_callid=2 ipaddr=192.168.x.x apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HG Reception
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=2206
Timed: 07/05/15 13:01
459698441mS PRN: ZAK: Feature Key Pressed 19

CPLFeatureOffHook
459698441mS CD: CALL: 1.2.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.3) Bend="ZAK(2231)" [ZAK(2231)] (8.10) CalledNum=2206

(HG Reception) CallingNum=780xxxxxxx () Internal=0 Time=34526 AState=Connected
459698442mS CMMap: a=1.3 b=0.0 R0
459698443mS CMMap: PCG::MapBChan pcp[78]b1r0 cp_b f585eb50 other_cp_b 0 type CGTypeSimple
459698443mS CMMap: PCG::MapBChan pcp[122]b1r0 cp_b f581e500 other_cp_b f585eb50 type CGTypeSimple
459698443mS CMMap: a=1.3 b=8.10 M12
459698443mS CMExtnEvt: ZAK: Recover Timer reason=CMTRWrapUp
459698444mS CMExtnEvt: v=-1 State, new=Idle old=PortRecoverDelay,0,0,ZAK
459698444mS CMExtnTx: v=2231, p1=0
CMVoiceMailStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[ZAK Msgs=0 Old=1 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [ZAK Msgs=0]
459698445mS ISDNL3Evt: v=1 stacknum=1 State, new=DiscReq, old=Proceeding id=136052
459698510mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=DiscReq id=136052
459698511mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=136052 in=0
BChan: slot=0 chan=15
459698511mS CMCallEvt: 0.136052.0 -1 Q931 Trunk:1 CHAN=15: StateChange: END=X CMCSCompleted->CMCSDelete
459698511mS CMTARGET: ISDN BChannel 15: in-service check = 1
459698511mS CMTARGET: ISDN BChannel 15: in-service check = 1
459698512mS CMCallEvt: 0.136052.0 -1 BaseEP: DELETE CMEndpoint f4e4e7a0 TOTAL NOW=6 CALL_LIST=2
459698536mS CMExtnEvt: ZAK:2231 ExtnFault now 1
459702535mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=1011 in=0
459702535mS CMCallEvt: 0.136057.0 32835 RAS.0: StateChange: END=T CMCSRinging->CMCSConnReq
459702535mS CMCallEvt: 0.136055.0 32835 TargetingEP: RequestEnd 0.136057.0 32835 RAS.0
459702536mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: Removed HG from Callinfo
459702536mS CMCallEvt: 0.136056.0 32835 ACDTep(HG Reception): StateChange: END=??? CMCSIdle->CMCSDelete
459702536mS CMCallEvt: 0.136056.0 -1 BaseEP: DELETE CMEndpoint f4fac858 TOTAL NOW=5 CALL_LIST=2
459702536mS CMCallEvt: 0.136055.0 -1 BaseEP: DELETE CMEndpoint f4fa4658 TOTAL NOW=4 CALL_LIST=2
459702537mS CMExtnEvt: v=1011 State, new=Connected old=Ringing,0,0,RAS
459702537mS CMCallEvt: 1.9.1 32835 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSRingBack->CMCSOGConnReq
459702538mS CMLineTx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=1 id=9 in=1
BChan: slot=0 chan=1
Display [++f#HG Reception,]
459702538mS CD: CALL: 1.9.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.2) Bend="HG Reception(2206)" [VoiceMail] (21.42)

CalledNum=#HG Reception (HG Bkup Recep) CallingNum=416xxxxxxx (JUSTIN ) Internal=0 Time=6017 AState=Connected
459702539mS CMMap: a=1.2 b=0.0 R0
459702539mS CMMap: PCG::MapBChan pcp[77]b1r0 cp_b f585ee78 other_cp_b 0 type CGTypeSimple
459702539mS CMMap: PCG::MapBChan pcp[32]b1r0 cp_b f5897758 other_cp_b f585ee78 type CGTypeSimple
459702539mS CMMap: a=1.2 b=21.42 M12
459702540mS PRN: Config Write Wake Up
459702540mS ISDNL3Evt: v=1 stacknum=1 State, new=ConnReq, old=Received id=9
459702609mS ISDNL3Evt: v=1 stacknum=1 State, new=Active, old=ConnReq id=9
459702610mS CMLineRx: v=1
CMConnectAck
Line: type=Q931Line 1 Call: lid=1 id=9 in=1
459702610mS CMCallEvt: 1.9.1 32835 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSOGConnReq->CMCSConnected
459702610mS CMTARGET: 1.9.1 32835 Q931 Trunk:1 CHAN=1: ~CMTargetHandler f4e57a98 ep f4f8f0f8
459702611mS CMCallEvt: 0.136057.0 32835 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
459702612mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[416xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=JUSTIN
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=416xxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=1 channel=1 rx_gain=32 tx_gain=32

ep_callid=9 ipaddr=192.168.x.x apps=0
459702690mS RES: Thu 7/5/2015 13:01:18 FreeMem=57271520 55979752(1) CachedMem=1291768 CMMsg=7(9) Buff=5200 1425 1000 12396 5

Links=37751 BTree=0 CPU=4/7/3150/17394/68104/1
459702690mS RES2: IP 500 V2 8.1(95) Tasks=53 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=66 Poll=0 Ready=0 CMReady=0 CMQueue=0

VPNNQueue=0 Monitor=2 SSA=0 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
459702691mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
459703040mS PRN: Updates IO list size 1 updated list size 1
459703040mS PRN: Sending Updates out to f4fdcba8 started
459703041mS PRN: Sending Updates out to f4fdcba8 finished
459703041mS PRN: Config Write Completed
459703517mS CMTARGET: ISDN BChannel 3: in-service check = 1
459703517mS ISDNL3Evt: v=1 stacknum=1 State, new=Present, old=NullState id=3
459703518mS CMLineRx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=1 id=3 in=1
Called[xxxxxxx] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[715xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=3
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageUnknown
invokeId 95
user '' presentation Restricted
459703519mS PRN: Q931Trunk: Found QBChannel to match 0.3 --> 1.4
459703519mS CMCallEvt: 0.136058.0 -1 BaseEP: NEW CMEndpoint f4e4e7a0 TOTAL NOW=5 CALL_LIST=2
459703519mS CMTARGET: ISDN BChannel 3: in-service check = 1
459703519mS CMTARGET: ISDN BChannel 3: in-service check = 1
459703519mS CMCallEvt: CREATE CALL:32836 (f4f96b38)
459703520mS CMCallEvt: 0.136059.0 -1 BaseEP: NEW CMEndpoint f4fa4658 TOTAL NOW=6 CALL_LIST=2
459703521mS CD: CALL: 1.3.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (1.4) Bend="" [] (0.0) CalledNum=xxxxxxx ()

CallingNum=7152342334 () Internal=0 Time=2 AState=Idle
459703522mS CMCallEvt: 1.3.1 32836 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSIdle->CMCSDialInitiated
459703522mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: LOOKUP CALL ROUTE: type=0 called_party=xxxxxxx sub= calling=715xxxxxxx

dir=in complete=1 ses=0
459703522mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: SET BESTMATCH: length 7 vs -1 match=905xxxxxxx dest=2334
459703522mS CMCallEvt: Priority hike: call 32836 priority 0->1
459703522mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: LOOKUP ICR: DDI= CGPN=715xxxxxxx (Destination 2334 ) => CDPN=2334
459703522mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: ADD TARGET (N): number=2334 type=0 depth=1 nobar=1 setorig=1 ses=0
459703523mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: SET USER: PIETER orig=1
459703523mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: ADD USER: PIETER depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0

type(CMNTypeUnknown) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
459703523mS CMCallEvt: 0.136060.0 -1 BaseEP: NEW CMEndpoint f4ee2098 TOTAL NOW=7 CALL_LIST=3
459703523mS CMCallEvt: 0.136060.0 -1 PIETER.-1: NEW CMExtnEndpoint f4ee2098, Name=PIETER, Extn=2334, Phys Extn=2334
459703524mS CMTARGET: 0.136060.0 32836 PIETER.0: ADD PRIMARY
459703524mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: INITIAL TARGETING SUCCEEDED
459703525mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: GetNoAnswerTimer:21
459703525mS CMCallEvt: 1.3.1 32836 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSDialInitiated->CMCSDialled
459703525mS CMLineTx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=1 id=3 in=1
BChan: slot=0 chan=3
459703526mS CMCallEvt: 0.136059.0 32836 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
459703527mS CMCallEvt: 0.136060.0 32836 PIETER.0: StateChange: END=T CMCSIdle->CMCSOffering
459703527mS CMExtnEvt: PIETER: CMExtnHandler::SetCurrent( id: 0->136060 )
459703527mS CMExtnTx: v=2334, p1=0
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=136060 in=0
Called[2334] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[715xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageUnknown
invokeId 95
user '' presentation Restricted
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=PIETER
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=PIETER
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=715xxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=1 channel=3 rx_gain=32 tx_gain=32

ep_callid=3 ipaddr=192.168.x.x apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:1 R:4) number=xxxxxxx
IE CMIEMohSourceId (247) MOH Source = 1
Display [715xxxxxxx>PIETER]
Timed: 07/05/15 13:01
Locale: enu
459703527mS CMExtnRx: v=2334, p1=0
CMAlerting
Line: type=DigitalExtn 6 Call: lid=0 id=136060 in=0
459703528mS CMCallEvt: 0.136060.0 32836 PIETER.0: StateChange: END=T CMCSOffering->CMCSRinging
459703528mS CMExtnEvt: v=25 State, new=Ringing old=Idle,0,0,PIETER
459703528mS CMCallEvt: 0.136059.0 32836 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
459703529mS CMCallEvt: 1.3.1 32836 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSDialled->CMCSRingBack
459703529mS CMLineTx: v=1
CMAlerting
Line: type=Q931Line 1 Call: lid=1 id=3 in=1
BChan: slot=0 chan=3
459703530mS CD: CALL: 1.3.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.4) Bend="PIETER(2334)" [PIETER(2334)] (0.0)

CalledNum=2334 (PIETER) CallingNum=715xxxxxxx () Internal=0 Time=11 AState=Ringing
459703532mS CMMap: a=1.4 b=0.0 R1
459703532mS ISDNL3Evt: v=1 stacknum=1 State, new=ICProceeding, old=Present id=3
459703533mS ISDNL3Evt: v=1 stacknum=1 State, new=Received, old=ICProceeding id=3
459704937mS SSL VPN [AVAYA_SUPPORT]: Session state change [WaitingToStart] -> [ResolveDomainName]
459704937mS ERR: SSL VPN [AVAYA_SUPPORT]: ERROR: DNS server address is not configured for neither the primary nor the backup server
459704937mS SSL VPN [AVAYA_SUPPORT]: End session
459704937mS SSL VPN [AVAYA_SUPPORT]: Session state change [ResolveDomainName] -> [Idle]
459704938mS SSL VPN [AVAYA_SUPPORT]: Session state change [Idle] -> [WaitingToStart]
459704938mS SSL VPN [AVAYA_SUPPORT]: Restart session in 60 seconds

********** SysMonitor v10.1 (95) [connected to 192.168.x.x (Action_Miss_01)] **********
459708239mS PRN: Monitor Status IP 500 V2 8.1(95)
459708239mS PRN: LAW=U PRI=1, BRI=0, ALOG=8, VCOMP=20, MDM=0, WAN=0, MODU=2 LANM=0 CkSRC=1 VMAIL=1(VER=3 TYP=1) 1-X=0 CALLS=3

(TOT=32836)
459713525mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: TimerExpired cause=CMTCCoverageTimeout
459713525mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: GetNoAnswerTimer:21
459715445mS CMExtnRx: v=2334, p1=0
CMConnect
Line: type=DigitalExtn 6 Call: lid=0 id=136060 in=0
459715445mS CMCallEvt: 0.136060.0 32836 PIETER.0: StateChange: END=T CMCSRinging->CMCSConnReq
459715446mS CMCallEvt: 0.136059.0 32836 TargetingEP: RequestEnd 0.136060.0 32836 PIETER.0
459715446mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: CancelTimer CMTCNoAnswerTimeout
459715446mS CMCallEvt: 0.136059.0 -1 BaseEP: DELETE CMEndpoint f4fa4658 TOTAL NOW=6 CALL_LIST=3
459715446mS CMExtnEvt: v=25 State, new=Connected old=Ringing,0,0,PIETER
459715447mS CMCallEvt: 1.3.1 32836 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSRingBack->CMCSOGConnReq
459715447mS CMLineTx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=1 id=3 in=1
Called[2334] Type=Internal (101) Reason=CMDRdirect
BChan: slot=0 chan=3
459715448mS PRN: PIETER: Feature Key Pressed 19

CPLFeatureOffHook
459715449mS CD: CALL: 1.3.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.4) Bend="PIETER(2334)" [PIETER(2334)] (8.27)

CalledNum=2334 (PIETER) CallingNum=715xxxxxxx () Internal=0 Time=11930 AState=Connected
459715450mS ISDNL3Evt: v=1 stacknum=1 State, new=ConnReq, old=Received id=3
459715450mS CMMap: a=1.4 b=0.0 R0
459715450mS CMMap: PCG::MapBChan pcp[79]b1r0 cp_b f585e828 other_cp_b 0 type CGTypeSimple
459715450mS CMMap: PCG::MapBChan pcp[139]b1r0 cp_b f5804150 other_cp_b f585e828 type CGTypeSimple
459715450mS CMMap: a=1.4 b=8.27 M12
459715511mS ISDNL3Evt: v=1 stacknum=1 State, new=Active, old=ConnReq id=3
459715512mS CMLineRx: v=1
CMConnectAck
Line: type=Q931Line 1 Call: lid=1 id=3 in=1
459715512mS CMCallEvt: 1.3.1 32836 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSOGConnReq->CMCSConnected
459715512mS CMTARGET: 1.3.1 32836 Q931 Trunk:1 CHAN=3: ~CMTargetHandler f4e57a98 ep f4e4e7a0
459715513mS CMCallEvt: 0.136060.0 32836 PIETER.0: StateChange: END=B CMCSConnReq->CMCSConnected
459715514mS CMExtnTx: v=2334, p1=0
CMConnectAck
Line: type=DigitalExtn 6 Call: lid=0 id=136060 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[715xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=7152342334
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceISDNTrunk type=2 number=1 channel=3 rx_gain=32 tx_gain=32

ep_callid=3 ipaddr=192.168.x.x apps=0
Timed: 07/05/15 13:01
459715695mS RES: Thu 7/5/2015 13:01:31 FreeMem=57250832 55979752(1) CachedMem=1271080 CMMsg=7(9) Buff=5200 1425 1000 12404 5

Links=37741 BTree=0 CPU=4/9/3150/17353/68104/1
459715695mS RES2: IP 500 V2 8.1(95) Tasks=53 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=66 Poll=0 Ready=0 CMReady=0 CMQueue=0

VPNNQueue=0 Monitor=2 SSA=0 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
459715695mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
459723592mS PRN: Config Write Wake Up
459724092mS PRN: Updates IO list size 1 updated list size 1
459724092mS PRN: Sending Updates out to f4fdcba8 started
459724093mS PRN: Sending Updates out to f4fdcba8 finished
459724093mS PRN: Config Write Completed
459729161mS ISDNL3Evt: v=1 stacknum=1 State, new=ReleaseReq, old=Active id=9
459729212mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=ReleaseReq id=9
459729212mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=1 id=9 in=1
BChan: slot=0 chan=1
Cause=16, Normal call clearing
459729213mS CMCallEvt: 1.9.1 32835 Q931 Trunk:1 CHAN=1: StateChange: END=A CMCSConnected->CMCSCompleted
459729214mS CMLOGGING: CALL:2015/05/0713:01,00:00:26,006,416xxxxxxx,I,2207,xxxxxxx,JUSTIN,,,0,,""n/a,0
459729214mS CD: CALL: 1.9.1 BState=Connected Cut=2 Music=0.0 Aend="Line 1" (1.2) Bend="HG Reception(2206)" [VoiceMail] (21.42)

CalledNum=#HG Reception (HG Bkup Recep) CallingNum=416xxxxxxx (JUSTIN ) Internal=0 Time=32693 AState=Idle
459729214mS CD: CALL: 1.9.1 Deleted
459729214mS CMCallEvt: 1.9.1 -1 Q931 Trunk:1 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
459729214mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
459729215mS CMCallEvt: 0.136057.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
459729215mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
459729215mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#HG Reception] Type=Voicemail (102) Reason=107 Calling[416xxxxxxx] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=21 chan=42
Cause=16, Normal call clearing
459729216mS CMMap: a=21.42 b=0.0 T0
459729217mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 136057->0 )
459729217mS CMCallEvt: 0.136057.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
459729217mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
459729217mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
Cause=16, Normal call clearing
459729217mS CMCallEvt: 0.136057.0 -1 BaseEP: DELETE CMEndpoint f4fa0d50 TOTAL NOW=5 CALL_LIST=2
459729217mS CMCallEvt: END CALL:32835 (f4e5b078)
459729218mS CMTARGET: ISDN BChannel 1: in-service check = 1
459729218mS CMTARGET: ISDN BChannel 1: in-service check = 1
459729219mS CMCallEvt: 1.9.1 -1 BaseEP: DELETE CMEndpoint f4f8f0f8 TOTAL NOW=4 CALL_LIST=2
459729220mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f5897758 other_cp_b f585ee78
459729220mS CMMap: a=1.2 b=21.42 M02
459729220mS CMMap: PCG::UnmapBChan pcp[77]b1r0 cp_b f585ee78 other_cp_b 0
459729267mS CMExtnTx: v=2221, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 4 Call: lid=0 id=-1 in=0
Called[Reception Msgs=1 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000001] Type=Default Plan=Default
Display [Reception Msgs=1]
Timed: 07/05/15 13:01
459729695mS RES: Thu 7/5/2015 13:01:45 FreeMem=57272896 55979752(1) CachedMem=1293144 CMMsg=7(9) Buff=5200 1425 1000 12404 5

Links=37754 BTree=0 CPU=8/12/3150/17446/68104/1
459729695mS RES2: IP 500 V2 8.1(95) Tasks=53 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=65 Poll=0 Ready=0 CMReady=0 CMQueue=0

VPNNQueue=0 Monitor=2 SSA=0 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
459729696mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
459734928mS CMExtnEvt: HARRY: No user activity
459754111mS PRN: Config Write Wake Up
459754611mS PRN: Updates IO list size 1 updated list size 1
459754611mS PRN: Sending Updates out to f4fdcba8 started
459754612mS PRN: Sending Updates out to f4fdcba8 finished
459754612mS PRN: Config Write Completed
459757826mS CMExtnEvt: Reception: No user activity
459758440mS CMExtnEvt: ZAK: No user activity
459764938mS SSL VPN [AVAYA_SUPPORT]: Session state change [WaitingToStart] -> [ResolveDomainName]

Gunnaro · May 13, 2015

I recommend downgrading to R8.1.85 first (Chassis and VMPro).
R8.1.95 has funny bugs.

Kind regards

Gunnar
______________________________________
Mille viae ducunt homines per saecula Romam

IPGuru · May 13, 2015

2 fairly obvious issue spring to mind

1) no call Queing on main group, when everyone is busy/out of group the call will rough straight to VM
2) overflow group 208 is programmed in the wrong location, it should be in the overflow group list of the main group.

A Maintenance contract is essential, not a Luxury.
Do things on the cheap & it will cost you dear

Wallysworld · May 13, 2015

Thanks again guys;

I wasn't aware of the 8.1.95 issue. Thanks, Gunnaro. Will look into downgrading although I'm pretty sure I brought it up to .95 after they first complained about this issue.

IPG - you are correct... good eye. 208 is in the main group. My flowchart was inaccurate.
Without the call queuing a call does go directly to v/m (they do keep changing their mind about backups) but if reception is on the phone a 2nd call does come through (collective call waiting and user setting).

Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

Intermittent calls not ringing at reception - going straight to v/m

Wallysworld

Technical User

Gunnaro

Vendor

IPGuru

Vendor

Wallysworld

Technical User

Similar threads

Part and Inventory Search

Sponsor