09-13-2013 05:52 AM - edited 03-16-2019 07:21 PM
Hi,
We have a customer who is having issue receiving a call from one certain number not being able to call them, call flow isdn --> h323 --> cucm
This was initially logged with the provider and a workaround was created, the provider hid the caller ID and the call works fine. If you call this number from another phone the call works. but if a call comes from 8075380 there is no ring back and provider sends a disconnect message after 15seconds without receiving alerting.
Any suggestions where to look next?
Here is the debug from the failed call
Sep 13 11:09:40.946: ISDN Se0/0/0:15 Q931: RX <- SETUP pd= 8 callref = 0x5600
Sending Complete
Bearer Capability i = 0x8090A3
Standard = CCITT
Transfer Capability = Speech
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA18382
Preferred, Channel 2
Progress Ind i = 0x8081 - Call not end-to-end ISDN, may have in-band info
Calling Party Number i = 0x4183, '8075380'
Plan:ISDN, Type:Subscriber(local)
Called Party Number i = 0x81, '2005'
Plan:ISDN, Type:Unknown
Low Layer Compat i = 0x8090A3
High Layer Compat i = 0x9181
Sep 13 11:09:40.950: ISDN Se0/0/0:15 Q931: ReceivedSETUP callref = 0xD600 callID = 0x2B20 switch = primary-net5 interface =User
Sep 13 11:09:40.958://-1/D211BC94AA61/CCAPI/cc_api_display_ie_subfields:
Sep 13 11:09:40.966://740125/D211BC94AA61/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x4A6EBB0C, Interface Type=1, Destination=, Mode=0x0,
Call Params(Calling Number=8075380,(CallingName=)(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed),
Called Number=2005(TON=Unknown, NPI=ISDN),Calling Translated=FALSE,
Subscriber Type Str=RegularLine,FinalDestinationFlag=TRUE, Outgoing Dial-peer=2000, Call Count On=FALSE,
Source Trkgrp Route Label=1, Target TrkgrpRoute Label=, tg_label_flag=1, Application Call Id=)
Sep 13 11:09:40.974: ISDN Se0/0/0:15 Q931: TX ->CALL_PROC pd = 8 callref = 0xD600
Channel ID i = 0xA98382
Exclusive, Channel 2
Sep 13 11:09:55.982://740125/D211BC94AA61/CCAPI/ccCallReleaseResources:
release reserved xcoding resource. My guess is this is something to do with transcending, but the same call works if caller ID is hidden(i dont have the debugs for that call though)
Sep 13 11:09:55.990://740125/D211BC94AA61/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=2010, Params=0x4F6A6798,Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
Sep 13 11:09:55.994: //740125/D211BC94AA61/CCAPI/ccCheckClipClir:
In: Calling Number=8075380(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed)
Sep 13 11:09:55.994://740125/D211BC94AA61/CCAPI/ccCheckClipClir:
Out: Calling Number=8075380(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed)
Sep 13 11:09:55.994://740125/D211BC94AA61/CCAPI/ccCallSetupRequest:
Destination Pattern=2..., Called Number=2005, Digit Strip=FALSE
Sep 13 11:09:55.994://740125/D211BC94AA61/CCAPI/ccCallSetupRequest:
Calling Number=8075380(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed),
Called Number=2005(TON=Unknown, NPI=ISDN),
Redirect Number=, Display Info=
Account Number=, Final Destination Flag=TRUE,
Guid=D211BC94-1B9B-11E3-AA61-0014F2AAB290,Outgoing Dial-peer=2010
Sep 13 11:09:55.994://740125/D211BC94AA61/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=8075380
cisco-anitype=4
cisco-aniplan=1
cisco-anipi=0
cisco-anisi=3
dest=2005
cisco-desttype=0
cisco-destplan=1
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=-1
cisco-rdnplan=-1
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
Sep 13 11:09:55.994://740125/D211BC94AA61/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x4A6EBB0C, Interface Type=1, Destination=, Mode=0x0,
Call Params(Calling Number=8075380,(CallingName=)(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed),
Called Number=2005(TON=Unknown, NPI=ISDN),Calling Translated=FALSE,
Subscriber Type Str=RegularLine,FinalDestinationFlag=TRUE, Outgoing Dial-peer=2010, Call Count On=FALSE,
Source Trkgrp Route Label=1, Target TrkgrpRoute Label=, tg_label_flag=1, Application Call Id=)
Sep 13 11:10:05.719: ISDN Se0/0/0:15 Q931: RX <-DISCONNECT pd = 8 callref = 0x5600
Cause i = 0x05809F - Normal, unspecified
Sep 13 11:10:05.751: ISDN Se0/0/0:15 Q931: TX -> RELEASEpd = 8 callref = 0xD600
Sep 13 11:10:05.751://740125/D211BC94AA61/CCAPI/cc_api_call_disconnected:
Cause Value=31, Interface=0x4B116398, Call Id=740125
Sep 13 11:10:05.755://740125/D211BC94AA61/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=FALSE, Cause Value=31,Retry Count=0)
Here is a succesfull call
dgfpel-2821-r1#ter mon
dgfpel-2821-r1#
Sep 13 12:17:59.968: ISDN Se0/0/0:15 Q931: RX <- SETUP pd = 8 callref = 0x2C00
Sending Complete
Bearer Capability i = 0x9090A3
Standard = CCITT
Transfer Capability = 3.1kHz Audio
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA18398
Preferred, Channel 24
Progress Ind i = 0x8A81 - Call not end-to-end ISDN, may have in-band info
Calling Party Number i = 0x4183, '4611923'
Plan:ISDN, Type:Subscriber(local)
Called Party Number i = 0x81, '2005'
Plan:ISDN, Type:Unknown
Sep 13 12:17:59.968: ISDN Se0/0/0:15 Q931: Received SETUP callref = 0xAC00 callID = 0x2B75 switch = primary-net5 interface = User
Sep 13 12:17:59.972: //-1/5D462822AAB6/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=4611923
cisco-anitype=4
cisco-aniplan=1
cisco-anipi=0
cisco-anisi=3
dest=2005
cisco-desttype=0
cisco-destplan=1
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=-1
cisco-rdnplan=-1
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
Sep 13 12:17:59.976: //-1/5D462822AAB6/CCAPI/cc_api_call_setup_ind_common:
Interface=0x4B116398, Call Info(
Calling Number=4611923,(Calling Name=)(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed),
Called Number=2005(TON=Unknown, NPI=ISDN),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,
Incoming Dial-peer=22, Progress Indication=NOT END TO END ISDN(1), Calling IE Present=TRUE,
Source Trkgrp Route Label=1, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
Sep 13 12:17:59.976: //-1/5D462822AAB6/CCAPI/ccCheckClipClir:
In: Calling Number=4611923(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed)
Sep 13 12:17:59.976: //-1/5D462822AAB6/CCAPI/ccCheckClipClir:
Out: Calling Number=4611923(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed)
Sep 13 12:17:59.976: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Sep 13 12:17:59.976: :cc_get_feature_vsa malloc success
Sep 13 12:17:59.976: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Sep 13 12:17:59.976: cc_get_feature_vsa count is 1
Sep 13 12:17:59.976: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Sep 13 12:17:59.976: :FEATURE_VSA attributes are: feature_name:0,feature_time:1322795336,feature_id:740487
Sep 13 12:17:59.976: //740493/5D462822AAB6/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=4611923(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed),
Called Number=2005(TON=Unknown, NPI=ISDN))
Sep 13 12:17:59.976: //740493/5D462822AAB6/CCAPI/cc_process_call_setup_ind:
Event=0x4B0D10F8
Sep 13 12:17:59.976: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 2005
Sep 13 12:17:59.976: //740493/5D462822AAB6/CCAPI/ccCallSetContext:
Context=0x4F6AA588
Sep 13 12:17:59.976: //740493/5D462822AAB6/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 740493 with tag 22 to app "_ManagedAppProcess_Default"
Sep 13 12:17:59.980: //740493/5D462822AAB6/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
Sep 13 12:17:59.980: //740493/5D462822AAB6/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=2000, Params=0x4F275394, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
Sep 13 12:17:59.980: //740493/5D462822AAB6/CCAPI/ccCheckClipClir:
In: Calling Number=4611923(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed)
Sep 13 12:17:59.984: //740493/5D462822AAB6/CCAPI/ccCheckClipClir:
Out: Calling Number=4611923(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed)
Sep 13 12:17:59.984: //740493/5D462822AAB6/CCAPI/ccCallSetupRequest:
Destination Pattern=2..., Called Number=2005, Digit Strip=FALSE
Sep 13 12:17:59.984: //740493/5D462822AAB6/CCAPI/ccCallSetupRequest:
Calling Number=4611923(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed),
Called Number=2005(TON=Unknown, NPI=ISDN),
Redirect Number=, Display Info=
Account Number=, Final Destination Flag=TRUE,
Guid=5D462822-1BA5-11E3-AAB6-0014F2AAB290, Outgoing Dial-peer=2000
Sep 13 12:17:59.984: //740493/5D462822AAB6/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=4611923
cisco-anitype=4
cisco-aniplan=1
cisco-anipi=0
cisco-anisi=3
dest=2005
cisco-desttype=0
cisco-destplan=1
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=-1
cisco-rdnplan=-1
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
Sep 13 12:17:59.984: //740493/5D462822AAB6/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x4A6EBB0C, Interface Type=1, Destination=, Mode=0x0,
Call Params(Calling Number=4611923,(Calling Name=)(TON=Subscriber, NPI=ISDN, Screening=Network, Presentation=Allowed),
Called Number=2005(TON=Unknown, NPI=ISDN), Calling Translated=FALSE,
Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=2000, Call Count On=FALSE,
Source Trkgrp Route Label=1, Target Trkgrp Route Label=, tg_label_flag=1, Application Call Id=)
Sep 13 12:17:59.984: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Sep 13 12:17:59.984: :cc_get_feature_vsa malloc success
Sep 13 12:17:59.984: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Sep 13 12:17:59.984: cc_get_feature_vsa count is 2
Sep 13 12:17:59.984: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Sep 13 12:17:59.984: :FEATURE_VSA attributes are: feature_name:0,feature_time:1322788168,feature_id:740488
Sep 13 12:17:59.984: //740494/5D462822AAB6/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1
Sep 13 12:17:59.984: //740494/5D462822AAB6/CCAPI/ccCallSetContext:
Context=0x4F275344
Sep 13 12:17:59.984: //740493/5D462822AAB6/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=2000
Sep 13 12:17:59.988: ISDN Se0/0/0:15 Q931: TX -> CALL_PROC pd = 8 callref = 0xAC00
Channel ID i = 0xA98398
Exclusive, Channel 24
Sep 13 12:18:00.100: //740494/5D462822AAB6/CCAPI/cc_api_set_called_ccm_detected:
CallInfo(called ccm detected=TRUE ccmVersion 3)
Sep 13 12:18:00.104: //740494/5D462822AAB6/CCAPI/cc_api_call_proceeding:
Interface=0x4A6EBB0C, Progress Indication=NULL(0)
Sep 13 12:18:00.208: //740494/5D462822AAB6/CCAPI/cc_api_set_called_ccm_detected:
CallInfo(called ccm detected=TRUE ccmVersion 3)
Sep 13 12:18:00.208: //740494/5D462822AAB6/CCAPI/cc_api_set_delay_xport:
CallInfo(delay xport=TRUE)
Sep 13 12:18:00.208: //740494/5D462822AAB6/CCAPI/cc_api_call_alert:
Interface=0x4A6EBB0C, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
Sep 13 12:18:00.208: //740494/5D462822AAB6/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
Sep 13 12:18:00.212: //740493/5D462822AAB6/CCAPI/ccCallAlert:
Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
Sep 13 12:18:00.212: //740493/5D462822AAB6/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, Alert Sent=TRUE)
Sep 13 12:18:00.216: ISDN Se0/0/0:15 Q931: TX -> ALERTING pd = 8 callref = 0xAC00
Progress Ind i = 0x8188 - In-band info or appropriate now available
Sep 13 12:18:00.216: //740494/5D462822AAB6/CCAPI/cc_api_set_called_ccm_detected:
CallInfo(called ccm detected=TRUE ccmVersion 3)
Sep 13 12:18:00.216: //740494/5D462822AAB6/CCAPI/cc_api_call_notify:
Data Bitmask=0x5, Interface=0x4A6EBB0C, Call Id=740494
Sep 13 12:18:00.216: //740494/5D462822AAB6/CCAPI/cc_api_get_ssCTreRoutingNotSupported:
CallInfo(ssCTreRoutingNotSupported=FALSE)
Sep 13 12:18:00.216: //740494/5D462822AAB6/CCAPI/cc_api_get_ccm_detected:
CallInfo(ccm detected=TRUE)
Sep 13 12:18:00.216: //740493/5D462822AAB6/CCAPI/ccCallNotify:
Data Bitmask=0x5, Call Id=740493
Sep 13 12:18:04.716: ISDN Se0/0/0:15 Q931: RX <- DISCONNECT pd = 8 callref = 0x2C00
Cause i = 0x00809F - Normal, unspecified
Sep 13 12:18:04.720: ISDN Se0/0/0:15 Q931: TX -> RELEASE pd = 8 callref = 0xAC00
Sep 13 12:18:04.720: //740493/5D462822AAB6/CCAPI/cc_api_call_disconnected:
Cause Value=31, Interface=0x4B116398, Call Id=740493
Sep 13 12:18:04.720: //740493/5D462822AAB6/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=31, Retry Count=0)
Sep 13 12:18:04.724: //740493/5D462822AAB6/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=FALSE, Tone=Null,
Tone Direction=Network, Params=0x0, Call Id=740493
Sep 13 12:18:04.724: //740494/5D462822AAB6/CCAPI/ccCallDisconnect:
Cause Value=31, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Sep 13 12:18:04.724: //740494/5D462822AAB6/CCAPI/ccCallDisconnect:
Cause Value=31, Call Entry(Responsed=TRUE, Cause Value=31)
Sep 13 12:18:04.724: //740494/5D462822AAB6/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
Sep 13 12:18:04.724: //740493/5D462822AAB6/CCAPI/ccCallDisconnect:
Cause Value=31, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=31)
Sep 13 12:18:04.724: //740493/5D462822AAB6/CCAPI/ccCallDisconnect:
Cause Value=31, Call Entry(Responsed=TRUE, Cause Value=31)
Sep 13 12:18:04.724: //740493/5D462822AAB6/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
Sep 13 12:18:04.732: //740494/5D462822AAB6/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x4A6EBB0C, Tag=0x0, Call Id=740494,
Call Entry(Disconnect Cause=31, Voice Class Cause Code=0, Retry Count=0)
Sep 13 12:18:04.732: //740494/5D462822AAB6/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Sep 13 12:18:04.732: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Sep 13 12:18:04.732: :cc_free_feature_vsa freeing 4ED82540
Sep 13 12:18:04.732: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Sep 13 12:18:04.732: vsacount in free is 1
dgfpel-2821-r1#
dgfpel-2821-r1#
dgfpel-2821-r1#
dgfpel-2821-r1#
Sep 13 12:18:04.744: ISDN Se0/0/0:15 Q931: RX <- RELEASE_COMP pd = 8 callref = 0x2C00
Sep 13 12:18:04.752: //740493/5D462822AAB6/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x4B116398, Tag=0x0, Call Id=740493,
Call Entry(Disconnect Cause=31, Voice Class Cause Code=0, Retry Count=0)
Sep 13 12:18:04.752: //740493/5D462822AAB6/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Sep 13 12:18:04.752: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Sep 13 12:18:04.752: :cc_free_feature_vsa freeing 4ED84140
Sep 13 12:18:04.752: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Sep 13 12:18:04.752: vsacount in free is 0
dgfpel-2821-r1#
Here is the dial peer config
!
dial-peer voice 2000 voip
preference 1
destination-pattern 2...
progress_ind setup enable 3
modem passthrough nse codec g711alaw
session target ipv4:2.153.55.10 PUB
voice-class codec 4500
voice-class h323 1
dtmf-relay h245-alphanumeric
fax rate disable
ip qos dscp cs5 media
ip qos dscp cs5 signaling
no vad
!
dial-peer voice 2010 voip
preference 2
destination-pattern 2...
progress_ind setup enable 3
modem passthrough nse codec g711alaw
session target ipv4:2.153.56.10 SUB
voice-class codec 4500
voice-class h323 1
dtmf-relay h245-alphanumeric
fax rate disable
ip qos dscp cs5 media
ip qos dscp cs5 signaling
no vad
!
09-14-2013 12:57 AM
Hi oleg,
Please do send full router configuration along with "debug h225 asn1" & "debug isdn q931" and detailed trace of RTMT of successful & failure call.
Also make 3 to 4 calls from '8075380' and check Transfer Capability = ??? received each time in debug isdn q931 set up message.
Also try to make successfull calls and check for the Transfer Capability =??? again.
Thanks & Regards,
Nishant Savalia
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide