Dial peer preference never makes it to 3 get Recovery on timer expiry

Unanswered Question
Jun 23rd, 2010

/* Style Definitions */ table.MsoNormalTable {mso-style-name:"Table Normal"; mso-tstyle-rowband-size:0; mso-tstyle-colband-size:0; mso-style-noshow:yes; mso-style-priority:99; mso-style-parent:""; mso-padding-alt:0in 5.4pt 0in 5.4pt; mso-para-margin:0in; mso-para-margin-bottom:.0001pt; mso-pagination:widow-orphan; font-size:10.0pt; font-family:"Times New Roman","serif";}

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#

  • 1
  • 2
  • 3
  • 4
  • 5
Overall Rating: 0 (0 ratings)
Loading.
Aaron Harrison Thu, 06/24/2010 - 00:02

Hi Jason


It's common to reduce the H225 TCP timeout so that it fails over quicker to the backup peers:


voice class h323 1

h225 timeout tcp establish 3


then on each VOIP dial-peer


voice-class h323 1


Regards


Aaron


Please rate helpful posts....

Actions

This Discussion