Cisco Support Community
cancel
Showing results for 
Search instead for 
Did you mean: 
New Member

Cisco 2901, ISDN - SIP. 10 Second delay dialling out from PSTN.

Hi,

We use Cisco 2901 as a tested product to convert SIP to ISDN so we can use this technology with Legacy PaBX's.

We have never come accross this problem, but when dialling out it takes 10 seconds to make the call. In a trace we case the router ISDN side does a vsacount in free is 10 down to 1, then the call is made. I cannot locate what adjusts this VSA count. Example trace is as below;

*Jun 29 10:26:00.137: //8604/028B1FFABDC0/CCAPI/ccGenerateToneInfo:

   Stop Tone On Digit=FALSE, Tone=Null,

   Tone Direction=Sum Network, Params=0x0, Call Id=8604

*Jun 29 10:26:00.137: //8603/028B1FFABDC0/CCAPI/cc_api_call_disconnected:

   Cause Value=16, Interface=0x30BE32B0, Call Id=8603

*Jun 29 10:26:00.137: //8603/028B1FFABDC0/CCAPI/cc_api_call_disconnected:

   Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)

*Jun 29 10:26:00.137: //8603/028B1FFABDC0/CCAPI/ccConferenceDestroy:

   Conference Id=0xA2C, Tag=0x0

*Jun 29 10:26:00.137: //8603/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0xA2C, Source Interface=0x30BE32B0, Source Call Id=8603,

   Destination Call Id=8604, Disposition=0x0, Tag=0x0

*Jun 29 10:26:00.137: //8604/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0xA2C, Source Interface=0x30E19354, Source Call Id=8604,

   Destination Call Id=8603, Disposition=0x0, Tag=0x0

*Jun 29 10:26:00.137: //8603/028B1FFABDC0/CCAPI/cc_generic_bridge_done:

   Conference Id=0xA2C, Source Interface=0x30E19354, Source Call Id=8604,

   Destination Call Id=8603, Disposition=0x0, Tag=0x0

*Jun 29 10:26:00.137: //8603/028B1FFABDC0/CCAPI/ccCallDisconnect:

   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)

*Jun 29 10:26:00.137: //8603/028B1FFABDC0/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

*Jun 29 10:26:00.137: //8604/028B1FFABDC0/CCAPI/ccCallDisconnect:

   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

*Jun 29 10:26:00.137: //8604/028B1FFABDC0/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

*Jun 29 10:26:00.137: //8604/028B1FFABDC0/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

*Jun 29 10:26:00.141: //8603/028B1FFABDC0/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x30BE32B0, Tag=0x0, Call Id=8603,

   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

*Jun 29 10:26:00.141: //8603/028B1FFABDC0/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

*Jun 29 10:26:00.141: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jun 29 10:26:00.141: :cc_free_feature_vsa freeing 312A6560

*Jun 29 10:26:00.141: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jun 29 10:26:00.141:  vsacount in free is 9

*Jun 29 10:26:00.165: %ISDN-6-DISCONNECT: Interface Serial0/0/0:0  disconnected from 213401 , call lasted 277 seconds

*Jun 29 10:26:00.165: ISDN Se0/0/0:15 Q931: TX -> DISCONNECT pd = 8  callref = 0x0AB7

       Cause i = 0x8290 - Normal call clearing

*Jun 29 10:26:00.169: ISDN Se0/0/0:15 Q931: RX <- RELEASE pd = 8  callref = 0x8AB7

*Jun 29 10:26:00.173: ISDN Se0/0/0:15 Q931: TX -> RELEASE_COMP pd = 8  callref = 0x0AB7

*Jun 29 10:26:00.173: //8604/028B1FFABDC0/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x30E19354, Tag=0x0, Call Id=8604,

   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

*Jun 29 10:26:00.173: //8604/028B1FFABDC0/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

*Jun 29 10:26:00.173: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jun 29 10:26:00.173: :cc_free_feature_vsa freeing 312A6640

*Jun 29 10:26:00.173: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jun 29 10:26:00.173:  vsacount in free is 8

*Jun 29 10:26:00.697: ISDN Se0/0/0:15 Q931: RX <- SETUP pd = 8  callref = 0x0038

        Bearer Capability i = 0x8090A3

                Standard = CCITT

                Transfer Capability = Speech

                Transfer Mode = Circuit

                Transfer Rate = 64 kbit/s

        Calling Party Number i = 0x80, '213391'

                Plan:Unknown, Type:Unknown

        Called Party Number i = 0x80, 'xxxxxx'

                Plan:Unknown, Type:Unknown

R1-RHS-VincentSq-2901#

*Jun 29 10:26:00.697: ISDN Se0/0/0:15 Q931: Received SETUP  callref = 0x8038 callID = 0x0015 switch = primary-net5 inter

face = Network

*Jun 29 10:26:00.701: ISDN Se0/0/0:15 Q931: TX -> SETUP_ACK pd = 8  callref = 0x8038

        Channel ID i = 0xA98381

                Exclusive, Channel 1

