[cisco-voip] AS5400, unable to get pots call to dial out via SIP
Nick Matthews
matthnick at gmail.com
Mon Jan 10 17:57:08 EST 2011
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).
-nick
On Mon, Jan 10, 2011 at 4:23 PM, Peter Grace <pete.grace at gmail.com> wrote:
> Hello list,
>
> 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.
>
>
> Here are my two dial-peer entries:
>
> dial-peer voice 1 pots
> incoming called-number 786235....
> direct-inward-dial
> !
> dial-peer voice 201 voip
> service session
> destination-pattern 7862350000
> session protocol sipv2
> session target ipv4:10.144.0.63
> session transport udp
> dtmf-relay rtp-nte
> codec g711ulaw
>
> dialpeer debug shows the incoming call matches 201:
>
> *Jan 10 21:19:42.719: //-1/2CD6C13A8013/DPM/dpMatchPeersMoreArg:
> Result=SUCCESS(0)
> List of Matched Outgoing Dial-peer(s):
> 1: Dial-peer Tag=201
>
>
> Here is "debug isdnq931":
>
> *Jan 10 21:21:32.867: ISDN Se7/0:23 Q931: RX <- SETUP pd = 8 callref =
> 0x03BB
> Bearer Capability i = 0x8090A2
> Standard = CCITT
> Transfer Capability = Speech
> Transfer Mode = Circuit
> Transfer Rate = 64 kbit/s
> Channel ID i = 0xA98381
> Exclusive, Channel 1
> Calling Party Number i = 0x2183, '4849190000'
> Plan:ISDN, Type:National
> Called Party Number i = 0xA1, '7862350000'
> Plan:ISDN, Type:National
> *Jan 10 21:21:32.871: ISDN Se7/0:23 Q931: TX -> CALL_PROC pd = 8 callref =
> 0x83BB
> Channel ID i = 0xA98381
> Exclusive, Channel 1
> tgmicsip01#
> *Jan 10 21:21:32.871: ISDN Se7/0:23 Q931: TX -> DISCONNECT pd = 8 callref
> = 0x83BB
> Cause i = 0x80AC - Requested circuit/channel not available
> *Jan 10 21:21:32.959: ISDN Se7/0:23 Q931: RX <- RELEASE pd = 8 callref =
> 0x03BB
> *Jan 10 21:21:32.963: ISDN Se7/0:23 Q931: TX -> RELEASE_COMP pd = 8
> callref = 0x83BB
> tgmicsip01#
> *Jan 10 21:21:34.515: ISDN Se7/0:23 Q931: RX <- SETUP pd = 8 callref =
> 0x19B9
> Bearer Capability i = 0x8090A2
> Standard = CCITT
> Transfer Capability = Speech
> Transfer Mode = Circuit
> Transfer Rate = 64 kbit/s
> Channel ID i = 0xA98381
> Exclusive, Channel 1
> Calling Party Number i = 0x2183, '4849190000'
> Plan:ISDN, Type:National
> Called Party Number i = 0xA1, '7862350000'
> Plan:ISDN, Type:National
> *Jan 10 21:21:34.519: ISDN Se7/0:23 Q931: TX -> CALL_PROC pd = 8 callref =
> 0x99B9
> Channel ID i = 0xA98381
> Exclusive, Channel 1
> tgmicsip01#
> *Jan 10 21:21:34.519: ISDN Se7/0:23 Q931: TX -> DISCONNECT pd = 8 callref
> = 0x99B9
> Cause i = 0x80AC - Requested circuit/channel not available
> *Jan 10 21:21:34.559: ISDN Se7/0:23 Q931: RX <- RELEASE pd = 8 callref =
> 0x19B9
> *Jan 10 21:21:34.563: ISDN Se7/0:23 Q931: TX -> RELEASE_COMP pd = 8
> callref = 0x99B9
>
> Here is a copy of "debug voice ccapi":
>
> *Jan 10 21:11:52.823: //-1/14C251DD8004/CCAPI/cc_api_display_ie_subfields:
> cc_api_call_setup_ind_common:
> cisco-username=
> ----- ccCallInfo IE subfields -----
> cisco-ani=4849190000
> cisco-anitype=2
> cisco-aniplan=1
> cisco-anipi=0
> cisco-anisi=3
> dest=7862350000
> cisco-desttype=2
> 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
>
> *Jan 10 21:11:52.823: //-1/14C251DD8004/CCAPI/cc_api_call_setup_ind_common:
> Interface=0x63B48ABC, Call Info(
> Calling Number=4849190000,(Calling Name=)(TON=National, NPI=ISDN,
> Screening=Network, Presentation=Allowed),
> Called Number=7862350000(TON=National, NPI=ISDN),
> Calling Translated=FALSE, Subscriber Type Str=RegularLine,
> FinalDestinationFlag=TRUE,
> Incoming Dial-peer=1, Progress Indication=NULL(0), Calling IE Present
> tgmicsip01#=TRUE,
> Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID
> Transparent=FALSE), Call Id=-1
> *Jan 10 21:11:52.823: //-1/14C251DD8004/CCAPI/ccCheckClipClir:
> In: Calling Number=4849190000(TON=National, NPI=ISDN, Screening=Network,
> Presentation=Allowed)
> *Jan 10 21:11:52.823: //-1/14C251DD8004/CCAPI/ccCheckClipClir:
> Out: Calling Number=4849190000(TON=National, NPI=ISDN,
> Screening=Network, Presentation=Allowed)
> *Jan 10 21:11:52.823: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
>
> *Jan 10 21:11:52.823: :cc_get_feature_vsa malloc success
> *Jan 10 21:11:52.823: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
>
> *Jan 10 21:11:52.823: cc_get_feature_vsa count is 1
> *Jan 10 21:11:52.823: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
>
> *Jan 10 21:11:52.823: :FEATURE_VSA attributes are:
> feature_name:0,feature_time:1720408424,feature_id:1
> *Jan 10 21:11:52.823: //1/14C251DD8004/CCAPI/cc_api_call_setup_ind_common:
> Set Up Event Sent;
> Call Info(Calling Number=4849190000(TON=National, NPI=ISDN,
> Screening=Network, Presentation=Allowed),
> Called Number=7862350000(TON=National, NPI=ISDN))
> *Jan 10 21:11:52.823: //1/14C251DD8004/CCAPI/cc_process_call_setup_ind:
> Event=0x668C3A98
> *Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/ccCallSetContext:
> Context=0x63DDA1CC
> *Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/cc_process_call_setup_ind:
> >>>>CCAPI handed cid 1 with tag 1 to app "_ManagedAppProcess_Default"
> *Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/ccCallProceeding:
> Progress Indication=NULL(0)
> *Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/ccCallSetupRequest:
> Destination=, Calling IE Present=TRUE, Mode=0,
> Outgoing Dial-peer=201, Params=0x63DDB094, Progress Indication=NULL(0)
> *Jan 10 21:11:52.827: //1/14C251DD8004/CCAPI/ccCallSetupRequest:
> Peer(Active Connections=0)
> *Jan 10 21:11:52.827: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
>
> *Jan 10 21:11:52.827: : updating existing feature vsa
> *Jan 10 21:11:52.827: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
>
> *Jan 10 21:11:52.827: feature call basic
> *Jan 10 21:11:52.831: //1/14C251DD8004/CCAPI/ccCallDisconnect:
> Cause Value=44, Tag=0x0, Call Entry(Previous Disconnect Cause=0,
> Disconnect Cause=0)
> *Jan 10 21:11:52.831: //1/14C251DD8004/CCAPI/ccCallDisconnect:
> Cause Value=44, Call Entry(Responsed=TRUE, Cause Value=44)
> *Jan 10 21:11:52.915: //1/14C251DD8004/CCAPI/cc_api_call_disconnect_done:
> Disposition=0, Interface=0x63B48ABC, Tag=0x0, Call Id=1,
> Call Entry(Disconnect Cause=44, Voice Class Cause Code=0, Retry Count=0)
> *Jan 10 21:11:52.915: //1/14C251DD8004/CCAPI/cc_api_call_disconnect_done:
> Call Disconnect Event Sent
> *Jan 10 21:11:52.915: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
>
> *Jan 10 21:11:52.915: :cc_free_feature_vsa freeing 668B5960
> *Jan 10 21:11:52.915: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
>
> *Jan 10 21:11:52.915: vsacount in free is 0
> *Jan 10 21:11:52.915: //-1/xxxxxxxxxxxx/CCAPI/ccTimerInit:
> ccTimerctx - 0x63793068
> *Jan 10 21:11:54.471: //-1/15BDC9618009/CCAPI/cc_api_display_ie_subfields:
> cc_api_call_setup_ind_common:
> cisco-username=
> ----- ccCallInfo IE subfields -----
> cisco-ani=4849190000
> cisco-anitype=2
> cisco-aniplan=1
> cisco-anipi=0
> cisco-anisi=3
> dest=7862350000
> cisco-desttype=2
> 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
>
> *Jan 10 21:11:54.471: //-1/15BDC9618009/CCAPI/cc_api_call_setup_ind_common:
> Interface=0x63B48ABC, Call Info(
> Calling Number=4849190000,(Calling Name=)(TON=National, NPI=ISDN,
> Screening=Network, Presentation=Allowed),
> Called Number=7862350000(TON=National, NPI=ISDN),
> Calling Translated=FALSE, Subscriber Type Str=RegularLine,
> FinalDestinationFlag=TRUE,
> Incoming Dial-peer=1, Progress Indication=NULL(0), Calling IE
> Present=TRUE,
> Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID
> Transparent=FALSE), Call Id=-1
> *Jan 10 21:11:54.471: //-1/15BDC9618009/CCAPI/ccCheckClipClir:
> In: Calling Number=4849190000(TON=National, NPI=ISDN, Screening=Network,
> Presentation=Allowed)
> *Jan 10 21:11:54.471: //-1/15BDC9618009/CCAPI/ccCheckClipClir:
> Out: Calling Number=4849190000(TON=National, NPI=ISDN,
> Screening=Network, Presentation=Allowed)
> *Jan 10 21:11:54.471: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
>
> *Jan 10 21:11:54.471: :cc_get_feature_vsa malloc success
> *Jan 10 21:11:54.471: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
>
> *Jan 10 21:11:54.471: cc_get_feature_vsa count is 1
> *Jan 10 21:11:54.471: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
>
> *Jan 10 21:11:54.471: :FEATURE_VSA attributes are:
> feature_name:0,feature_time:1720408424,feature_id:2
> *Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/cc_api_call_setup_ind_common:
> Set Up Event Sent;
> Call Info(Calling Number=4849190000(TON=National, NPI=ISDN,
> Screening=Network, Presentation=Allowed),
> Called Number=7862350000(TON=National, NPI=ISDN))
> *Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/cc_process_call_setup_ind:
> Event=0x668C3A98
> *Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/ccCallSetContext:
> Context=0x63DDBEBC
> *Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/cc_process_call_setup_ind:
> >>>>CCAPI handed cid 2 with tag 1 to app "_ManagedAppProcess_Default"
> *Jan 10 21:11:54.471: //2/15BDC9618009/CCAPI/ccCallProceeding:
> Progress Indication=NULL(0)
> *Jan 10 21:11:54.475: //2/15BDC9618009/CCAPI/ccCallSetupRequest:
> Destination=, Calling IE Present=TRUE, Mode=0,
> Outgoing Dial-peer=201, Params=0x63DDCD84, Progress Indication=NULL(0)
> *Jan 10 21:11:54.475: //2/15BDC9618009/CCAPI/ccCallSetupRequest:
> Peer(Active Connections=0)
> *Jan 10 21:11:54.475: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
>
> *Jan 10 21:11:54.475: : updating existing feature vsa
> *Jan 10 21:11:54.475: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
>
> *Jan 10 21:11:54.475: feature call basic
> *Jan 10 21:11:54.475: //2/15BDC9618009/CCAPI/ccCallDisconnect:
> Cause Value=44, Tag=0x0, Call Entry(Previous Disconnect Cause=0,
> Disconnect Cause=0)
> *Jan 10 21:11:54.475: //2/15BDC9618009/CCAPI/ccCallDisconnect:
> Cause Value=44, Call Entry(Responsed=TRUE, Cause Value=44)
> *Jan 10 21:11:54.567: //2/15BDC9618009/CCAPI/cc_api_call_disconnect_done:
> Disposition=0, Interface=0x63B48ABC, Tag=0x0, Call Id=2,
> Call Entry(Disconnect Cause=44, Voice Class Cause Code=0, Retry Count=0)
> *Jan 10 21:11:54.567: //2/15BDC9618009/CCAPI/cc_api_call_disconnect_done:
> Call Disconnect Event Sent
> *Jan 10 21:11:54.567: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
>
> *Jan 10 21:11:54.567: :cc_free_feature_vsa freeing 668B5960
> *Jan 10 21:11:54.567: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
>
> *Jan 10 21:11:54.567: vsacount in free is 0
>
>
> _______________________________________________
> cisco-voip mailing list
> cisco-voip at puck.nether.net
> https://puck.nether.net/mailman/listinfo/cisco-voip
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20110110/e79f7b41/attachment.html>
More information about the cisco-voip
mailing list