[cisco-voip] During CME-as-SRST first dial peers fail (as planned) but last dial peer to NME-CUE doesn't get hit before recovery on timer expirty

Jason Aarons (US) jason.aarons at us.didata.com
Wed Jun 23 22:22:22 EDT 2010


Doing some CME-as-SRST testing, so I pulled the WAN link now when I dial a number on the router's PRI I get a "Recovery on timer expiry" as it tries down the dial peers the first two will fails since the Sub/Pub aren't local.  Any thoughts, the T.310 timer didn't help. I can pickup a phone and dial 42104060 and it works, it just a dial peer timeout issue I can't remember how to fix -jason

Dial 281-647-1100 in SRST rings 42104060 which matches dial peers;
First dial peer is Subscriber
Second dial peer is Publisher
Third dial peer is CME-as-SRST with a .aef script


dial-peer voice 42104060 voip
description **UCCX 800-422-4060**
preference 1
destination-pattern 42104060
session target ipv4:10.225.55.133
incoming called-number .
dtmf-relay h245-alphanumeric
codec g711ulaw
no vad
!
dial-peer voice 42104061 voip
description **UCCX 800-422-4060**
preference 2
destination-pattern 42104060
session target ipv4:10.225.55.132
incoming called-number .
dtmf-relay h245-alphanumeric
codec g711ulaw
no vad
!
dial-peer voice 42104063 voip
description **cue autoattendant pilot number**
preference 3
destination-pattern 42104060
session protocol sipv2
session target ipv4:10.225.106.3
dtmf-relay sip-notify
codec g711ulaw
no vad

debug isdn q931 is              ON.
Router#debug voip ccapi inout
voip ccapi inout debugging is on
Router#
Jun 23 20:47:19 dst: ISDN Se0/0/0:23 Q931: RX <- SETUP pd = 8  callref = 0x00DF
        Bearer Capability i = 0x8090A2
                Standard = CCITT
                Transfer Capability = Speech
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0xA98381
                Exclusive, Channel 1
        Facility i = 0x9F8B0100A10F02010106072A8648CE1500040A0100
                Protocol Profile =  Networking Extensions
                0xA10F02010106072A8648CE1500040A0100
                Component = Invoke component
                        Invoke Id = 1
                        Operation = InformationFollowing (calling_name)
                                Name information in subsequent FACILITY message
        Progress Ind i = 0x8283 - Origination address is non-ISDN
        Calling Party Number i = 0x2183, '9043383245'
                Plan:ISDN, Type:National
        Called Party Number i = 0x80, '1100'
                Plan:Unknown, Type:Unknown
Jun 23 20:47:19 dst: ISDN Se0/0/0:23 Q931: Received SETUP  callref = 0x80DF callID = 0x00C0 switch = primary-ni interface = User
Jun 23 20:47:19 dst: //-1/42445AFA80A9/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=9043383245
   cisco-anitype=2
   cisco-aniplan=1
   cisco-anipi=0
   cisco-anisi=3
   dest=42101100
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-lastrdn=
   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

