TSP Errors

dnmann · ‎02-05-2002

Unity 3.1(2) TSP 3.1(2). Sometimes the Unity ports do not forward and users get a busy signal. It happens when subscribers try to access voicemail or outside users call in to the openeing greeting. I have a ton of warning messages in the Application log relating to the TSP. They are: Warning EVENT ID 111 AvCiscoTsp_MC AvCiscoTsp Device 7: Failed blind transfer to extension 5425 (This one shows up with differnent device numbers and different extensions) Error Miu_MC EVENT ID 525 Component Miu: Thread 0x0000096C had a Failure on Port 2 in Method CAvMiuLine::Transfer(eMIU_XFER_RELEASE) DESCRIPTION: Timed-out waiting for LINECALLSTATE_IDLE after lineBlindTransfer. DETAILS: HCALL: 0x000104AB CallState: LINECALLSTATE_CONNECTED DestAddress: 5324. CALLINFO: CallerID: 9 CalledID: 2802 RedirectingID: 2802 Origin: Internal Reason: FwdUncond Trunk: 0. CALL SEQUENCE: [15:07:56:265 - 0x00000994] Drop() - S_OK [15:07:56:265 - 0x00000994] Transfer(RELEASE to 4600) - S_OK [15:08:03:781 - 0x0000105C] LINE_APPNEWCALL(0x000104AB) [15:08:03:781 - 0x0000105C] SetEvent(NewCall) [15:08:03:906 - 0x000009AC] Answer() entered [15:08:03:906 - 0x0000105C] LINE_REPLY(0x00010713 | 0x00000000) [15:08:03:906 - 0x000009AC] lineAnswer(0x000104AB) - 0x00010713 [15:08:05:453 - 0x0000105C] LINECALLSTATE_CONNECTED(0x000104AB | Active) [15:08:05:453 - 0x000009AC] WaitFor(0x00010713 | CONNECTED) - CONNECTED [15:08:05:453 - 0x000009AC] Answer() - S_MIU_CONNECTED [15:08:05:718 - 0x000009AC] Play() entered [15:08:05:718 - 0x000009AC] WavOpen(0x0AE3B080) - 0x0A6A2CFC [15:08:05:718 - 0x000009AC] WavGetLength(0x0A6A2CFC) - 10794 [15:08:05:718 - 0x000009AC] WavClose(0x0A6A2CFC) - 0x00000000 [15:08:05:718 - 0x000009AC] WavOpen(0x0AE3B080) - 0x0A6A2CFC [15:08:05:718 - 0x000009AC] WavSetChunks(0x0A6A2CFC) - 0x00000000 [15:08:05:718 - 0x000009AC] WavSetPosition(0x0A6A2CFC | 0) - 0 [15:08:05:718 - 0x000009AC] WavSetVolume(0x0A6A2CFC | 50) - 0x00000000 [15:08:05:718 - 0x000009AC] WavSetSpeed(0x0A6A2CFC | 100) - 0x00000000 [15:08:05:968 - 0x000009AC] WavPlay(0x0A6A2CFC) - 0x00000000 [15:08:16:687 - 0x000010C0] SetEvent(WavStopped) [15:08:16:687 - 0x000009AC] WaitFor(StopEvents) - WavStopped [15:08:16:687 - 0x000009AC] WavGetLength(0x0A6A2CFC) - 10794 [15:08:16:687 - 0x000009AC] WavClose(0x0A6A2CFC) - 0x00000000 [15:08:16:687 - 0x000009AC] Play(Item 1 of 1 | IStream 0x0AE3B080) - 0 to 10794 [15:08:16:687 - 0x000009AC] Play(1 Item | PlayLength 10794) - S_OK [15:08:16:687 - 0x000009AC] GatherDigits(GATHER) entered [15:08:19:687 - 0x000009AC] WaitFor(Digit) - Timeout [15:08:19:687 - 0x000009AC] GatherDigits(GATHER - <NONE>) - S_MIU_TERM_TIMEOUT [15:08:19:687 - 0x000009AC] Play() entered [15:08:19:687 - 0x000009AC] WavOpen(0x0AE3B080) - 0x0A6A2B2C [15:08:19:687 - 0x000009AC] WavGetLength(0x0A6A2B2C) - 10794 [15:08:19:687 - 0x000009AC] WavClose(0x0A6A2B2C) - 0x00000000 [15:08:19:687 - 0x000009AC] WavOpen(0x0AE3B080) - 0x0A6A2B2C [15:08:19:687 - 0x000009AC] WavSetChunks(0x0A6A2B2C) - 0x00000000 [15:08:19:687 - 0x000009AC] WavSetPosition(0x0A6A2B2C | 0) - 0 [15:08:19:687 - 0x000009AC] WavSetVolume(0x0A6A2B2C | 50) - 0x00000000 [15:08:19:687 - 0x000009AC] WavSetSpeed(0x0A6A2B2C | 100) - 0x00000000 [15:08:19:921 - 0x000009AC] WavPlay(0x0A6A2B2C) - 0x00000000 [15:08:24:515 - 0x0000096C] WaitFor(0x00010444 | IDLE) - Timeout. Error AvConvMsg_MC EVENT ID 10020 [Thread 0x00000A04] AvCDEConvBase::Run() Possible infinite loop detected in [PHGreeting]; Object = 03:{80AAA392-EF4D-4795-BE90-623151B54035} Error AvConvMsg_MC EVENT ID 10016 Transferred to FailSafe conversation while running conversation PHGreeting on Port 4. See the following file for the contents of the Named Properties : D:\CommServer\logs\NPDump_20020205_150251.txt There are a few others, but I will start with these for now. Thanks

kechambe · ‎02-05-2002

