cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
801
Views
20
Helpful
7
Replies

Cisco unity express call manager - continues busy tone problem

aalawneh
Beginner
Beginner

CaHi,

I have a problem with my Cisco 2821 call manager, we are getting busy tone on our normal land lines (Copper wires) when call from outside, and cant place a call from the office but the internal extensions are working fine.

 

The problem solved by reload the router but this is happening once weekly & i have to reload the router each time.

 

below are the log from debug command voip ccapi inout  -    appreciate your kind support

 

Feb 8 11:39:29.234: //-1/9C24BDD08F8C/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=319
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=0 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

Feb 8 11:39:29.234: //-1/9C24BDD08F8C/CCAPI/cc_api_call_setup_ind_common:
Interface=0x4B0A00E4, Call Info(
Calling Number=319,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,
Incoming Dial-peer=20016, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TR
KHUFF_UCME#UE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
Feb 8 11:39:29.234: //-1/9C24BDD08F8C/CCAPI/ccCheckClipClir:
In: Calling Number=319(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Feb 8 11:39:29.234: //-1/9C24BDD08F8C/CCAPI/ccCheckClipClir:
Out: Calling Number=319(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Feb 8 11:39:29.234: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 8 11:39:29.234: :cc_get_feature_vsa malloc success
Feb 8 11:39:29.234: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 8 11:39:29.234: cc_get_feature_vsa count is 11
Feb 8 11:39:29.234: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 8 11:39:29.234: :FEATURE_VSA attributes are: feature_name:0,feature_time:1271769376,feature_id:1959
Feb 8 11:39:29.238: //1910/9C24BDD08F8C/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=319(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown))
Feb 8 11:39:29.238: //1910/9C24BDD08F8C/CCAPI/cc_process_call_setup_ind:
Event=0x48BE00C8
Feb 8 11:39:29.238: //1910/9C24BDD08F8C/CCAPI/ccCallSetContext:
Context=0x4722BA38
Feb 8 11:39:29.238: //1910/9C24BDD08F8C/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 1910 with tag 20016 to app "_ManagedAppProcess_Default"
Feb 8 11:39:29.238: //1910/9C24BDD08F8C/CCAPI/ccCallSetupAck:
Call Id=1910
Feb 8 11:39:29.238: //1910/9C24BDD08F8C/CCAPI/cc_api_set_transfer_info:
Transfer Number=, Transfer Reason=0x0
Feb 8 11:39:29.238: //1910/9C24BDD08F8C/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=TRUE, Tone=Dial Tone,
Tone Direction=Network, Params=0x0, Call Id=1910
Feb 8 11:39:29.238: //1910/9C24BDD08F8C/CCAPI/ccSetDigitTimeouts:
Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)
Feb 8 11:39:29.242: //1910/9C24BDD08F8C/CCAPI/ccSetDigitTimeouts:
Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
Feb 8 11:39:29.242: //1910/9C24BDD08F8C/CCAPI/ccCallReportDigits:
(callID=0x776, digit_event=0x1, enable=TRUE, consume=FALSE)
Feb 8 11:39:29.242: //1910/9C24BDD08F8C/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=1910
Feb 8 11:39:29.242: //1910/9C24BDD08F8C/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x4B0A00E4, callID=0x776, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
Feb 8 11:39:29.242: //1910/9C24BDD08F8C/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x4B0A00E4, Call Id=1910
Feb 8 11:39:29.242: //1910/9C24BDD08F8C/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms))
Feb 8 11:39:33.546: //1910/9C24BDD08F8C/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x4B0A00E4, Call Id=1910
Feb 8 11:39:33.546: //1910/9C24BDD08F8C/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=FALSE, Cause Value=16, Retry Count=0)
Feb 8 11:39:33.546: //1910/9C24BDD08F8C/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
Feb 8 11:39:33.546: //1910/9C24BDD08F8C/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
Feb 8 11:39:33.546: //1910/9C24BDD08F8C/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
Feb 8 11:39:33.550: //1910/9C24BDD08F8C/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x4B0A00E4, Tag=0x0, Call Id=1910,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
Feb 8 11:39:33.550: //1910/9C24BDD08F8C/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Feb 8 11:39:33.554: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 8 11:39:33.554: :cc_free_feature_vsa freeing 4BCDA918
Feb 8 11:39:33.554: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 8 11:39:33.554: vsacount in free is 10
KHUFF_UCME#
KHUFF_UCME#
Feb 8 11:39:48.682: //-1/A7BBA9F78F90/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=319
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=0 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

Feb 8 11:39:48.682: //-1/A7BBA9F78F90/CCAPI/cc_api_call_setup_ind_common:
Interface=0x4B0A00E4, Call Info(
Calling Number=319,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,
Incoming Dial-peer=20016, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TR
KHUFF_UCME#UE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
Feb 8 11:39:48.682: //-1/A7BBA9F78F90/CCAPI/ccCheckClipClir:
In: Calling Number=319(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Feb 8 11:39:48.682: //-1/A7BBA9F78F90/CCAPI/ccCheckClipClir:
Out: Calling Number=319(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
Feb 8 11:39:48.682: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 8 11:39:48.682: :cc_get_feature_vsa malloc success
Feb 8 11:39:48.682: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 8 11:39:48.682: cc_get_feature_vsa count is 11
Feb 8 11:39:48.682: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 8 11:39:48.682: :FEATURE_VSA attributes are: feature_name:0,feature_time:1271769376,feature_id:1960
Feb 8 11:39:48.682: //1911/A7BBA9F78F90/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=319(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown))
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/cc_process_call_setup_ind:
Event=0x48BE00C8
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/ccCallSetContext:
Context=0x4722CE50
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 1911 with tag 20016 to app "_ManagedAppProcess_Default"
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/ccCallSetupAck:
Call Id=1911
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/cc_api_set_transfer_info:
Transfer Number=, Transfer Reason=0x0
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=TRUE, Tone=Dial Tone,
Tone Direction=Network, Params=0x0, Call Id=1911
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/ccSetDigitTimeouts:
Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/ccSetDigitTimeouts:
Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/ccCallReportDigits:
(callID=0x777, digit_event=0x1, enable=TRUE, consume=FALSE)
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=1911
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x4B0A00E4, callID=0x777, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
Feb 8 11:39:48.686: //1911/A7BBA9F78F90/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x4B0A00E4, Call Id=1911
Feb 8 11:39:48.690: //1911/A7BBA9F78F90/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms))
Feb 8 11:39:49.090: //1911/A7BBA9F78F90/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=1911, Digit=6, DigitBeginFlags=0x0,
Rtp Timestamp=0x8CA0, Rtp Expiration=0x0
Feb 8 11:39:49.090: //1911/A7BBA9F78F90/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=1911, Digit=6, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
Feb 8 11:39:49.090: //1911/A7BBA9F78F90/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
Feb 8 11:39:49.090: //1911/A7BBA9F78F90/CCAPI/ccCallReportDigits:
(callID=0x777, digit_event=0x0, enable=FALSE, consume=FALSE)
Feb 8 11:39:49.090: //1911/A7BBA9F78F90/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=1911
Feb 8 11:39:49.094: //1911/A7BBA9F78F90/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x4B0A00E4, callID=0x777, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
Feb 8 11:39:49.094: //1911/A7BBA9F78F90/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x4B0A00E4, Call Id=1911
Feb 8 11:39:49.094: //1911/A7BBA9F78F90/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=10000(ms))
Feb 8 11:39:49.094: //1911/A7BBA9F78F90/CCAPI/ccCallDisconnect:
Cause Value=28, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Feb 8 11:39:49.094: //1911/A7BBA9F78F90/CCAPI/ccCallDisconnect:
Cause Value=28, Call Entry(Responsed=TRUE, Cause Value=28)
Feb 8 11:39:49.094: //1911/A7BBA9F78F90/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
KHUFF_UCME#
KHUFF_UCME#
Feb 8 11:39:57.818: //1911/A7BBA9F78F90/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x4B0A00E4, Tag=0x0, Call Id=1911,
Call Entry(Disconnect Cause=28, Voice Class Cause Code=0, Retry Count=0)
Feb 8 11:39:57.818: //1911/A7BBA9F78F90/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Feb 8 11:39:57.818: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 8 11:39:57.818: :cc_free_feature_vsa freeing 4BCDA918
Feb 8 11:39:57.818: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 8 11:39:57.818: vsacount in free is 10

7 Replies 7

Maren Mahoney
VIP Advocate VIP Advocate
VIP Advocate

I see two things: First, I see a callerID of 319 (presumably an internal extension) but nowhere in the debug do I see a dialed number (no "called party"). So this means either that the internal extension did not dial, or that the DTMF did not reach the CUCME.

The second thing I see is a DisconnectCause=28, which is an Invalid number format. (Which is a little different than a DisconnectCause=1, which is an unknown number.)

Questions:

  • What is the DN of the originator and what are they dialing?
  • I'm assuming CUCMExpress and UnityExpress, is this correct?
  • What IOS version are you running on the router?
  • What sort of PSTN connection do you have? (SIP? ISDN?)
  • Is the requirement to reload the router to fix the problem a new thing or has this always been true?
  • Could you post your router config? (Feel free to sanitize.)

Maren

Hello Maren,

 

I see two things: First, I see a callerID of 319 (presumably an internal extension) but nowhere in the debug do I see a dialed number (no "called party"). So this means either that the internal extension did not dial, or that the DTMF did not reach the CUCME.

 

i was trying to call outside from extension 319 by dialing land line number "Called Party". And i confirm no issue with internal extensions at all, all the way they are working.

 

The second thing I see is a DisconnectCause=28, which is an Invalid number format. (Which is a little different than a DisconnectCause=1, which is an unknown number.)

 

Yes i can see this but relay not sure about it. I shared with you (Attached) same output after reload.

 

Questions:

  • What is the DN of the originator and what are they dialing? Originator is extension 319 
  • I'm assuming CUCMExpress and UnityExpress, is this correct? Yes
  • What IOS version are you running on the router? Cisco IOS Software, 2800 Software (C2800NM-IPVOICE-M), Version 15.1(4)M1, RELEAS E SOFTWARE (fc1)   - Cisco Unity Express 8.5.1
  • What sort of PSTN connection do you have? (SIP? ISDN?)  ISDN
  • Is the requirement to reload the router to fix the problem a new thing or has this always been true? its new thing since 2 months
  • Could you post your router config? (Feel free to sanitize. attached

Thanks

Nithin Eluvathingal
VIP Mentor VIP Mentor
VIP Mentor

i assume you are using analogue connection.See the Voice-port status. Most probably the port will be UP.

 

can you share the show  voice port summary

 

 



Response Signature


Yes its analogue connection.

 

show voice port summary is attached.

 

I assume its dues to the disconnect issue with FXO port.  Even after disconnect if you notice voice port status showing 'UP", instead of reloading the router shut the voice port which shows up . If that solves your problem, then you need to modify the configuration to resolve the FXO  disconnect issue.



Response Signature


The port is showing up now & there no problem. We got this out put after reload so i think this is normal.

 

Actually i used to shutdown the port first then reload the router. I will check the result with shutdown only when problem accrued again.

 

actually no changes done to the configuration from long time & i don know why this issue is came.

After call disconnect the port should show on hook. if it still showing off hook means the FXO port is not disconnecting the call.



Response Signature


Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community:

Recognize Your Peers