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!

Register Log in
  • Congratulations Mike Lewis on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

Calls not routing to VM. Keep recycling in phone system...

Status
Not open for further replies.
Jontu Kontar

Jontu Kontar

IS-IT--Management
Jun 3, 2010
33
US
tl;dr Calls stay in HG queue until VM timer expires. Then they get transfered back into HG queue. Continue, ad nauseum.

This issue started within that last few months (when were were on R6 of IPO). I have remade HG's, Users, and even upgraded IPO to R8 (soon to be R8.1). I'm at a loss to explain where things are getting lost. Can someone help me decipher the log excerpt to figure out the issue (or tell me what settings I need to get the monitor to output some useful logs).


Code: 
 6234mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: PrimeForHGTarget: PT Engine VRTC setorig=0 recall=0 resetExtnVars 1
 630406234mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: AddHGTarget PT Engine VRTC (depth=0) allowq=0 type=CMNTypeDefault 
 630406234mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: AddHGTargetRingGroup PT Engine VRTC starting at 0
 630406234mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: OV visable. VM NOT visable:Timer running
 630406235mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: FindNextInServiceHgInHgIncludedList: No Alternative!
 630406235mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: ADD HG PT Engine VRTC Complete search - no targets found
 630407172mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: ActionVoicemailTimeout
 630407172mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: SELECT: TRY VOICEMAIL orig_hg(603) orig_user()
 630407172mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: ADD VM TARGET
 630407173mS CMTARGET:     ****   5.15.1 18840 Q931 Trunk:5 CHAN=2: MakeVoicemailTarget pbx=<null> local=1 type=1
 630407173mS CMCallEvt:    0.88256.0 -1 BaseEP: NEW CMEndpoint f4dc0318 TOTAL NOW=6 CALL_LIST=2
 630407173mS CMTARGET:     0.88256.0 18840 RAS.0: ADD PRIMARY
 630407173mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: ADD VM TARGET: SUCCEEDED
 630407173mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: ActionVoicemailTimeout: Targeting VM...
 630407174mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: SelectTargetAvailable 1 targets
 630407175mS CMCallEvt:    0.88256.0 18840 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
 630407175mS CMExtnEvt:    RAS: CMExtnHandler::SetCurrent( id: 0->88256 )
 630407175mS CMExtnTx: v=RAS, p1=0
            CMSetup
            Line: type=RAS 1 Call: lid=0 id=1011 in=0
            Called[#PT Engine VRTC] Type=Voicemail (102) Reason=107 SndComp Calling[3194270087] Type=Unknown Plan=ISDN Pres=Allowed (0) 
            BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
            BChan: slot=21 chan=42
            IE CMIECalledPartyName (224)(Type=CMNameDefault) name=PT Engine VRTC
            IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=PT Engine VRTC
            IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:3) number=603
            IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=PT Engine VRTC
            IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=603
            IE CMIEIcrPriorityDetail (239) Priority = 1
            IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=978
            IE CMIEMohSourceId (247) MOH Source = 1
            IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=3194270087
            IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=8 class=CMDeviceISDNTrunk type=2 number=5 channel=2 rx_gain=32 tx_gain=32 ep_callid=15 ipaddr=206.93.84.111 apps=8
            Display [3194270087>PT Engine VRTC]
            Tag type=Text flags=0x1 [Engines / Vortec.] [0x45 0x6e 0x67 0x69 0x6e 0x65 0x73 0x20 0x2f 0x20 0x56 0x6f 0x72 0x74 0x65 0x63 0x00 ]
            Timed: 22/07/12 16:41
            Locale: enu
 630407176mS CMMap: a=21.42 b=1.254 T
 630407178mS CMExtnRx: v=RAS, p1=0
            CMConnect
            Line: type=NoLine 0 Call: lid=0 id=-1 in=0
            IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#PT Engine VRTC
            IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
            IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=88256 ipaddr=206.93.84.111 apps=8
            IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=PT Engine VRTC
            IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=603
 630407178mS CMCallEvt:    0.88256.0 18840 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
 630407178mS CMCallEvt:    0.88255.0 18840 TargetingEP: RequestEnd 0.88256.0 18840 RAS.0
 630407180mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: Removed HG from Callinfo
 630407180mS CMCallEvt:    0.88254.0 18840 ACDTep(PT Engine VRTC): StateChange: END=??? CMCSRinging->CMCSDelete
 630407180mS CMCallEvt:    0.88255.0 -1 BaseEP: DELETE CMEndpoint f4dbed38 TOTAL NOW=5 CALL_LIST=2
 630407182mS CMExtnEvt:    v=1011 State, new=Connected  old=Idle,0,0,RAS
 630407182mS CMCallEvt:    0.88256.0 18840 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
 630407183mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: ~CMTargetHandler f4dd60f4 ep f4e3a7e8
 630407183mS CMExtnTx: v=RAS, p1=0
            CMConnectAck
            Line: type=RAS 1 Call: lid=0 id=1011 in=0
            BChan: slot=21 chan=42
            IE CMIERespondingPartyNumber (230)(P:0 S:3 T:0 N:1 R:4) number=3194270087
            IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=8 class=CMDeviceISDNTrunk type=2 number=5 channel=2 rx_gain=32 tx_gain=32 ep_callid=15 ipaddr=206.93.84.111 apps=8
            IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=PT Engine VRTC
            IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=603
            Tag type=Text flags=0x1 [Engines / Vortec.] [0x45 0x6e 0x67 0x69 0x6e 0x65 0x73 0x20 0x2f 0x20 0x56 0x6f 0x72 0x74 0x65 0x63 0x00 ]
 630407185mS CMCallEvt:    0.88254.0 -1 BaseEP: DELETE CMEndpoint f4e335c4 TOTAL NOW=4 CALL_LIST=2
 630407185mS CD: CALL: 5.15.1 BState=Connected Cut=3 Music=0.0 Aend="Line 5" (3.3) Bend="PT Engine VRTC(603)" [VoiceMail] (21.42) CalledNum=#PT Engine VRTC (PT Engine VRTC) CallingNum=3194270087 () Internal=0 Time=45127 AState=Connected
 630407186mS CMMap: a=3.3 b=0.0 R0
 630407186mS CMMap: PCG::MapBChan pcp[127]b1r0 cp_b f57a6e68 other_cp_b 0 type CGTypeSimple
 630407187mS CMMap: PCG::MapBChan pcp[76]b1r0 cp_b f57e5aa4 other_cp_b f57a6e68 type CGTypeSimple
 630407187mS CMMap: a=3.3 b=21.42 M1
 630407187mS PRN: Config Write Wake Up
 630407188mS CMExtnRx: v=RAS, p1=0
            CMFacility
            Line: type=NoLine 0 Call: lid=0 id=-1 in=0
            Called[CLAIM] Type=ACD (103) Reason=CMDRdirect  
 630407191mS CD: CALL: 5.15.1 BState=Connected Cut=3 Music=0.0 Aend="Line 5" (3.3) Bend="PT Engine VRTC(603)" [VoiceMail] (21.42) CalledNum=#PT Engine VRTC (PT Engine VRTC) CallingNum=3194270087 () Internal=0 Time=45133 AState=Connected
 630407199mS CMExtnRx: v=RAS, p1=0
            CMTransfer
            Line: type=NoLine 0 Call: lid=0 id=-1 in=0
            Called[603] Type=Default (100) Reason=CMDRdirect  SndComp 
            Tag type=Text flags=0x1 [Engines / Vortec.] [0x45 0x6e 0x67 0x69 0x6e 0x65 0x73 0x20 0x2f 0x20 0x56 0x6f 0x72 0x74 0x65 0x63 0x00 ]
 630407200mS CMCallEvt:    0.88256.0 18840 RAS.0: Transfer CMCauseTransfer
 630407200mS PRN: CDR - ResetQueueSize=500
 630407201mS PRN: CDR - TCPSend maxqueuesize=500 operational=0
 630407201mS ERR: CDRServer discarding - framecount=500
 630407201mS CD: CALL: 5.15.1 BState=Connected Cut=3 Music=0.0 Aend="Line 5" (3.3) Bend="PT Engine VRTC(603)" [VoiceMail] (21.42) CalledNum=#PT Engine VRTC (PT Engine VRTC) CallingNum=3194270087 () Internal=0 Time=45143 AState=Connected
 630407201mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: ADD TARGET (N): number=603 type=100 depth=1 nobar=1 setorig=1 ses=0
 630407202mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: HG(PT Engine VRTC,603,206.93.84.111) Requires Routing To Master(1). IsLocalExecutive(1)
 630407202mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: HG call targeting occuring here
 630407202mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: PrimeForHGTarget: PT Engine VRTC setorig=1 recall=0 resetExtnVars 1
 630407202mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: AddHGTarget PT Engine VRTC (depth=1) allowq=1 type=CMNTypeDefault 
 630407203mS CMCallEvt:    0.88257.0 -1 BaseEP: NEW CMEndpoint f4e335c4 TOTAL NOW=5 CALL_LIST=2
 630407203mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: PrepareTransferTargets Found 1 target
 630407203mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: GetNoAnswerTimer:20
 630407204mS CMCallEvt:    0.88258.0 -1 BaseEP: NEW CMEndpoint f4dbed38 TOTAL NOW=6 CALL_LIST=2
 630407206mS CMExtnEvt:    RAS: CALL LOST (CMCauseTransfer)
 630407206mS CMCallEvt:    0.88256.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
 630407206mS CMExtnEvt:    v=1011 State, new=PortRecoverDelay  old=Connected,0,0,RAS
 630407206mS CMExtnTx: v=RAS, p1=0
            CMReleaseComp
            Line: type=RAS 1 Call: lid=0 id=1011 in=0
            Called[#PT Engine VRTC] Type=Voicemail (102) Reason=107 Calling[3194270087] Type=Unknown Plan=ISDN Pres=Allowed (0) 
            BChan: slot=21 chan=42
            Cause=126, Transfer(IPO)
 630407207mS CMMap: a=21.42 b=0.0 T0
 630407208mS CMExtnEvt:    RAS: CMExtnHandler::SetCurrent( id: 88256->0 )
 630407208mS CMCallEvt:    0.88256.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
 630407209mS CMExtnEvt:    v=1011 State, new=Idle  old=PortRecoverDelay,0,0,RAS
 630407209mS CMExtnTx: v=RAS, p1=0
            CMReleaseComp
            Line: type=RAS 1 Call: lid=0 id=1011 in=0
            BChan: slot=21 chan=42
            Cause=126, Transfer(IPO)
 630407209mS CMCallEvt:    0.88258.0 18840 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
 630407210mS CMCallEvt:    0.88257.0 18840 ACDTep(PT Engine VRTC): StateChange: END=T CMCSIdle->CMCSOffering
 630407210mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: CancelTimer CMTCNoAnswerTimeout
 630407211mS CMCallEvt:    0.88257.0 18840 ACDTep(PT Engine VRTC): StateChange: END=T CMCSOffering->CMCSRinging
 630407211mS CMCallEvt:    0.88258.0 18840 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
 630407214mS CMCallEvt:    0.88256.0 -1 BaseEP: DELETE CMEndpoint f4dc0318 TOTAL NOW=5 CALL_LIST=2
 630407215mS CMMap: PCG::UnmapBChan pcp[76]b1r0 cp_b f57e5aa4 other_cp_b f57a6e68
 630407215mS CMMap: a=3.3 b=21.42 M0
 630407215mS CMMap: PCG::UnmapBChan pcp[127]b1r0 cp_b f57a6e68 other_cp_b 0
 630407215mS CMMap: a=3.3 b=0.0 R1
 630407216mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: PrimeForHGTarget: PT Engine VRTC setorig=0 recall=0 resetExtnVars 1
 630407216mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: AddHGTarget PT Engine VRTC (depth=0) allowq=0 type=CMNTypeDefault 
 630407216mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: AddHGTargetRingGroup PT Engine VRTC starting at 0
 630407216mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: OV visable. VM NOT visable:Timer running
 630407217mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: FindNextInServiceHgInHgIncludedList: No Alternative!
 630407217mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: ADD HG PT Engine VRTC Complete search - no targets found
 630407217mS CD: CALL: 5.15.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 5" (3.3) Bend="PT Engine VRTC(603)" [] (0.0) CalledNum=603 (PT Engine VRTC) CallingNum=3194270087 () Internal=0 Time=45159 AState=Connected
 630407319mS RES: Sun 22/7/2012 16:41:12 FreeMem=56972096(2) CMMsg=9 (10) Buff=5200 960 883 7411 4 Links=3995
 630407320mS RES2: IP 500 8.0(44) Tasks=39 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=100 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=2 TCP=46 TAPI=12 CF=UMNT
 630407687mS PRN: Updates IO list size 1 updated list size 1
 630407687mS PRN: Sending Updates out to f4effee8 started
 630407687mS PRN: Sending Updates out to f4effee8 finished
 630407688mS PRN: Config Write Completed
 630408217mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: PrimeForHGTarget: PT Engine VRTC setorig=0 recall=0 resetExtnVars 1
 630408217mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: AddHGTarget PT Engine VRTC (depth=0) allowq=0 type=CMNTypeDefault 
 630408217mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: AddHGTargetRingGroup PT Engine VRTC starting at 0
 630408217mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: OV visable. VM NOT visable:Timer running
 630408217mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: FindNextInServiceHgInHgIncludedList: No Alternative!
 630408218mS CMTARGET:     5.15.1 18840 Q931 Trunk:5 CHAN=2: ADD HG PT Engine VRTC Complete search - no targets found
 
Sort by date Sort by votes
Simply follow your route from the ICR and work from there. It shouldn't be hard to figure out why its doing it.

ACSS - SME
General Geek



1832163.png
 
Upvote 0 Downvote
Must be in the VM Module, the trace only shows that VM answered the call and then transfers it back to the group were it is routed back to voicemail ands so on as you can see in my first respond.
Just start in VM Pro! Reconsider what is setup and is it really doing what you want it to do.
If you cant figure that out then post youre requirements in a new thread and you'll get a answer, i'm pretty sure about that.

If it ain't dutch it ain't much
 
Upvote 0 Downvote
It's possible in voicemail pro that the group's leave mail is set to transfer back out to the group. Hence, instead of leaving a message it just routes it back to the group to ring.
 
Upvote 0 Downvote
In order to root out this issue, I've dumbed down the routing to:

Code: 
Incoming Call Route:
  DID -> VM:PT Engine VRTC

VoiceMail Module:
  PT Engine VRTC:
    Start -> Transfer to 603 (PT Engine VRTC HG)

[ol 1]
[li]Call comes in on DID.[/li]
[li]Transfer to VM Module: PT Engine VRTC.[/li]
[li]Transfer to Hunt Group: PT Engine VRTC.[/li]
[li]Transfer to HG mail box when VM timer elapses.[/li]
[/ol]


Telecomboy said:
group's leave mail is set to transfer back out to the group
Click to expand...

Thank's for the the idea! I don't remember setting up any "Entry Points" for the PT Engine VRTC Hunt Group and checking on it reveals none.
 
Upvote 0 Downvote
It won't go to the HG mailbox as your have given the module the same name as the group. This is why it goes back to the module when the VM timer expires.

rename your Module and it will be happy again.

Jamie Green

Football is not a matter of life and death-It is far more important!!!!
 
Upvote 0 Downvote
Indeed, modules can't be named the same as groups, just doesn't work :)

 
Upvote 0 Downvote
If the module just transfers it to the group, why not just send the call straight there. Why do you need VM to handle this??? Does a play a recording before transferring??

Jamie Green

Football is not a matter of life and death-It is far more important!!!!
 
Upvote 0 Downvote
Status
Not open for further replies.

Similar threads

quazimotto
  • Question
VM Pro Not Seeing IIS Installed
Replies
6
Views
352
quazimotto
quazimotto
joestar
  • Question
Caller ID (CLID) Not Passing to Twinned Phone with SIP
Replies
7
Views
487
Menniss
Menniss
dbb1234
  • Locked
  • Question
Avaya Twinned Phone call going directly to phone VM?
Replies
3
Views
151
IPOTS
I
K
  • Locked
  • Question
96xx IP Phone not update.
Replies
7
Views
503
derfloh
derfloh
ipcom123
  • Locked
  • Question
Missed calls logs in Avaya Phones
Replies
7
Views
684
gkittelson
gkittelson

Part and Inventory Search

Sponsor

Back
Top