06-29-2012 03:56 AM - edited 03-16-2019 11:55 AM
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:
06-29-2012 04:16 AM
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"
06-29-2012 06:32 AM
The topology is
TDM PaBX --- ISDN --- Cisco 2901 --- SIP --- Session Border controller.
Let me look into the times you listed.
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide