CFNA to SUB VM -- F.Busy

[admin_2]

Interesting problem.<br><br>IP phones are configured to CFNA to the Front desk (IP-phone). Front desk transfer calls to their vm. <br> <br>Receptionist can forward the calls to the subscriber voice mail. ( Press Transfer dial the vm pilot/messages button then extension and #2). This stopped working when we changed the codec from G.711 to G.729. As soon as we changed the codec back to G.711 the transfer to voice mail from Front desk started working.<br><br>Fast Busy: <br>If I configure the IP phone CFNA to subscriber voice mail box after four rings we get fast busy. I tried the calls from outside and also IP phone to IP phone gets fast busy. It seems like the CFNA is not liking the subscriber voice mail box. The codecs are back to G711. Since all the phones are program to go to front desk it is not a problem and when they set their phones to Call forward all to voice mail it works. I do not recall if I had tested this before the codec change. Because every phone CFNA is to F.desk.<br>User community in this case has no idea of this problem.<br> <br>But as soon as we convert the codecs will lose this transfer to voice mail capability and then no voice mail.<br> <br>Any ideas...<br>Unity server AD or DNS issues.... I have seen some warnings regrading AD timing and few dns warnings in the event viewer. You can ping the Unity server by name no prblems. The Maestrotool.exe for SkinnyTSp didn't show any thing in the log file. Just the three lines. No additional information. <br> <br>I also tested the IP phones configured with just the vm pilot number in CFNA and having no mail box assigned in Unity. After four rings call goes into the pilot number and caller will hear the main greeting. Only problem is when the phones have the CFNA set to thier vm extension.<br> <br>CM 3.12c Unity 3.0.2 TSP 3.0.1.4 in central site. 60 ip phones in a remote site. 4224 gway with PRI in the remote site for local and LD calls. Sites are connected via T1 2600 routers on each side.<br> <br>Please let me know what else I should be looking. <br><br> <br><br><br>

[#2]

"This stopped working when we changed the codec from G.711 to G.729. As soon as we changed the codec back to G.711 the transfer to voice mail from Front desk started working."

Changed the codec where? CCM? Unity? Both?

"If I configure the IP phone CFNA to subscriber voice mail box after four rings we get fast busy"

Why are phones (that want to forward to Unity) being CFNA anywhere else than the Unity pilot number? That's where they should go. Is the DN that you are referring to the "Voice Message box" field in CCM? If so, then I would expect phones that forward to that DN to forward off to re-order. Try calling some one's "Voice Message Box" number and you'll get the same result. This field is used for something different. It's used if the Phone's DN is 100, but their voicemail ID is 200, then you stick 200 in that field. So, when they call or forward their phone to voice mail, 200 is the number that is used.

"But as soon as we convert the codecs will lose this transfer to voice mail capability and then no voice mail."

It will help to know about the codec settings in the regions where the calling party is, the party that does the transfer, and Unity.

"Unity server AD or DNS issues "

Most likely not the cause of getting re-order.

"The Maestrotool.exe for SkinnyTSp didn't show any thing in the log file. Just the three lines"

In Unity 3.X, the TSP logs will be in their own, separate file (diag_AvSkinny... or diag_SvcHost... or something like that). The release notes for TSP 3.0.3 will have info on setting and collecting TSP traces in a Unity 3.X system.

We'll probably be able to workaround these issues, we'll just need a bit more info.

Steve Olivier
Software Engineer
Cisco Systems

[#3]

This stopped working when we changed the codec from G.711 to G.729. As soon as we changed the codec back to G.711 the transfer to voice mail from Front desk started working."

Changed the codec where? CCM? Unity? Both?

CCM and Unity both.Verified the registry setting in Unity. Only one default region in CM which was changed to G.729.

"If I configure the IP phone CFNA to subscriber voice mail box after four rings we get fast busy"

Why are phones (that want to forward to Unity) being CFNA anywhere else than the Unity pilot number? That's where they should go. Is the DN that you are referring to the "Voice Message box" field in CCM? If so, then I would expect phones that forward to that DN to forward off to re-order. Try calling some one's "Voice Message Box" number and you'll get the same result. This field is used for something different. It's used if the Phone's DN is 100, but their voicemail ID is 200, then you stick 200 in that field. So, when they call or forward their phone to voice mail, 200 is the number that is used.

Customer requirement the CFNA should be the front desk number. Front desk will transfer to the Voice mail for the subscriber. Each phone has their voice message number in the voice message field (default setting), CFNA has the Front desk number.

"But as soon as we convert the codecs will lose this transfer to voice mail capability and then no voice mail."

It will help to know about the codec settings in the regions where the calling party is, the party that does the transfer, and Unity.

All the phones are confiugred with default device pool which includes the default region. Only default region was changed to G729 in CM.
One partition ALL_P, and one CS All_CS. Each phone is configured with All_CS.
"Unity server AD or DNS issues "

Most likely not the cause of getting re-order.

"The Maestrotool.exe for SkinnyTSp didn't show any thing in the log file. Just the three lines"

In Unity 3.X, the TSP logs will be in their own, separate file (diag_AvSkinny... or diag_SvcHost... or something like that). The release notes for TSP 3.0.3 will have info on setting and collecting TSP traces in a Unity 3.X system.

We'll probably be able to workaround these issues, we'll just need a bit more info.

Steve Olivier
Software Engineer
Cisco Systems

[#4]

"CCM and Unity both.Verified the registry setting in Unity. Only one default region in CM which was changed to G.729. "

Cool.

"Customer requirement the CFNA should be the front desk number. Front desk will transfer to the Voice mail for the subscriber. Each phone has their voice message number in the voice message field (default setting), CFNA has the Front desk number."

Cool. Just for testing purposes, is it possible to set a phone to CFNA to the Unity pilot number to see if the re-order condition happens in that call-flow? Maybe that's already been done? My thought is to pull that front desk transfer out of the call flow to verify if that is indeed the origination of the problem.

Additionally, have any CCM traces been gathered? Usually incomplete call scenarios like will have a reason why the reorder was sent to a party in the logs. If the reason for the reorder is due to some sort of device capability mis-match, Unity traces will not show anything since that call was never extended to Unity.

Thanks.




Steve Olivier
Software Engineer
Cisco Systems

[#5]

Here is the CCM trace.
7873 calling 7803.

02/27/2002 12:39:43.156 Cisco CallManager|CMProcMon - ------Entered Router Verification|<:ZSCM1-CLUSTER><:ZSCM1>
02/27/2002 12:39:43.156 Cisco CallManager|CMProcMon - ----Exited Router Verification|<:ZSCM1-CLUSTER><:ZSCM1>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - awaitingCallResponse_ForwardNoAnswerTimer CallKey= 0x1E98|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - findInterceptTableEntry - Found Intercept table entry for dn= 7803:All_P, InterceptKey= 0x225 |<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - matchNextCallForwardDestination - Destination Matched - Dn= 7803, CSS= All_P |<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - formatRedirectCallReq - Using Orig Party CallKey= 1E98|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - sendRedirectCallReq ForwardActiveCallEntry - Redirected Party= 0x100A4ED Node= 1, to Dn= 7803 CSS= All_P, CallKey= 1E98 |<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - awaitingCallResponse_ForwardNoAnswerTimer Redirect to CFB or CFNA Dest. CallKey= 0x1E98|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Insert an entry into CiCcp table, now this table has 8 entries|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Insert an entry into TransTable, now this table has 8 entries|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Digit analysis: match(fqcn="7873", cn="7873", pss="All_P", dd="7803")|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Digit analysis: analysis results|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager||PretransformCallingPartyNumber=7873
|CallingPartyNumber=7873
|DialingPartition=All_P
|DialingPattern=7803
|DialingRoutePatternRegularExpression=(7803)
|DialingWhere=
|PatternType=Enterprise
|PotentialMatches=NoPotentialMatchesExist
|DialingSdlProcessId=(1,34,3540)
|PretransformDigitString=7803
|PretransformTagsList=SUBSCRIBER
|PretransformPositionalMatchList=7803
|CollectedDigits=7803
|UnconsumedDigits=
|TagsList=SUBSCRIBER
|PositionalMatchList=7803
|VoiceMailbox=7803
|DisplayName= Chris Kaleth
|RouteBlockFlag=RouteThisPattern
|InterceptPartition=All_P
|InterceptPattern=7803
|InterceptWhere=
|InterceptSdlProcessId=(1,25,1)
|InterceptSsType=16777220
|InterceptSsKey=549|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Delete entries from TransTable, now this table has 7 entries|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Insert an entry into TransTable, now this table has 8 entries|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Delete entries from TransTable, now this table has 7 entries|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|ForwardManager - wait_SsRedirectCallRes - Received DestParty= 0x0, CallKey= 0x1E98|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|ForwardManager - wait_SsInterceptInd Received - Party= 0x100A4ED, InterceptKey= 0x225 |<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|ForwardManager - findCallBySsParty - Found entry for party= 0x100A4ED, CallKey= 0x1E98 |<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|ForwardManager - wait_SsInterceptInd - Sending Intercept to child process. Party= 0x100A4ED, InterceptKey= 0x225|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - awaitingCallResponse_SsRedirectCallRes - DestParty= 0x0, CallKey= 0x1E98|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - sendSsNotifyReq - to Party= 0x0, CallKey= 0x1E98 |<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - awaitingCallResponse_SsInterceptInd - Forwarded call Extended, or Call Pickup Call Extended. Party= 0x100A4ED, CallKey= 0x1E98|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Forwarding - ss - Extended Call to Party= 0x100A4ED, CallKey= 0x1E98 |<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|Insert an entry into TransTable, now this table has 8 entries|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.421 Cisco CallManager|EnvProcessCdr::wait_DbCdrReq|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|Delete entries from TransTable, now this table has 7 entries|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|ForwardManager - wait_SsExtendCallErr - mPartyToActiveCallIndexMap Added Entry for Party= 0x100A4ED, CallKey= 0x1E98|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|Forwarding - awaitingCallResponse_SsExtendCallErr. ErrorCode= 14, CallKey= 0x1E98|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|Forwarding - awaitingCallResponse_SsExtendCallErr. Busy - Attempting forward. CallKey= 0x1E98|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|Forwarding - sendClearCallReq - CallKey= 0x1E98, PartyId= 0x100A4ED|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
<02/27/2002 12:39:43.437 Cisco CallManager|Forwarding - awaitingCallResponse_SsExtendCallErr Busy - Hop Count EXCEEDED or No Busy dest. Play re-order.- CallKey= 0x1E98|<:ZSCM1CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|<:ZSCM1-CLUSTER><:ZSCM1><:1><:DIRECTORY number=""><:7873><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|CTI: RoutePatternToCtiCommandData::findValue() : RP=7873:All_P, bRc=0, T=, #entries=0|<:ZSCM1-CLUSTER><:ZSCM1>
02/27/2002 12:39:43.437 Cisco CallManager|CTI: RoutePatternToCtiCommandData::findValue() : RP=7873:All_P, bRc=0, T=, #entries=0|<:ZSCM1-CLUSTER><:ZSCM1>
02/27/2002 12:39:43.437 Cisco CallManager|<:ZSCM1-CLUSTER><:ZSCM1><:1><:DIRECTORY number=""><:7803><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|CTI: RoutePatternToCtiCommandData::findValue() : RP=7803:All_P, bRc=0, T=, #entries=0|<:ZSCM1-CLUSTER><:ZSCM1>
02/27/2002 12:39:43.437 Cisco CallManager|CTI: RoutePatternToCtiCommandData::findValue() : RP=7803:All_P, bRc=0, T=, #entries=0|<:ZSCM1-CLUSTER><:ZSCM1>
02/27/2002 12:39:43.437 Cisco CallManager|StationD: 528e5a4 StartTone tone=35(LineBusyTone).|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|StationD: 528e5a4 DefineTimeDate timeDateInfo=? systemTime=1014835183.|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|StationD: 528e8c4 SetRinger ringMode=1(RingOff).|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|StationD: 528e8c4 DefineTimeDate timeDateInfo=? systemTime=1014835183.|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|StationD: 528e8c4 ConnectionStatisticsReq directoryNum=7803 callIdentifier=16819438 statsProcessingMode=0(clearStats)|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|StationD: 528e8c4 SetSpeakerMode speakermode=2(Off).|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|StationD: 528e8c4 ClearPromptStatus lineInstance=1 callReference=16819438.|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002 12:39:43.437 Cisco CallManager|StationD: 528e8c4 CallState callState=2(OnHook) lineInstance=1 callReference=16819438|<:ZSCM1-CLUSTER><:ZSCM1><:1><:10.16.220.202><:SEP00078551FAFD>
02/27/2002

[#6]

You'll have to bare with me here, I am a Unity developer and CCM traces aren't my specialty, but I can't find where the call is extended to a Unity port. To me, it looks like 7803 is set to forward to 7803 (like I said though, I'm a Unity developer: ) )

To which DN is 7803 configured to forward in RNA? To which DN is 7803 configured to forward in BUSY? What are the DN's of the Unity ports?

Steve Olivier
Software Engineer
Cisco Systems