call goes directly to reception v/m - intermittently

[Wallysworld]

Hi Guys;

IPO 8.1.73
v/m pro

I'm having an issue with calls intermittently going to voicemail when reception is not on the phone. Calls come into a reception hg and then forward to a bkup reception hg then to voicemail. It functions normally most of the time, but once in a while, the call goes directly to v/m without ringing on the phone.
I've had monitor running on it and have been looking at the results but am having trouble deciphering where the problem is. I'm hoping one of you wizards can assist with why this is happening.

Thank you in advance for any suggestions/assistance...

Line: type=Q931Line 1 Call: lid=1 id=13 in=1
Called[6781444] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[15063755045] 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.CodePageISO8859-1
invokeId 96
user 'Bruce Hallett -' presentation Allowed
2596293641mS CMCallEvt: 0.80089.0 -1 BaseEP: NEW CMEndpoint f4ec0ea8 TOTAL NOW=9 CALL_LIST=4
2596293641mS CMTARGET: ISDN BChannel 3: in-service check = 1
2596293641mS CMTARGET: ISDN BChannel 3: in-service check = 1
2596293641mS CMCallEvt: CREATE CALL:17958 (f4ed5138)
2596293641mS CMCallEvt: 0.80090.0 -1 BaseEP: NEW CMEndpoint f4f9fe60 TOTAL NOW=10 CALL_LIST=4
2596293643mS CD: CALL: 1.13.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (1.4) Bend="" [] (0.0) CalledNum=6781444 () CallingNum=15063755045 (Bruce Hallett -) Internal=0 Time=2 AState=Idle
2596293643mS CMCallEvt: 1.13.1 17958 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSIdle->CMCSDialInitiated
2596293644mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: LOOKUP CALL ROUTE: type=0 called_party=6781444 sub= calling=15063755045 dir=in complete=1 ses=0
2596293644mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: SET BESTMATCH: length 7 vs -1 match=9056781444 dest=HG Reception
2596293644mS CMCallEvt: Priority hike: call 17958 priority 0->1
2596293644mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: LOOKUP ICR: DDI= CGPN=15063755045 Matched Time profile: Daytime (Destination HG Reception ) => CDPN=HG Reception
2596293644mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: ADD TARGET (N): number=HG Reception type=0 depth=1 nobar=1 setorig=1 ses=0
2596293645mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: HG(HG Reception,2206,192.168.1.45) Requires Routing To Master(1). IsLocalExecutive(1)
2596293645mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: HG call targeting occuring here
2596293645mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: PrimeForHGTarget: HG Reception setorig=1 recall=0 resetExtnVars 1
2596293645mS CMCallEvt: Priority hike: call 17958 priority 1->5
2596293645mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: AddHGTarget HG Reception (depth=1) allowq=1 type=CMNTypeUnknown
2596293645mS CMCallEvt: 0.80091.0 -1 BaseEP: NEW CMEndpoint f4fcdf98 TOTAL NOW=11 CALL_LIST=5
2596293646mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: AddHGTargetRingGroup HG Reception starting at 0
2596293646mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: 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)
2596293646mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: OV visable. VM NOT visable:Timer running
2596293646mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: FindNextInServiceHgInHgIncludedList: HG Bkup Recep
2596293646mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: PrimeForHGTarget: HG Bkup Recep setorig=0 recall=0 resetExtnVars 1
2596293646mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: AddHGTarget HG Bkup Recep (depth=2) allowq=0 type=CMNTypeUnknown
2596293647mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: AddHGTargetRingGroup HG Bkup Recep starting at 0
2596293647mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: 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)
2596293647mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: OV visable. VM NOT visable:Timer running
2596293647mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: FindNextInServiceHgInHgIncludedList: No Alternative!
2596293647mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: ADD HG HG Bkup Recep Complete search - no targets found
2596293647mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: NO INITIAL TARGETS: HG Reception
2596293647mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: AddHGTarget HG Reception (depth=1) allowq=0 type=CMNTypeUnknown
2596293648mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: OV visable. VM NOT visable:Timer running
2596293648mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: FindNextInServiceHgInHgIncludedList: No Alternative!
2596293648mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: PrimeForHGTarget: HG Reception setorig=0 recall=0 resetExtnVars 1
2596293648mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: AddHGTarget HG Reception (depth=2) allowq=0 type=CMNTypeUnknown
2596293648mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: AddHGTargetRingGroup HG Reception starting at 0
2596293648mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: 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)
2596293649mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: OV visable. VM NOT visable:Timer running
2596293649mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: FindNextInServiceHgInHgIncludedList: HG Bkup Recep
2596293649mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: PrimeForHGTarget: HG Bkup Recep setorig=0 recall=0 resetExtnVars 1
2596293649mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: AddHGTarget HG Bkup Recep (depth=3) allowq=0 type=CMNTypeUnknown
2596293649mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: AddHGTargetRingGroup HG Bkup Recep starting at 1
2596293649mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: 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)
2596293649mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: OV visable. VM NOT visable:Timer running
2596293650mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: FindNextInServiceHgInHgIncludedList: No Alternative!
2596293650mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: ADD HG HG Bkup Recep Complete search - no targets found
2596293650mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: SELECT: TRY VOICEMAIL orig_hg(2206) orig_user()
2596293650mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: ADD VM TARGET
2596293650mS CMTARGET: **** 1.13.1 17958 Q931 Trunk:1 CHAN=3: MakeVoicemailTarget pbx=<null> local=1 type=1
2596293651mS CMCallEvt: 0.80092.0 -1 BaseEP: NEW CMEndpoint f4fbd3c8 TOTAL NOW=12 CALL_LIST=5
2596293651mS CMTARGET: 0.80092.0 17958 RAS.0: ADD PRIMARY
2596293651mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: ADD VM TARGET: SUCCEEDED
2596293651mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: GetNoAnswerTimer:29
2596293651mS CMCallEvt: 1.13.1 17958 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSDialInitiated->CMCSDialled
2596293652mS CMLineTx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=1 id=13 in=1
BChan: slot=0 chan=3
2596293652mS CMCallEvt: 0.80090.0 17958 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
2596293653mS CMCallEvt: 0.80092.0 17958 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
2596293653mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->80092 )
2596293654mS 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[15063755045] 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 96
user 'Bruce Hallett -' presentation Allowed
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Bruce Hallett -
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=HG Bkup Recep
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Bruce Hallett -
IE CMIERespondingPartyNumber (230)(P:0 S:1 T:0 N:1 R:4) number=15063755045
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=13 ipaddr=192.168.1.45 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=6781444
IE CMIEMohSourceId (247) MOH Source = 1
Display [Bruce Hallett ->HG Bkup Recep]
Timed: 16/07/14 09:40
Locale: enu
2596293656mS 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=80092 ipaddr=192.168.1.45 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
2596293656mS CMCallEvt: 0.80092.0 17958 RAS.0: StateChange: END=T CMCSOffering->CMCSRinging
2596293656mS CMExtnEvt: v=1011 State, new=Ringing old=Idle,0,0,RAS
2596293656mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: CancelTimer CMTCNoAnswerTimeout
2596293657mS CMCallEvt: 0.80090.0 17958 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
2596293657mS CMCallEvt: 1.13.1 17958 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSDialled->CMCSRingBack
2596293657mS CMLineTx: v=1
CMAlerting
Line: type=Q931Line 1 Call: lid=1 id=13 in=1
BChan: slot=0 chan=3
2596293658mS CD: CALL: 1.13.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.4) Bend="HG Reception(2206)" [RAS] (0.0) CalledNum=2207 (HG Bkup Recep) CallingNum=15063755045 (Bruce Hallett -) Internal=0 Time=17 AState=Ringing
2596294714mS CMExtnEvt: Reception: Recover Timer reason=CMTRWrapUp
2596294714mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Reception
2596294715mS 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: 16/07/14 09:40
2596294716mS CMExtnEvt: JOHN: Recover Timer reason=CMTRWrapUp
2596294716mS CMExtnEvt: v=14 State, new=Idle old=PortRecoverDelay,0,0,JOHN
2596294717mS CMExtnTx: v=2237, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
Called[JOHN Msgs=1 Old=2 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000001] Type=Default Plan=Default
Display [JOHN Msgs=1]
Timed: 16/07/14 09:40
2596299656mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=1011 in=0
2596299656mS CMCallEvt: 0.80092.0 17958 RAS.0: StateChange: END=T CMCSRinging->CMCSConnReq
2596299656mS CMCallEvt: 0.80090.0 17958 TargetingEP: RequestEnd 0.80092.0 17958 RAS.0
2596299657mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: Removed HG from Callinfo
2596299657mS CMCallEvt: 0.80091.0 17958 ACDTep(HG Reception): StateChange: END=??? CMCSIdle->CMCSDelete
2596299658mS CMCallEvt: 0.80091.0 -1 BaseEP: DELETE CMEndpoint f4fcdf98 TOTAL NOW=11 CALL_LIST=5
2596299658mS CMCallEvt: 0.80090.0 -1 BaseEP: DELETE CMEndpoint f4f9fe60 TOTAL NOW=10 CALL_LIST=5
2596299658mS CMExtnEvt: v=1011 State, new=Connected old=Ringing,0,0,RAS
2596299659mS CMCallEvt: 1.13.1 17958 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSRingBack->CMCSOGConnReq
2596299659mS CMLineTx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=1 id=13 in=1
BChan: slot=0 chan=3
Display [++f#HG Reception,]
2596299660mS CD: CALL: 1.13.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.4) Bend="HG Reception(2206)" [VoiceMail] (21.42) CalledNum=#HG Reception (HG Bkup Recep) CallingNum=15063755045 (Bruce Hallett -) Internal=0 Time=6018 AState=Connected
2596299729mS CMLineRx: v=1
CMConnectAck
Line: type=Q931Line 1 Call: lid=1 id=13 in=1
2596299729mS CMCallEvt: 1.13.1 17958 Q931 Trunk:1 CHAN=3: StateChange: END=A CMCSOGConnReq->CMCSConnected
2596299730mS CMTARGET: 1.13.1 17958 Q931 Trunk:1 CHAN=3: ~CMTargetHandler f4fe91d8 ep f4ec0ea8
2596299730mS CMCallEvt: 0.80092.0 17958 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
2596299731mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[15063755045] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Bruce Hallett -
IE CMIERespondingPartyNumber (230)(P:0 S:1 T:0 N:1 R:4) number=15063755045
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=13 ipaddr=192.168.1.45 apps=0
2596299743mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
2596299744mS 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 ]
2596299744mS CMLineTx: v=1
CMVoiceMailMsg
Line: type=Q931Line 1 Call: lid=1 id=13 in=1
BChan: slot=0 chan=3
Tag type=Text flags=0x1 [1.] [0x31 0x00 ]
2596299753mS CD: CALL: 1.13.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.4) Bend="HG Reception(2206)" [VoiceMail] (21.42) CalledNum=#HG Reception (HG Bkup Recep) CallingNum=15063755045 (Bruce Hallett -) Internal=0 Time=6112 AState=Connected
2596300292mS CMCallEvt: 0.80038.0 17943 MAUREEN.0: StateChange: END=B CMCSConnected->CMCSCompleted
2596300292mS CMExtnEvt: v=20 State, new=PortRecoverDelay old=Connected,0,0,MAUREEN
2596300293mS CMLOGGING: CALL:2014/07/1609:30,00:09:19,004,6479862785,I,2206,6781444,THOMASJ.FORTH,,,0,,""n/a,0
2596300293mS CD: CALL: 1.7.1 BState=Disconnecting Cut=1 Music=0.0 Aend="Line 1" (1.3) Bend="MAUREEN(2245)" [MAUREEN(2245)] (8.22) CalledNum=2206 (HG Reception) CallingNum=6479862785 (THOMAS J. FORTH) Internal=0 Time=564351 AState=Connected
2596300294mS CD: CALL: 1.7.1 Deleted
2596300294mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=1 id=7 in=1
Cause=16, Normal call clearing
2596300295mS CMCallEvt: 1.7.1 -1 Q931 Trunk:1 CHAN=2: StateChange: END=X CMCSConnected->CMCSCompleted
2596300295mS CMExtnEvt: MAUREEN: CALL LOST (CMCauseNormal)
2596300295mS CMExtnEvt: MAUREEN: Extn(2245) Calling Party Number(6479862785) Type(CMNTypeUnknown)
2596300295mS CMExtnEvt: MAUREEN: CMExtnHandler::SetCurrent( id: 80038->0 )
2596300296mS CMCallEvt: 0.80038.0 -1 MAUREEN.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2596300296mS CMCallEvt: END CALL:17943 (f4fbb500)
2596300296mS CMCallEvt: 0.80038.0 -1 BaseEP: DELETE CMEndpoint f4fcaa20 TOTAL NOW=9 CALL_LIST=4
2596300430mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=1 id=7 in=1
BChan: slot=0 chan=2
2596300430mS CMCallEvt: 1.7.1 -1 Q931 Trunk:1 CHAN=2: StateChange: END=X CMCSCompleted->CMCSDelete
2596300430mS CMTARGET: ISDN BChannel 2: in-service check = 1
2596300430mS CMTARGET: ISDN BChannel 2: in-service check = 1
2596300431mS CMCallEvt: 1.7.1 -1 BaseEP: DELETE CMEndpoint f4fd2a00 TOTAL NOW=8 CALL_LIST=4
2596302296mS CMExtnEvt: MAUREEN: Recover Timer reason=CMTRWrapUp
2596302296mS CMExtnEvt: v=20 State, new=Idle old=PortRecoverDelay,0,0,MAUREEN
2596302297mS CMExtnTx: v=2245, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
Called[MAUREEN Msgs=0 Old=6 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [MAUREEN Msgs=0]
Timed: 16/07/14 09:40
2596309588mS CMTARGET: ISDN BChannel 2: in-service check = 1
2596309589mS CMLineRx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=1 id=23 in=1
Called[6787742] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[6563407110] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=2
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageUnknown
invokeId 97
user '' presentation Restricted
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDOrigNotISDN (3)
2596309590mS CMCallEvt: 0.80093.0 -1 BaseEP: NEW CMEndpoint f4fd2a00 TOTAL NOW=9 CALL_LIST=4
2596309590mS CMTARGET: ISDN BChannel 2: in-service check = 1
2596309590mS CMTARGET: ISDN BChannel 2: in-service check = 1
2596309590mS CMCallEvt: CREATE CALL:17959 (f4fbb500)
2596309590mS CMCallEvt: 0.80094.0 -1 BaseEP: NEW CMEndpoint f4f9fe60 TOTAL NOW=10 CALL_LIST=4
2596309592mS CD: CALL: 1.23.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (1.3) Bend="" [] (0.0) CalledNum=6787742 () CallingNum=6563407110 () Internal=0 Time=2 AState=Idle
2596309592mS CMCallEvt: 1.23.1 17959 Q931 Trunk:1 CHAN=2: StateChange: END=A CMCSIdle->CMCSDialInitiated
2596309593mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: LOOKUP CALL ROUTE: type=0 called_party=6787742 sub= calling=6563407110 dir=in complete=1 ses=0
2596309593mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: SET BESTMATCH: length 7 vs -1 match=9056787742 dest=7204
2596309593mS CMCallEvt: Priority hike: call 17959 priority 0->1
2596309593mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: LOOKUP ICR: DDI= CGPN=6563407110 (Destination 7204 ) => CDPN=7204
2596309593mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: ADD TARGET (N): number=7204 type=0 depth=1 nobar=1 setorig=1 ses=0
2596309594mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: SET USER: Accounting Fax orig=1
2596309594mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: ADD USER: Accounting Fax 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)
2596309594mS CMCallEvt: 0.80095.0 -1 BaseEP: NEW CMEndpoint f4fcaa20 TOTAL NOW=11 CALL_LIST=5
2596309594mS CMCallEvt: 0.80095.0 -1 Accounting Fax.-1: NEW CMExtnEndpoint f4fcaa20, Name=Accounting Fax, Extn=7204, Phys Extn=7204
2596309595mS CMTARGET: 0.80095.0 17959 Accounting Fax.0: ADD PRIMARY
2596309596mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: INITIAL TARGETING SUCCEEDED
2596309596mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: GetNoAnswerTimer:21
2596309596mS CMCallEvt: 1.23.1 17959 Q931 Trunk:1 CHAN=2: StateChange: END=A CMCSDialInitiated->CMCSDialled
2596309596mS CMLineTx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=1 id=23 in=1
BChan: slot=0 chan=2
2596309597mS CMCallEvt: 0.80094.0 17959 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
2596309598mS CMCallEvt: 0.80095.0 17959 Accounting Fax.0: StateChange: END=T CMCSIdle->CMCSOffering
2596309598mS CMExtnEvt: Accounting Fax: CMExtnHandler::SetCurrent( id: 0->80095 )
2596309598mS CMExtnTx: v=7204, p1=0
CMSetup
Line: type=AnalogueExtn 19 Call: lid=0 id=2 in=0
Called[7204] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[6563407110] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=2 chan=16
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageUnknown
invokeId 97
user '' presentation Restricted
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDOrigNotISDN (3)
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Accounting Fax
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Accounting Fax
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=6563407110
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=23 ipaddr=192.168.1.45 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:1 R:4) number=6787742
IE CMIEMohSourceId (247) MOH Source = 1
UUI type=Local [......2Pd..] [0x01 0x01 0x06 0x00 0x01 0x02 0x32 0x50 0x64 0x01 0x00 ]
Display [External]
Timed: 16/07/14 09:40
Locale: enu
2596309599mS CD: CALL: 1.23.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (1.3) Bend="Accounting Fax(7204)" [Accounting Fax(7204)] (0.0) CalledNum=7204 (Accounting Fax) CallingNum=6563407110 () Internal=0 Time=9 AState=Dialled
2596309601mS CMExtnRx: v=7204, p1=0
CMAlerting
Line: type=AnalogueExtn 19 Call: lid=0 id=2 in=0
2596309601mS CMCallEvt: 0.80095.0 17959 Accounting Fax.0: StateChange: END=T CMCSOffering->CMCSRinging
2596309601mS CMExtnEvt: v=2 State, new=Ringing old=Idle,0,0,Accounting Fax
2596309602mS CMCallEvt: 0.80094.0 17959 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
2596309602mS CMCallEvt: 1.23.1 17959 Q931 Trunk:1 CHAN=2: StateChange: END=A CMCSDialled->CMCSRingBack
2596309602mS CMLineTx: v=1
CMAlerting
Line: type=Q931Line 1 Call: lid=1 id=23 in=1
BChan: slot=0 chan=2
2596309603mS CD: CALL: 1.23.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.3) Bend="Accounting Fax(7204)" [Accounting Fax(7204)] (0.0) CalledNum=7204 (Accounting Fax) CallingNum=6563407110 () Internal=0 Time=13 AState=Ringing
2596316501mS CMExtnRx: v=7204, p1=0
CMConnect
Line: type=AnalogueExtn 19 Call: lid=0 id=2 in=0
2596316501mS CMCallEvt: 0.80095.0 17959 Accounting Fax.0: StateChange: END=T CMCSRinging->CMCSConnReq
2596316501mS CMCallEvt: 0.80094.0 17959 TargetingEP: RequestEnd 0.80095.0 17959 Accounting Fax.0
2596316501mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: CancelTimer CMTCCoverageTimeout
2596316501mS CMCallEvt: 0.80094.0 -1 BaseEP: DELETE CMEndpoint f4f9fe60 TOTAL NOW=10 CALL_LIST=5
2596316502mS CMExtnEvt: v=2 State, new=Connected old=Ringing,0,0,Accounting Fax
2596316502mS CMCallEvt: 1.23.1 17959 Q931 Trunk:1 CHAN=2: StateChange: END=A CMCSRingBack->CMCSOGConnReq
2596316503mS CMLineTx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=1 id=23 in=1
Called[7204] Type=Internal (101) Reason=CMDRdirect
BChan: slot=0 chan=2
2596316503mS CD: CALL: 1.23.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (1.3) Bend="Accounting Fax(7204)" [Accounting Fax(7204)] (2.16) CalledNum=7204 (Accounting Fax) CallingNum=6563407110 () Internal=0 Time=6913 AState=Connected
2596316531mS CMLineRx: v=1
CMConnectAck
Line: type=Q931Line 1 Call: lid=1 id=23 in=1
2596316531mS CMCallEvt: 1.23.1 17959 Q931 Trunk:1 CHAN=2: StateChange: END=A CMCSOGConnReq->CMCSConnected
2596316532mS CMTARGET: 1.23.1 17959 Q931 Trunk:1 CHAN=2: ~CMTargetHandler f4fe91d8 ep f4fd2a00
2596316532mS CMCallEvt: 0.80095.0 17959 Accounting Fax.0: StateChange: END=B CMCSConnReq->CMCSConnected
2596316533mS CMExtnTx: v=7204, p1=0
CMConnectAck
Line: type=AnalogueExtn 19 Call: lid=0 id=2 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6563407110] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=2 chan=16
IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=6563407110
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=23 ipaddr=192.168.1.45 apps=0
Timed: 16/07/14 09:40
2596319225mS CMExtnRx: v=2225, p1=0
CMReleaseComp
Line: type=DigitalExtn 6 Call: lid=0 id=80003 in=0
2596319226mS CMCallEvt: 0.80003.0 17936 VINCE.0: StateChange: END=A CMCSConnected->CMCSCompleted
2596319226mS CMExtnEvt: v=3 State, new=PortRecoverDelay old=Connected,0,0,VINCE
2596319227mS CMLOGGING: CALL:2014/07/1609:26,00:13:22,000,2225,O,19059795630,919059795630,VINCE,,,0,,""n/a,0
2596319227mS CD: CALL: 0.80003.0 BState=Connected Cut=2 Music=0.0 Aend="VINCE(2225)" (8.5) Bend="Line 1" [Line 1] (1.16) CalledNum=19059795630 () CallingNum=2225 (VINCE) Internal=0 Time=834869 AState=Idle
2596319228mS CD: CALL: 0.80003.0 Deleted
2596319228mS CMExtnEvt: VINCE: CALL LOST (CMCauseNormal)
2596319228mS CMExtnEvt: VINCE: Extn(2225) Calling Party Number(2225) Type(CMNTypeInternal)
2596319228mS CMExtnEvt: VINCE: CMExtnHandler::SetCurrent( id: 80003->0 )
2596319228mS CMCallEvt: 0.80003.0 -1 VINCE.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2596319229mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=80006 in=0
Cause=16, Normal call clearing
2596319229mS CMCallEvt: 0.80006.0 -1 Q931 Trunk:1 CHAN=15: StateChange: END=X CMCSConnected->CMCSCompleted
2596319230mS CMCallEvt: END CALL:17936 (f4fa1330)
2596319230mS CMCallEvt: 0.80003.0 -1 BaseEP: DELETE CMEndpoint f4f63800 TOTAL NOW=9 CALL_LIST=4
2596319482mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=80006 in=0
BChan: slot=0 chan=15
2596319483mS CMCallEvt: 0.80006.0 -1 Q931 Trunk:1 CHAN=15: StateChange: END=X CMCSCompleted->CMCSDelete
2596319483mS CMTARGET: ISDN BChannel 15: in-service check = 1
2596319483mS CMTARGET: ISDN BChannel 15: in-service check = 1
2596319483mS CMCallEvt: 0.80006.0 -1 BaseEP: DELETE CMEndpoint f4ed26e8 TOTAL NOW=8 CALL_LIST=4
2596321228mS CMExtnEvt: VINCE: Recover Timer reason=CMTRWrapUp
2596321228mS CMExtnEvt: v=3 State, new=Idle old=PortRecoverDelay,0,0,VINCE
2596321229mS CMExtnTx: v=2225, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
Called[VINCE Msgs=0 Old=13 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [VINCE Msgs=0]
Timed: 16/07/14 09:40
2596323353mS 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 [0.] [0x30 0x00 ]
2596323354mS CMLineTx: v=1
CMVoiceMailMsg
Line: type=Q931Line 1 Call: lid=1 id=13 in=1
BChan: slot=0 chan=3
Tag type=Text flags=0x1 [0.] [0x30 0x00 ]
2596323355mS 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: 16/07/14 09:40
2596324896mS CMExtnEvt: LEO: No user activity
2596327286mS CMExtnRx: v=RAS, p1=0
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
2596327286mS CMCallEvt: 0.80092.0 17958 RAS.0: StateChange: END=B CMCSConnected->CMCSCompleted
2596327286mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
2596327287mS CMLOGGING: CALL:2014/07/1609:40,00:00:27,006,15063755045,I,2207,6781444,BruceHallett-,,,0,,""n/a,0
2596327287mS CD: CALL: 1.13.1 BState=Disconnecting Cut=1 Music=0.0 Aend="Line 1" (1.4) Bend="HG Reception(2206)" [VoiceMail] (21.42) CalledNum=#HG Reception (HG Bkup Recep) CallingNum=15063755045 (Bruce Hallett -) Internal=0 Time=33646 AState=Connected
2596327287mS CD: CALL: 1.13.1 Deleted
2596327288mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=1 id=13 in=1
Cause=16, Normal call clearing
2596327288mS CMCallEvt: 1.13.1 -1 Q931 Trunk:1 CHAN=3: StateChange: END=X CMCSConnected->CMCSCompleted
2596327288mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
2596327288mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 80092->0 )
2596327289mS CMCallEvt: 0.80092.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
2596327289mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
2596327289mS 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
2596327290mS CMCallEvt: END CALL:17958 (f4ed5138)
2596327291mS CMCallEvt: 0.80092.0 -1 BaseEP: DELETE CMEndpoint f4fbd3c8 TOTAL NOW=7 CALL_LIST=3
2596327432mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=1 id=13 in=1
BChan: slot=0 chan=3
2596327432mS CMCallEvt: 1.13.1 -1 Q931 Trunk:1 CHAN=3: StateChange: END=X CMCSCompleted->CMCSDelete
2596327432mS CMTARGET: ISDN BChannel 3: in-service check = 1
2596327433mS CMTARGET: ISDN BChannel 3: in-service check = 1
2596327433mS CMCallEvt: 1.13.1 -1 BaseEP: DELETE CMEndpoint f4ec0ea8 TOTAL NOW=6 CALL_LIST=3

 