*Jun 29 10:26:01.201: //-1/A81B28908016/CCAPI/cc_api_display_ie_subfields:

   cc_api_call_setup_ind_common:

   cisco-username=

   ----- ccCallInfo IE subfields -----

   cisco-ani=213391

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=0

   dest=xxxx

   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 29 10:26:01.201: //-1/A81B28908016/CCAPI/cc_api_call_setup_ind_common:

   Interface=0x30E19354, Call Info(

   Calling Number=213391,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

   Called Number=xxxx9(TON=Unknown, NPI=Unknown),

   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

*Jun 29 10:26:01.201: //-1/A81B28908016/CCAPI/ccCheckClipClir:

   In: Calling Number=213391(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

*Jun 29 10:26:01.201: //-1/A81B28908016/CCAPI/ccCheckClipClir:

   Out: Calling Number=213391(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

*Jun 29 10:26:01.201: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jun 29 10:26:01.201: :cc_get_feature_vsa malloc success

*Jun 29 10:26:01.201: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jun 29 10:26:01.201:  cc_get_feature_vsa count is 9

*Jun 29 10:26:01.201: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jun 29 10:26:01.201: :FEATURE_VSA attributes are: feature_name:0,feature_time:824862280,feature_id:5281

*Jun 29 10:26:01.201: //8618/A81B28908016/CCAPI/cc_api_call_setup_ind_common:

   Set Up Event Sent;

   Call Info(Calling Number=213391(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

   Called Number=xxxx(TON=Unknown, NPI=Unknown))

*Jun 29 10:26:01.201: //8618/A81B28908016/CCAPI/cc_process_call_setup_ind:

   Event=0x30DF5B68

*Jun 29 10:26:01.201: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

   Try with the demoted called number xxxxx

*Jun 29 10:26:01.201: //8618/A81B28908016/CCAPI/ccCallSetContext:

   Context=0x409C1ED4

*Jun 29 10:26:01.201: //8618/A81B28908016/CCAPI/cc_process_call_setup_ind:

   >>>>CCAPI handed cid 8618 with tag 1 to app "_ManagedAppProcess_Default"

*Jun 29 10:26:01.201: //8618/A81B28908016/CCAPI/ccCallProceeding:

   Progress Indication=NULL(0)

*Jun 29 10:26:01.201: //8618/A81B28908016/CCAPI/ccCallSetupRequest:

   Destination=, Calling IE Present=TRUE, Mode=0,

   Outgoing Dial-peer=100, Params=0x409C0C1C, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)

*Jun 29 10:26:01.201: //8618/A81B28908016/CCAPI/ccCheckClipClir:

   In: Calling Number=213391(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

*Jun 29 10:26:01.201: //8618/A81B28908016/CCAPI/ccCheckClipClir:

   Out: Calling Number=213391(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

*Jun 29 10:26:01.205: //8618/A81B28908016/CCAPI/ccCallSetupRequest:

   Destination Pattern=.T, Called Number=xxxx, Digit Strip=FALSE

*Jun 29 10:26:01.205: //8618/A81B28908016/CCAPI/ccCallSetupRequest:

   Calling Number=213391(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

   Called Number=xxxxTON=Unknown, NPI=Unknown),

   Redirect Number=, Display Info=

   Account Number=, Final Destination Flag=TRUE,

   Guid=A81B2890-C10B-11E1-8016-1CDF0F9E4D00, Outgoing Dial-peer=100

*Jun 29 10:26:01.205: //8618/A81B28908016/CCAPI/cc_api_display_ie_subfields:

   ccCallSetupRequest:

   cisco-username=

   ----- ccCallInfo IE subfields -----

   cisco-ani=213391

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=0

   dest=xxxx

   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 29 10:26:01.205: //8618/A81B28908016/CCAPI/ccIFCallSetupRequestPrivate:

   Interface=0x30BE32B0, Interface Type=3, Destination=, Mode=0x0,

   Call Params(Calling Number=213391,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allo

wed),

   Called Number=xxxx(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

   Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=100, Call Count On=FALSE,

   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

*Jun 29 10:26:01.205: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

2 REPLIES
VIP Super Bronze

Cisco 2901, ISDN - SIP. 10 Second delay dialling out from PSTN.

Hi,

Whats your setup? Is it CUCM-------h323----VG------PSTN?

When you say its takes 10s to dial is that from cucm or from the gateway? When you dial (assuming this is cucm) does the call come to the gateway immediately? Looking at the trace you sent, the calling number is 213391 and the called number is 02031761009.

The gateway received call setup request at Jun 29 10:26:00.697and the call was sent to the destination using dial-peer 100 at Jun 29 10:26:01.205. That doesnt look like 10s...

Please rate useful posts

"For the love of God is broader than the measure of man's mind And the heart of the Eternal is most wonderfully kind"

Please rate all useful posts "The essence of christianity is not the enthronement but the obliteration of self --William Barclay"
New Member

Cisco 2901, ISDN - SIP. 10 Second delay dialling out from PSTN.

The topology is

TDM PaBX --- ISDN --- Cisco 2901 --- SIP --- Session Border controller.

Let me look into the times you listed.

668
Views
0
Helpful
2
Replies
CreatePlease to create content