It looks like an ISDN problem. I would check your controller configuration, make sure you've got isdn incoming-voice voice on there instead of a different bearer-cap, and any other oddness that may exist. It looks like it's disconnecting before it even starts to care about the outbound direction. There may have been a bug involved - check if 'show voice dsp group all' has any allocation failures (last line of the debug).<br>
<br>-nick<br><br><div class="gmail_quote">On Mon, Jan 10, 2011 at 4:23 PM, Peter Grace <span dir="ltr"><<a href="mailto:pete.grace@gmail.com">pete.grace@gmail.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin: 0pt 0pt 0pt 0.8ex; border-left: 1px solid rgb(204, 204, 204); padding-left: 1ex;">
Hello list,<br><br>I have an AS5400 I'm trying to configure, so that the incoming DID's on the PRI trunk will ring a sip endpoint on the other end. Pretty basic, I think. I see the call come in over ISDN, the dialpeer matches, but then... nothing. debug ccsip all shows nothing. I've included what I think to be the relevant debugs, but please let me know if you need me to include additional ones and I'll certainly do so. This has got me seriously stumped! Thanks in advance for any help that can be lent on this.<br>
<br><br>Here are my two dial-peer entries:<br><br>dial-peer voice 1 pots<br> incoming called-number 786235....<br> direct-inward-dial<br>!<br>dial-peer voice 201 voip<br> service session<br> destination-pattern 7862350000<br>
session protocol sipv2<br> session target ipv4:10.144.0.63<br> session transport udp<br> dtmf-relay rtp-nte<br> codec g711ulaw<br><br>dialpeer debug shows the incoming call matches 201:<br><br>*Jan 10 21:19:42.719: //-1/2CD6C13A8013/DPM/dpMatchPeersMoreArg:<br>
Result=SUCCESS(0)<br> List of Matched Outgoing Dial-peer(s):<br> 1: Dial-peer Tag=201<br><br><br>Here is "debug isdnq931":<br><br>*Jan 10 21:21:32.867: ISDN Se7/0:23 Q931: RX <- SETUP pd = 8 callref = 0x03BB<br>
Bearer Capability i = 0x8090A2<br> Standard = CCITT<br> Transfer Capability = Speech<br> Transfer Mode = Circuit<br> Transfer Rate = 64 kbit/s<br> Channel ID i = 0xA98381<br>
Exclusive, Channel 1<br> Calling Party Number i = 0x2183, '4849190000'<br> Plan:ISDN, Type:National<br> Called Party Number i = 0xA1, '7862350000'<br> Plan:ISDN, Type:National<br>
*Jan 10 21:21:32.871: ISDN Se7/0:23 Q931: TX -> CALL_PROC pd = 8 callref = 0x83BB<br> Channel ID i = 0xA98381<br> Exclusive, Channel 1<br>tgmicsip01#<br>*Jan 10 21:21:32.871: ISDN Se7/0:23 Q931: TX -> DISCONNECT pd = 8 callref = 0x83BB<br>
Cause i = 0x80AC - Requested circuit/channel not available<br>*Jan 10 21:21:32.959: ISDN Se7/0:23 Q931: RX <- RELEASE pd = 8 callref = 0x03BB<br>*Jan 10 21:21:32.963: ISDN Se7/0:23 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x83BB<br>
tgmicsip01#<br>*Jan 10 21:21:34.515: ISDN Se7/0:23 Q931: RX <- SETUP pd = 8 callref = 0x19B9<br> Bearer Capability i = 0x8090A2<br> Standard = CCITT<br> Transfer Capability = Speech<br>
Transfer Mode = Circuit<br> Transfer Rate = 64 kbit/s<br> Channel ID i = 0xA98381<br> Exclusive, Channel 1<br> Calling Party Number i = 0x2183, '4849190000'<br>
Plan:ISDN, Type:National<br> Called Party Number i = 0xA1, '7862350000'<br> Plan:ISDN, Type:National<br>*Jan 10 21:21:34.519: ISDN Se7/0:23 Q931: TX -> CALL_PROC pd = 8 callref = 0x99B9<br>
Channel ID i = 0xA98381<br> Exclusive, Channel 1<br>tgmicsip01#<br>*Jan 10 21:21:34.519: ISDN Se7/0:23 Q931: TX -> DISCONNECT pd = 8 callref = 0x99B9<br> Cause i = 0x80AC - Requested circuit/channel not available<br>
*Jan 10 21:21:34.559: ISDN Se7/0:23 Q931: RX <- RELEASE pd = 8 callref = 0x19B9<br>*Jan 10 21:21:34.563: ISDN Se7/0:23 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x99B9<br><br>Here is a copy of "debug voice ccapi":<br>
<br>*Jan 10 21:11:52.823: //-1/14C251DD8004/CCAPI/cc_api_display_ie_subfields:<br> cc_api_call_setup_ind_common:<br> cisco-username=<br> ----- ccCallInfo IE subfields -----<br> cisco-ani=4849190000<br> cisco-anitype=2<br>
cisco-aniplan=1<br> cisco-anipi=0<br> cisco-anisi=3<br> dest=7862350000<br> cisco-desttype=2<br> cisco-destplan=1<br> cisco-rdie=FFFFFFFF<br> cisco-rdn=<br> cisco-rdntype=-1<br> cisco-rdnplan=-1<br> cisco-rdnpi=-1<br>
cisco-rdnsi=-1<br> cisco-redirectreason=-1 fwd_final_type =0<br> final_redirectNumber =<br> hunt_group_timeout =0<br><br>*Jan 10 21:11:52.823: //-1/14C251DD8004/CCAPI/cc_api_call_setup_ind_common:<br> Interface=0x63B48ABC, Call Info(<br>
Calling Number=4849190000,(Calling Name=)(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),<br> Called Number=7862350000(TON=National, NPI=ISDN),<br> Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,<br>
Incoming Dial-peer=1, Progress Indication=NULL(0), Calling IE Present<br>tgmicsip01#=TRUE,<br> Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1<br>*Jan 10 21:11:52.823: //-1/14C251DD8004/CCAPI/ccCheckClipClir:<br>
In: Calling Number=4849190000(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)<br>*Jan 10 21:11:52.823: //-1/14C251DD8004/CCAPI/ccCheckClipClir:<br> Out: Calling Number=4849190000(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)<br>
*Jan 10 21:11:52.823: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:<br><br>*Jan 10 21:11:52.823: :cc_get_feature_vsa malloc success<br>*Jan 10 21:11:52.823: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:<br><br>*Jan 10 21:11:52.823: cc_get_feature_vsa count is 1<br>
*Jan 10 21:11:52.823: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:<br><br>*Jan 10 21:11:52.823: :FEATURE_VSA attributes are: feature_name:0,feature_time:1720408424,feature_id:1<br>*Jan 10 21:11:52.823: //1/14C251DD8004/CCAPI/cc_api_call_setup_ind_common:<br>
Set Up Event Sent;<br> Call Info(Calling Number=4849190000(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),<br> Called Number=7862350000(TON=National, NPI=ISDN))<br>*Jan 10 21:11:52.823: //1/14C251DD8004/CCAPI/cc_process_call_setup_ind:<br>
Event=0x668C3A98<br>*Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/ccCallSetContext:<br> Context=0x63DDA1CC<br>*Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/cc_process_call_setup_ind:<br> >>>>CCAPI handed cid 1 with tag 1 to app "_ManagedAppProcess_Default"<br>
*Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/ccCallProceeding:<br> Progress Indication=NULL(0)<br>*Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/ccCallSetupRequest:<br> Destination=, Calling IE Present=TRUE, Mode=0,<br>
Outgoing Dial-peer=201, Params=0x63DDB094, Progress Indication=NULL(0)<br>*Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/ccCallSetupRequest:<br> Peer(Active Connections=0)<br>*Jan 10 21:11:52.827: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:<br>
<br>*Jan 10 21:11:52.827: : updating existing feature vsa<br>*Jan 10 21:11:52.827: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:<br><br>*Jan 10 21:11:52.827: feature call basic<br>*Jan 10 21:11:52.831: //1/14C251DD8004/CCAPI/ccCallDisconnect:<br>
Cause Value=44, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)<br>*Jan 10 21:11:52.831: //1/14C251DD8004/CCAPI/ccCallDisconnect:<br> Cause Value=44, Call Entry(Responsed=TRUE, Cause Value=44)<br>
*Jan 10 21:11:52.915: //1/14C251DD8004/CCAPI/cc_api_call_disconnect_done:<br> Disposition=0, Interface=0x63B48ABC, Tag=0x0, Call Id=1,<br> Call Entry(Disconnect Cause=44, Voice Class Cause Code=0, Retry Count=0)<br>*Jan 10 21:11:52.915: //1/14C251DD8004/CCAPI/cc_api_call_disconnect_done:<br>
Call Disconnect Event Sent<br>*Jan 10 21:11:52.915: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:<br><br>*Jan 10 21:11:52.915: :cc_free_feature_vsa freeing 668B5960<br>*Jan 10 21:11:52.915: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:<br>
<br>*Jan 10 21:11:52.915: vsacount in free is 0<br>*Jan 10 21:11:52.915: //-1/xxxxxxxxxxxx/CCAPI/ccTimerInit:<br> ccTimerctx - 0x63793068<br>*Jan 10 21:11:54.471: //-1/15BDC9618009/CCAPI/cc_api_display_ie_subfields:<br>
cc_api_call_setup_ind_common:<br> cisco-username=<br> ----- ccCallInfo IE subfields -----<br> cisco-ani=4849190000<br> cisco-anitype=2<br> cisco-aniplan=1<br> cisco-anipi=0<br> cisco-anisi=3<br> dest=7862350000<br>
cisco-desttype=2<br> cisco-destplan=1<br> cisco-rdie=FFFFFFFF<br> cisco-rdn=<br> cisco-rdntype=-1<br> cisco-rdnplan=-1<br> cisco-rdnpi=-1<br> cisco-rdnsi=-1<br> cisco-redirectreason=-1 fwd_final_type =0<br>
final_redirectNumber =<br> hunt_group_timeout =0<br><br>*Jan 10 21:11:54.471: //-1/15BDC9618009/CCAPI/cc_api_call_setup_ind_common:<br> Interface=0x63B48ABC, Call Info(<br> Calling Number=4849190000,(Calling Name=)(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),<br>
Called Number=7862350000(TON=National, NPI=ISDN),<br> Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,<br> Incoming Dial-peer=1, Progress Indication=NULL(0), Calling IE Present=TRUE,<br>
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1<br>*Jan 10 21:11:54.471: //-1/15BDC9618009/CCAPI/ccCheckClipClir:<br> In: Calling Number=4849190000(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)<br>
*Jan 10 21:11:54.471: //-1/15BDC9618009/CCAPI/ccCheckClipClir:<br> Out: Calling Number=4849190000(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)<br>*Jan 10 21:11:54.471: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:<br>
<br>*Jan 10 21:11:54.471: :cc_get_feature_vsa malloc success<br>*Jan 10 21:11:54.471: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:<br><br>*Jan 10 21:11:54.471: cc_get_feature_vsa count is 1<br>*Jan 10 21:11:54.471: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:<br>
<br>*Jan 10 21:11:54.471: :FEATURE_VSA attributes are: feature_name:0,feature_time:1720408424,feature_id:2<br>*Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/cc_api_call_setup_ind_common:<br> Set Up Event Sent;<br> Call Info(Calling Number=4849190000(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),<br>
Called Number=7862350000(TON=National, NPI=ISDN))<br>*Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/cc_process_call_setup_ind:<br> Event=0x668C3A98<br>*Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/ccCallSetContext:<br>
Context=0x63DDBEBC<br>
*Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/cc_process_call_setup_ind:<br> >>>>CCAPI handed cid 2 with tag 1 to app "_ManagedAppProcess_Default"<br>*Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/ccCallProceeding:<br>
Progress Indication=NULL(0)<br>*Jan 10 21:11:54.475: //2/15BDC9618009/CCAPI/ccCallSetupRequest:<br> Destination=, Calling IE Present=TRUE, Mode=0,<br> Outgoing Dial-peer=201, Params=0x63DDCD84, Progress Indication=NULL(0)<br>
*Jan 10 21:11:54.475: //2/15BDC9618009/CCAPI/ccCallSetupRequest:<br> Peer(Active Connections=0)<br>*Jan 10 21:11:54.475: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:<br><br>*Jan 10 21:11:54.475: : updating existing feature vsa<br>
*Jan 10 21:11:54.475: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:<br><br>*Jan 10 21:11:54.475: feature call basic<br>*Jan 10 21:11:54.475: //2/15BDC9618009/CCAPI/ccCallDisconnect:<br> Cause Value=44, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)<br>
*Jan 10 21:11:54.475: //2/15BDC9618009/CCAPI/ccCallDisconnect:<br> Cause Value=44, Call Entry(Responsed=TRUE, Cause Value=44)<br>*Jan 10 21:11:54.567: //2/15BDC9618009/CCAPI/cc_api_call_disconnect_done:<br> Disposition=0, Interface=0x63B48ABC, Tag=0x0, Call Id=2,<br>
Call Entry(Disconnect Cause=44, Voice Class Cause Code=0, Retry Count=0)<br>*Jan 10 21:11:54.567: //2/15BDC9618009/CCAPI/cc_api_call_disconnect_done:<br> Call Disconnect Event Sent<br>*Jan 10 21:11:54.567: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:<br>
<br>*Jan 10 21:11:54.567: :cc_free_feature_vsa freeing 668B5960<br>*Jan 10 21:11:54.567: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:<br><br>*Jan 10 21:11:54.567: vsacount in free is 0<br><br>
<br>_______________________________________________<br>
cisco-voip mailing list<br>
<a href="mailto:cisco-voip@puck.nether.net">cisco-voip@puck.nether.net</a><br>
<a href="https://puck.nether.net/mailman/listinfo/cisco-voip" target="_blank">https://puck.nether.net/mailman/listinfo/cisco-voip</a><br>
<br></blockquote></div><br>