[cisco-voip] AS5400, unable to get pots call to dial out via SIP

Peter Grace pete.grace at gmail.com
Mon Jan 10 16:23:19 EST 2011


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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20110110/1256b063/attachment.html>


More information about the cisco-voip mailing list