[Wallysworld]

Hi Guys;
any chance of someone helping me out with this? I can see the call is being rejected by the receptionist, just not sure why.

thanks again.

 

[AvayaNACR]

Since you are on 8.1 do you have IPOSS support on the system?

Have you opened a ticket on it?

I recall we had a customer with this issue, but without the hunt groups. They upgraded to 9.0.3 and the issue resolved

Good Luck

 

[andynetcon]

You could try upgrading to 8.1.81. Also knowing what the call flow looks like would be helpful. It's ringing to the first hunt group where your receptionist is the only member? Is queuing on? What kind of hunt group? What are your timers? Does the receptionist have multiple appearance buttons?

 

[Wallysworld]

incoming calls go to reception (only member in hunt group).
no queuing
collective call waiting
no answer timer 29 secs, voicemail answer time 30 secs.
overflow used to go to 2nd hg but they changed their minds and only wanted reception answering calls, so no overflow.

I will try upgrading them to .81.

Thanks for responding.

 

[Telecomboy]

I did have this issue with R8.1 also. Randomly calls would go straight to vm versus ring reception (reception was a hunt group with 1 phone in the group). Avaya told me to delete and recreate the user to fix. I did do that, but I also upgraded them to the latest R8.1.81 so it is possible the upgrade was the real fix.