Jun 23 20:47:19 dst: //-1/42445AFA80A9/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x491ABDB0, Call Info(
   Calling Number=9043383245,(Calling Name=)(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),
   Called Number=42101100(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,
   Incoming Dial-peer=10, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
Jun 23 20:47:19 dst: //-1/42445AFA80A9/CCAPI/ccCheckClipClir:
   In: Calling Number=9043383245(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)
Jun 23 20:47:19 dst: //-1/42445AFA80A9/CCAPI/ccCheckClipClir:
   Out: Calling Number=9043383245(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)
Jun 23 20:47:19 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jun 23 20:47:19 dst: :cc_get_feature_vsa malloc success
Jun 23 20:47:19 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jun 23 20:47:19 dst:  cc_get_feature_vsa count is 1
Jun 23 20:47:19 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jun 23 20:47:19 dst: :FEATURE_VSA attributes are: feature_name:0,feature_time:1253757960,feature_id:769
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
  Call Info(Calling Number=9043383245(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),
   Called Number=42101100(TON=Unknown, NPI=Unknown))
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/cc_process_call_setup_ind:
   Event=0x4823FC98
Jun 23 20:47:19 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 42101100
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/ccCallSetContext:
   Context=0x4A5AC4DC
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 749 with tag 10 to app "_ManagedAppProcess_Default"
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=42104060, Params=0x4A5A8BCC, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/ccCheckClipClir:
   In: Calling Number=9043383245(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/ccCheckClipClir:
   Out: Calling Number=9043383245(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/ccCallSetupRequest:
   Destination Pattern=42104060, Called Number=42104060, Digit Strip=FALSE
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/ccCallSetupRequest:
   Calling Number=9043383245(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),
   Called Number=42104060(TON=Unknown, NPI=Unknown),
   Redirect Number=, Display Info=
   Account Number=, Final Destination Flag=TRUE,
   Guid=42445AFA-7E69-11DF-80A9-FCFBFBA11610, Outgoing Dial-peer=42104060
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=9043383245
   cisco-anitype=2
   cisco-aniplan=1
   cisco-anipi=0
   cisco-anisi=3
   dest=42104060
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-lastrdn=
   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

Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x48807B30, Interface Type=1, Destination=, Mode=0x0,
   Call Params(Calling Number=9043383245,(Calling Name=)(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),
   Called Number=42104060(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=42104060, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
Jun 23 20:47:19 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jun 23 20:47:19 dst: :cc_get_feature_vsa malloc success
Jun 23 20:47:19 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jun 23 20:47:19 dst:  cc_get_feature_vsa count is 2
Jun 23 20:47:19 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jun 23 20:47:19 dst: :FEATURE_VSA attributes are: feature_name:0,feature_time:1253757736,feature_id:770
Jun 23 20:47:19 dst: //750/42445AFA80A9/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1
Jun 23 20:47:19 dst: //750/42445AFA80A9/CCAPI/ccCallSetContext:
   Context=0x4A5A8B7C
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=42104060
Jun 23 20:47:19 dst: ISDN Se0/0/0:23 Q931: TX -> CALL_PROC pd = 8  callref = 0x80DF
        Channel ID i = 0xA98381
                Exclusive, Channel 1
Jun 23 20:47:19 dst: ISDN Se0/0/0:23 Q931: RX <- FACILITY pd = 8  callref = 0x00DF
        Facility i = 0x9F8B0100A117020101020100800F4141524F4E5320414E47454C412020
                Protocol Profile =  Networking Extensions
                0xA117020101020100800F4141524F4E5320414E47454C412020
                Component = Invoke component
                        Invoke Id = 1
                        Operation = CallingName
                                Name Presentation Allowed Extended
                                Name = AARONS ANGELA
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/cc_api_call_facility:
   Interface=0x491ABDB0, Call Id=749
Jun 23 20:47:19 dst: //750/42445AFA80A9/CCAPI/ccCallFacility:
   Call Id=750
Jun 23 20:47:19 dst: //749/42445AFA80A9/CCAPI/cc_api_get_called_ccm_detected:
   CallInfo(ccm detected=0)
Jun 23 20:47:29 dst: ISDN Se0/0/0:23 Q931: RX <- RELEASE pd = 8  callref = 0x00DF
        Cause i = 0x82E6 - Recovery on timer expiry
Jun 23 20:47:29 dst: ISDN Se0/0/0:23 Q931: TX -> RELEASE_COMP pd = 8  callref = 0x80DF
Jun 23 20:47:29 dst: //749/42445AFA80A9/CCAPI/cc_api_call_disconnected:
   Cause Value=102, Interface=0x491ABDB0, Call Id=749
Jun 23 20:47:29 dst: //749/42445AFA80A9/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=FALSE, Cause Value=102, Retry Count=0)
Jun 23 20:47:29 dst: //750/42445AFA80A9/CCAPI/ccCallDisconnect:
   Cause Value=102, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Jun 23 20:47:29 dst: //750/42445AFA80A9/CCAPI/ccCallDisconnect:
   Cause Value=102, Call Entry(Responsed=FALSE, Cause Value=102)
Jun 23 20:47:29 dst: //750/42445AFA80A9/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
Jun 23 20:47:29 dst: //749/42445AFA80A9/CCAPI/ccCallDisconnect:
   Cause Value=102, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=102)
Jun 23 20:47:29 dst: //749/42445AFA80A9/CCAPI/ccCallDisconnect:
   Cause Value=102, Call Entry(Responsed=TRUE, Cause Value=102)
Jun 23 20:47:29 dst: //749/42445AFA80A9/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
Jun 23 20:47:29 dst: //750/42445AFA80A9/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x48807B30, Tag=0x0, Call Id=750,
   Call Entry(Disconnect Cause=102, Voice Class Cause Code=0, Retry Count=0)
Jun 23 20:47:29 dst: //750/42445AFA80A9/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Jun 23 20:47:29 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jun 23 20:47:29 dst: :cc_free_feature_vsa freeing 4ABAD320
Jun 23 20:47:29 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jun 23 20:47:29 dst:  vsacount in free is 1
Jun 23 20:47:29 dst: //749/42445AFA80A9/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x491ABDB0, Tag=0x0, Call Id=749,
   Call Entry(Disconnect Cause=102, Voice Class Cause Code=0, Retry Count=0)
Jun 23 20:47:29 dst: //749/42445AFA80A9/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Jun 23 20:47:29 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jun 23 20:47:29 dst: :cc_free_feature_vsa freeing 4ABAD400
Jun 23 20:47:29 dst: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jun 23 20:47:29 dst:  vsacount in free is 0



Router#show isdn timers
    ISDN Serial0/0/0:23 Timers (dsl 0) Switchtype = primary-ni
        ISDN Layer 2 values
         K     =   7 outstanding I-frames
         N200  =   3 max number of retransmits
         T200  =   1.000 seconds
         T202  =   2.000 seconds
         T203  =  30.000 seconds
        ISDN Layer 3 values
         T301  = 300.000 seconds
         T303  =   4.000 seconds
         T304  =  20.000 seconds
         T305  =  30.000 seconds
         T306  =  60.000 seconds
         T307  = 180.000 seconds
         T308  =   4.000 seconds
         T309    Disabled
         T310  = 400.000 seconds
         T313  =   4.000 seconds
         T316  =  30.000 seconds
         T318  =   4.000 seconds
         T319  =   4.000 seconds
         T322  =   4.000 seconds
         T323  = 120.000 seconds
         T3OOS =   5.000 seconds
         TGUARD=   8.000 seconds, Expiry = REJECT_CALL
    ISDN Serial0/2/0:23 Timers (dsl 1) Switchtype = primary-ni
        ISDN Layer 2 values
         K     =   7 outstanding I-frames
         N200  =   3 max number of retransmits
         T200  =   1.000 seconds
         T202  =   2.000 seconds
         T203  =  30.000 seconds
        ISDN Layer 3 values
         T301  = 300.000 seconds
         T303  =   4.000 seconds
         T304  =  20.000 seconds
         T305  =  30.000 seconds
         T306  =  60.000 seconds
         T307  = 180.000 seconds
         T308  =   4.000 seconds
         T309    Disabled
         T310  =  30.000 seconds
         T313  =   4.000 seconds
         T316  =  30.000 seconds
         T318  =   4.000 seconds
         T319  =   4.000 seconds

Router#




-----------------------------------------
Disclaimer:

This e-mail communication and any attachments may contain
confidential and privileged information and is for use by the
designated addressee(s) named above only.  If you are not the
intended addressee, you are hereby notified that you have received
this communication in error and that any use or reproduction of
this email or its contents is strictly prohibited and may be
unlawful.  If you have received this communication in error, please
notify us immediately by replying to this message and deleting it
from your computer. Thank you.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20100623/6f441c3d/attachment.html>


More information about the cisco-voip mailing list