Unity does not control the forwarding of the ports rather the PBX it is integrated to does. What kind of PBX is this? CallManager? If so a CCM trace would give us a better idea of what's going on. Can you post a CCM trace of a failed forward?

Keith

Keith Chambers
Unity Technical Lead
Unified Voice Team, San Jose
Cisco Systems
kechambe@cisco.com

dnmann · ‎02-06-2002

It is Call Manager, version 3.1(2c). I am not very familar with the trace process. Would you be able to tell me how to find the trace?

Thank You

Report Inappropriate Content · ‎02-06-2002

Just another suggestion...

In the original TSP failure message for the blind transfer, it will tell you why the blind transfer failed: either it received busy or reorder from CCM. If you take a closer look at that message for the reason, then you can look into why CCM would be sending busy or reorder to Unity when it is trying to transfer rather than gather CCM traces. This could be a CSS issue if the reason was reorder, or it could be that the phone was busy, but not forwarded.

Steve Olivier
Software Engineer
Cisco Systems

dnmann · ‎02-06-2002

I think this is a section of a trace indicating one of the problems. Unity had an error that said a blind transfer to 4102 could not occur because the line was busy. This is the corresponding trace from the time of the error. The line is set to forward busy and no answer to Unity.

02/06/2002 10:32:58.645 Cisco CallManager|StationD: 642ecd0 SelectSoftKeys instance=1 reference=33701749 softKeySetIndex=2 validKeyMask=1.|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.645 Cisco CallManager|StationD: 642ecd0 CallState callState=12(Proceed) lineInstance=1 callReference=33702101|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.645 Cisco CallManager|StationD: 642ecd0 CallInfo callingPartyName='Voice Mail' callingParty=2006 cgpnVoiceMailbox= calledPartyName='Deborah Cullen' calledParty=4102 cdpnVoiceMailbox=4102 originalCalledPartyName='Deborah Cullen' originalCalledParty=4102 originalCdpnVoiceMailbox=4102 originalCdpnRedirectReason=0 lastRedirectingPartyName='' lastRedirectingParty= lastRedirectingVoiceMailbox= lastRedirectingReason=0 callType=2(OutBound) lineInstance=1 callReference=33702101.|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.645 Cisco CallManager|StationD::star_StationOutputCallInfo(): callInfo: CI=33702101, CallingPartyName=Voice Mail, CallingParty=2006, CalledPartyName=Deborah Cullen, CalledParty=4102, OriginalCalledPartyName=Deborah Cullen, OriginalCalledParty=4102, lastRedirectingPartyName=, lastRedirectingParty=|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.645 Cisco CallManager|StationD: 642ecd0 DialedNumber dialedNumber=4102 lineInstance=1 callReference=33702101.|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.738 Cisco CallManager|CTI: RoutePatternToCtiCommandData::findValue() : RP=2006:Phones, bRc=0, T=, #entries=0|<:NBCO-CCM01-CLUSTER><:10.110.10.1>
02/06/2002 10:32:58.738 Cisco CallManager|CTI: RoutePatternToCtiCommandData::findValue() : RP=2006:Phones, bRc=0, T=, #entries=0|<:NBCO-CCM01-CLUSTER><:10.110.10.1>
02/06/2002 10:32:58.738 Cisco CallManager|StationD: 642ecd0 StartTone tone=35(LineBusyTone).|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.926 Cisco CallManager|StationInit: 642ecd0 Stimulus stimulus=9(Line) stimulusInstance=1.|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.926 Cisco CallManager|StationD - StimLine: hold cases|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.926 Cisco CallManager|StationD: 642ecd0 SelectSoftKeys instance=1 reference=33701749 softKeySetIndex=2 validKeyMask=-1.|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.926 Cisco CallManager|StationD: 642ecd0 SetLamp stimulus=9(Line) stimulusInstance=1 lampMode=3(LampWink).|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.926 Cisco CallManager|StationD: 642ecd0 ClearPromptStatus lineInstance=1 callReference=33702101.|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.926 Cisco CallManager|StationD: 642ecd0 CallState callState=2(OnHook) lineInstance=1 callReference=33702101|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.926 Cisco CallManager|StationD: 642ecd0 DefineTimeDate timeDateInfo=? systemTime=1013009578.|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>
02/06/2002 10:32:58.926 Cisco CallManager|StationD: 642ecd0 StopTone.|<:NBCO-CCM01-CLUSTER><:10.110.10.1><:1><:10.110.10.3><:UNITY1-VI7>

kechambe · ‎02-06-2002

I can't tell from here. Can you stop and start a new trace and then make it fail and post the whole trace? I think the forum can handle a fairly large file...

Keith

Keith Chambers
Unity Technical Lead
Unified Voice Team, San Jose
Cisco Systems
kechambe@cisco.com

dnmann · ‎02-06-2002

The problem is, Ican't make it fail. It happens intermittently. I have a hunch it may be occuring because both the forward busy and forward no answer are in use while the person is on the line, so when a fourth person calls in, it rings busy.

How do you stop and start a new trace?

kechambe · ‎02-06-2002

The forward busy and forward no answer don't have a concept of in use. They are intercepts or redirects if you will.

Any chance you configured Cisco Messaging Interface?

There is a trace on check box where you configure the traces. Uncheck that, click update then recheck that and check update again.

Keith

Keith Chambers
Unity Technical Lead
Unified Voice Team, San Jose
Cisco Systems
kechambe@cisco.com

dnmann · ‎02-06-2002

I did initially configure the CMI, but I found a post here a while ago and got rid of it. I thought that had solve my problem, and it seemed to have for a while. But when I went live into production it seems to have cropped up again. I have noticed that if a phone is off hook, then it takes another call, that one goes right to Unity as per forward busy, the third call will also go to Unity with the first two still active, but the forth one will get a busy signal. Is this normal?