cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
624
Views
0
Helpful
5
Replies

CUCM

sequoia_junior
Level 1
Level 1

Good morning dears,

I'm new in cisco ip telephony and after a system recovery suddenly cucm are unable to make international calls only. Both local and national calls work normally.

For international calls we have route patter 9.!

We have two gateways for outside calls, one h323 cisco voice gateway 2811 (with fxo lines) and other matrix gsm gateway (sim cards) and both work normally. Just international calls doesn't work.

When i try to make international calls the ip phone stay busy and show "call proceed" on display

We don't change any config on cisco voice gateway, we just do system recovery on cucm.

dial-peer 900 config:

sh dial-peer voice 900

VoiceEncapPeer900 peer type = voice, system default peer = FALSE, information type = voice, description = `CHAMADAS INTERNACIONAIS', tag = 900, destination-pattern = `.T', voice reg type = 0, corresponding tag = 0, allow watch = FALSE answer-address = `', preference=0, CLID Restriction = None CLID Network Number = `' CLID Second Number sent CLID Override RDNIS = disabled, rtp-ssrc mux = system source carrier-id = `', target carrier-id = `', source trunk-group-label = `', target trunk-group-label = `', numbering Type = `unknown' group = 900, Admin state is up, Operation state is up, Outbound state is up, incoming called-number = `', connections/maximum = 0/unlimited, DTMF Relay = disabled, URI classes: Destination = huntstop = disabled, in bound application associated: 'DEFAULT' out bound application associated: '' dnis-map = permission :both incoming COR list:maximum capability outgoing COR list:minimum requirement Translation profile (Incoming): Translation profile (Outgoing): incoming call blocking: translation-profile = `' disconnect-cause = `no-service' advertise 0x40 capacity_update_timer 25 addrFamily 4 oldAddrFamily 4 mailbox selection policy: none type = pots, prefix = `', forward-digits all session-target = `', voice-port = `0/0/0', direct-inward-dial = disabled, digit_strip = enabled, register E.164 number with H323 GK and/or SIP Registrar = TRUE fax rate = system, payload size = 20 bytes supported-language = '' preemption level = `routine' bandwidth: maximum = 64 KBits/sec, minimum = 64 KBits/sec voice class called-number: inbound = `', outbound = `' dial tone generation after remote onhook = enabled Time elapsed since last clearing of voice call statistics never Connect Time = 0, Charged Units = 0, Successful Calls = 11, Failed Calls = 1616, Incomplete Calls = 0 Accepted Calls = 0, Refused Calls = 0, Last Disconnect Cause is "22 ", Last Disconnect Text is "no circuit (34)", Last Setup Time = 294268240. Last Disconnect Time = 0.

Please help me

5 Replies 5

Jitender Bhandari
Cisco Employee
Cisco Employee

Hi

Can you attach below debugs for failed call with callign called number

debug voip ccapi inout

debug voip vtsp all

debug vpm signal

debug h225 asn1
debug h245 asn1

JB

Dear Jitender and Dennis, thanks for your quick reply

Here you have both debugs and DNA results:

debug voip ccapi inout:

Jul 24 16:58:25.750: //-1/80CEB62E5118/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=Edson Monteiro
----- ccCallInfo IE subfields -----
cisco-ani=1169
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=00351939491221
cisco-desttype=0
cisco-destplan=0
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

Jul 24 16:58:25.754: //-1/80CEB62E5118/CCAPI/cc_api_call_setup_ind_common:
Interface=0x473ABA6C, Call Info(
Calling Number=1169,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=00351939491221(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=T RUE,
Incoming Dial-peer=1000, Progress Indication=NULL(0), Calling IE Present=TRUE ,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALS E), Call Id=13760
Jul 24 16:58:25.754: //-1/80CEB62E5118/CCAPI/ccCheckClipClir:
In: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Pre sentation=Allowed)
Jul 24 16:58:25.754: //-1/80CEB62E5118/CCAPI/ccCheckClipClir:
Out: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Pr esentation=Allowed)
Jul 24 16:58:25.754: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 16:58:25.754: :cc_get_feature_vsa malloc success
Jul 24 16:58:25.754: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 16:58:25.754: cc_get_feature_vsa count is 1
Jul 24 16:58:25.754: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 16:58:25.754: :FEATURE_VSA attributes are: feature_name:0,feature_time:12 11085640,feature_id:13760
Jul 24 16:58:25.754: //13760/80CEB62E5118/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passe d, Presentation=Allowed),
Called Number=00351939491221(TON=Unknown, NPI=Unknown))
Jul 24 16:58:25.754: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 16:58:25.754: :Inside cc_build_feature_vsa
Jul 24 16:58:25.754: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 16:58:25.754: feature call basic
Jul 24 16:58:25.754: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 16:58:25.754: cc_build_feature_vsa attr is fn:TWC,ft:07/24/2017 18:58:25. 754,cgn:1169,cdn:00351939491221,frs:0,fid:13760,fcid:80CEB62E9A1E619751185101C0A 806A0,legID:35C0
Jul 24 16:58:25.754: //13760/80CEB62E5118/CCAPI/cc_process_call_setup_ind:
Event=0x47AE22F0
Jul 24 16:58:25.754: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 00351939491221
Jul 24 16:58:25.758: //13760/80CEB62E5118/CCAPI/ccCallSetContext:
Context=0x463F4590
Jul 24 16:58:25.758: //13760/80CEB62E5118/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 13760 with tag 1000 to app "_ManagedAppProcess_Default"
Jul 24 16:58:25.758: //13760/80CEB62E5118/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
Jul 24 16:58:25.758: //13760/80CEB62E5118/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=900, Params=0x463F9E60, Progress Indication=NULL(0)
Jul 24 16:58:25.758: //13760/80CEB62E5118/CCAPI/ccCheckClipClir:
In: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Pre sentation=Allowed)
Jul 24 16:58:25.758: //13760/80CEB62E5118/CCAPI/ccCheckClipClir:
Out: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Pr esentation=Allowed)
Jul 24 16:58:25.758: //13760/80CEB62E5118/CCAPI/ccCallSetupRequest:
Destination Pattern=.T, Called Number=00351939491221, Digit Strip=TRUE
Jul 24 16:58:25.758: //13760/80CEB62E5118/CCAPI/ccCallSetupRequest:
Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Present ation=Allowed),
Called Number=00351939491221(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=
Account Number=Edson Monteiro, Final Destination Flag=TRUE,
Guid=80CEB62E-9A1E-6197-5118-5101C0A806A0, Outgoing Dial-peer=900
Jul 24 16:58:25.762: //13760/80CEB62E5118/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=Edson Monteiro
----- ccCallInfo IE subfields -----
cisco-ani=1169
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=00351939491221
cisco-desttype=0
cisco-destplan=0
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

Jul 24 16:58:25.762: //13760/80CEB62E5118/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x47204008, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=1169,(Calling Name=)(TON=Unknown, NPI=Unknown, Scr eening=User, Passed, Presentation=Allowed),
Called Number=00351939491221(TON=Unknown, NPI=Unknown), Calling Translated=FA LSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=90 0, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Appl ication Call Id=)
Jul 24 16:58:25.762: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 16:58:25.762: :cc_get_feature_vsa malloc success
Jul 24 16:58:25.762: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 16:58:25.762: cc_get_feature_vsa count is 2
Jul 24 16:58:25.762: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 16:58:25.762: :FEATURE_VSA attributes are: feature_name:0,feature_time:12 11085416,feature_id:13761
Jul 24 16:58:25.762: //13761/80CEB62E5118/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
Jul 24 16:58:25.762: //13761/80CEB62E5118/CCAPI/ccCallSetContext:
Context=0x463F9E10
Jul 24 16:58:25.762: //13760/80CEB62E5118/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=900
Jul 24 16:58:25.766: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 16:58:25.766: :Inside cc_build_feature_vsa

Jul 24 16:58:25.766: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 16:58:25.766: feature call basic
Jul 24 16:58:25.766: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 16:58:25.766: cc_build_feature_vsa attr is fn:TWC,ft:07/24/2017 18:58:25. 762,cgn:1169,cdn:00351939491221,frs:0,fid:13761,fcid:80CEB62E9A1E619751185101C0A 806A0,legID:35C1
Jul 24 16:58:25.766: //13761/80CEB62E5118/CCAPI/cc_api_call_proceeding:
Interface=0x47204008, Progress Indication=NULL(0)
Jul 24 16:58:27.022: //13761/80CEB62E5118/CCAPI/cc_api_call_disconnected:
Cause Value=34, Interface=0x47204008, Call Id=13761
Jul 24 16:58:27.022: //13761/80CEB62E5118/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=34, Retry Count=0)
Jul 24 16:58:27.022: //13760/xxxxxxxxxxxx/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
Jul 24 16:58:27.022: //13761/80CEB62E5118/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=13761
Jul 24 16:58:27.022: //13761/80CEB62E5118/CCAPI/ccCallDisconnect:
Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect C ause=34)
Jul 24 16:58:27.022: //13761/80CEB62E5118/CCAPI/ccCallDisconnect:
Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)
Jul 24 16:58:27.026: //13761/80CEB62E5118/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x47204008, Tag=0x0, Call Id=13761,
Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)
Jul 24 16:58:27.026: //13761/80CEB62E5118/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Jul 24 16:58:27.026: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 24 16:58:27.026: :cc_free_feature_vsa freeing 482FB260
Jul 24 16:58:27.026: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 24 16:58:27.026: vsacount in free is 1
Jul 24 16:58:27.030: //13760/80CEB62E5118/CCAPI/ccCallDisconnect:
Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect C ause=0)
Jul 24 16:58:27.030: //13760/80CEB62E5118/CCAPI/ccCallDisconnect:
Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)
Jul 24 16:58:27.030: //13760/80CEB62E5118/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
Jul 24 16:58:27.034: //13760/80CEB62E5118/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x473ABA6C, Tag=0x0, Call Id=13760,
Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)

Jul 24 16:58:27.034: //13760/80CEB62E5118/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Jul 24 16:58:27.034: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 24 16:58:27.034: :cc_free_feature_vsa freeing 482FB340
Jul 24 16:58:27.034: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 24 16:58:27.034: vsacount in free is 0

*************************************************************************************************


debug voip vtsp all:

voip vtsp all debugging is on


Jul 24 17:00:22.454: //-1/009773745218/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=Edson Monteiro
----- ccCallInfo IE subfields -----
cisco-ani=1169
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=00351939491221
cisco-desttype=0
cisco-destplan=0
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

Jul 24 17:00:22.454: //-1/009773745218/CCAPI/cc_api_call_setup_ind_common:
Interface=0x473ABA6C, Call Info(
Calling Number=1169,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=00351939491221(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=1000, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=13762
Jul 24 17:00:22.454: //-1/009773745218/CCAPI/ccCheckClipClir:
In: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
Jul 24 17:00:22.454: //-1/009773745218/CCAPI/ccCheckClipClir:
Out: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

******************************************************************************************************
debug vpm signal:

Voice Port Module signaling debugging is enabled

Jul 24 17:02:23.830: //-1/80B992BC5318/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=Edson Monteiro
----- ccCallInfo IE subfields -----
cisco-ani=1169
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=00351939491221
cisco-desttype=0
cisco-destplan=0
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

Jul 24 17:02:23.830: //-1/80B992BC5318/CCAPI/cc_api_call_setup_ind_common:
Interface=0x473ABA6C, Call Info(
Calling Number=1169,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Present ation=Allowed),
Called Number=00351939491221(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=1000, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=1376 4
Jul 24 17:02:23.830: //-1/80B992BC5318/CCAPI/ccCheckClipClir:
In: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowe d)
Jul 24 17:02:23.830: //-1/80B992BC5318/CCAPI/ccCheckClipClir:
Out: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allow ed)
Jul 24 17:02:23.830: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 17:02:23.830: :cc_get_feature_vsa malloc success
Jul 24 17:02:23.830: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 17:02:23.830: cc_get_feature_vsa count is 1
Jul 24 17:02:23.830: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 17:02:23.830: :FEATURE_VSA attributes are: feature_name:0,feature_time:1211085640,feature _id:13764
Jul 24 17:02:23.830: //13764/80B992BC5318/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation= Allowed),
Called Number=00351939491221(TON=Unknown, NPI=Unknown))
Jul 24 17:02:23.834: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 17:02:23.834: :Inside cc_build_feature_vsa
Jul 24 17:02:23.834: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 17:02:23.834: feature call basic
Jul 24 17:02:23.834: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 17:02:23.834: cc_build_feature_vsa attr is fn:TWC,ft:07/24/2017 19:02:23.830,cgn:1169,cdn :00351939491221,frs:0,fid:13764,fcid:80B992BC881F619753185301C0A806A0,legID:35C4
Jul 24 17:02:23.834: //13764/80B992BC5318/CCAPI/cc_process_call_setup_ind:
Event=0x47AE22F0
Jul 24 17:02:23.834: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 00351939491221
Jul 24 17:02:23.834: //13764/80B992BC5318/CCAPI/ccCallSetContext:
Context=0x463F6310
Jul 24 17:02:23.834: //13764/80B992BC5318/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 13764 with tag 1000 to app "_ManagedAppProcess_Default"
Jul 24 17:02:23.838: //13764/80B992BC5318/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
Jul 24 17:02:23.838: //13764/80B992BC5318/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=900, Params=0x463F54A0, Progress Indication=NULL(0)
Jul 24 17:02:23.838: //13764/80B992BC5318/CCAPI/ccCheckClipClir:
In: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowe d)
Jul 24 17:02:23.838: //13764/80B992BC5318/CCAPI/ccCheckClipClir:
Out: Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allow ed)
Jul 24 17:02:23.838: //13764/80B992BC5318/CCAPI/ccCallSetupRequest:
Destination Pattern=.T, Called Number=00351939491221, Digit Strip=TRUE
Jul 24 17:02:23.838: //13764/80B992BC5318/CCAPI/ccCallSetupRequest:
Calling Number=1169(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=00351939491221(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=
Account Number=Edson Monteiro, Final Destination Flag=TRUE,
Guid=80B992BC-881F-6197-5318-5301C0A806A0, Outgoing Dial-peer=900
Jul 24 17:02:23.838: //13764/80B992BC5318/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=Edson Monteiro
----- ccCallInfo IE subfields -----
cisco-ani=1169
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=00351939491221
cisco-desttype=0
cisco-destplan=0
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

Jul 24 17:02:23.838: //13764/80B992BC5318/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x47204008, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=1169,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Pas sed, Presentation=Allowed),
Called Number=00351939491221(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=900, Call Count On =FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id= )
Jul 24 17:02:23.838: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 17:02:23.838: :cc_get_feature_vsa malloc success
Jul 24 17:02:23.838: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 17:02:23.838: cc_get_feature_vsa count is 2
Jul 24 17:02:23.838: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jul 24 17:02:23.838: :FEATURE_VSA attributes are: feature_name:0,feature_time:1211085416,feature _id:13765
Jul 24 17:02:23.842: //-1/xxxxxxxxxxxx/VTSP:(0/0/0):-1:-1:-1/vtsp_allocate_cdb:
CDB=0x46421518
Jul 24 17:02:23.842: //-1/xxxxxxxxxxxx/VTSP:(0/0/0):-1:-1:-1/vtsp_insert_cdb:
Jul 24 17:02:23.842: //13765/80B992BC5318/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
Jul 24 17:02:23.842: //13765/80B992BC5318/CCAPI/ccCallSetContext:
Context=0x463F5450
Jul 24 17:02:23.842: //13764/80B992BC5318/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=900
Jul 24 17:02:23.842: //-1/xxxxxxxxxxxx/VTSP:(0/0/0):-1:-1:-1/vtsp_process_event:
[state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ]
Jul 24 17:02:23.842: htsp_timer_stop3
Jul 24 17:02:23.842: //13765/80B992BC5318/VTSP:(0/0/0):-1:-1:-1/vtsp_og_call_req:
Echo Cancel=TRUE, Gain Control=0
Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE
Jul 24 17:02:23.842: //13765/80B992BC5318/VTSP:(0/0/0):-1:-1:-1/vtsp_do_call_setup_req:
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_dsm_opened_cb:
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_setup_req_pend_succ:
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_request_call:
Mode=0, Peer=900
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_create_call_active_on_setup_r eq:
Target Carrier ID=
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_create_call_active_on_setup_r eq:
Source Carrier ID=htsp_setup_req
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_request_call:
Progress Indication=0, Info Trans Capability=0
Calling Number=1169, TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed
Called Number=00351939491221, TON=Unknown, NPI=Unknown
Jul 24 17:02:23.846: htsp_process_event: [0/0/0, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_set up
Jul 24 17:02:23.846: [0/0/0] set signal state = 0xC timestamp = 0
Jul 24 17:02:23.846: htsp_timer - 1300 msec
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING]
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_setup_pend_proceeding:
Jul 24 17:02:23.846: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_timer:
Timer Start Time=320954427, Timer Value=25000(ms)
Jul 24 17:02:23.846: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 17:02:23.846: :Inside cc_build_feature_vsa
Jul 24 17:02:23.846: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 17:02:23.846: feature call basic
Jul 24 17:02:23.846: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

Jul 24 17:02:23.846: cc_build_feature_vsa attr is fn:TWC,ft:07/24/2017 19:02:23.838,cgn:1169,cdn :00351939491221,frs:0,fid:13765,fcid:80B992BC881F619753185301C0A806A0,legID:35C5
Jul 24 17:02:23.850: //13765/80B992BC5318/CCAPI/cc_api_call_proceeding:
Interface=0x47204008, Progress Indication=NULL(0)
Jul 24 17:02:23.850: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_setup_pend_proceeding:
Peer=900, Progress Indication=0, Return Code=2
Jul 24 17:02:23.850: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_dsm_opened_cb:
Jul 24 17:02:23.850: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_process_event:
[state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS]
Jul 24 17:02:23.850: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_setup_req_wait_pend_success:
Event=73
Jul 24 17:02:23.850: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_timer_stop:

Timer Stop Time=320954427
Jul 24 17:02:24.098: htsp_process_event: [0/0/0, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_powe r_denial_detected
Jul 24 17:02:24.102: htsp_timer2 - 1000 msec
Jul 24 17:02:24.102: htsp_timer_stop
Jul 24 17:02:25.102: htsp_process_event: [0/0/0, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER2]fxols _power_den_disc
Jul 24 17:02:25.102: htsp_timer_stop
Jul 24 17:02:25.102: htsp_timer_stop2
Jul 24 17:02:25.102: [0/0/0] set signal state = 0x4 timestamp = 0
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_TSP_DISCONNECT_IND]
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_setup_pend_release_ind:
Cause Value=34
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_ring_noan_timer_stop:
Timer Stop Time=320954552
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_cc_call_disconnected:
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_cc_call_disconnected:
Cause Value=34
Jul 24 17:02:25.102: //13765/80B992BC5318/CCAPI/cc_api_call_disconnected:
Cause Value=34, Interface=0x47204008, Call Id=13765
Jul 24 17:02:25.102: //13765/80B992BC5318/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=34, Retry Count=0)
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_set_release_source:
Release Direction=PSTN, Release Source=Called Party-PSTN
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_setup_pend_release_ind:
Peer=900
Jul 24 17:02:25.102: //13764/xxxxxxxxxxxx/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
Jul 24 17:02:25.102: //13765/80B992BC5318/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=13765
Jul 24 17:02:25.102: //13765/80B992BC5318/CCAPI/ccCallDisconnect:
Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=34)
Jul 24 17:02:25.102: //13765/80B992BC5318/CCAPI/ccCallDisconnect:
Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_call_disconnect:
[Out Of Service]-Tone Played In Direction [Both]
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_process_event:
[state:S_WAIT_HOST_DISC, event:E_CC_DISCONNECT]
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_host_disc_cleanup:
dsm_indicate_error=0, short_cleardown_timeout=0, Mode=0
Jul 24 17:02:25.102: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_host_disc_cleanup:
Exit State=16
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_dsm_get_levels_done_cb:
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_process_event:
[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_dsm_dsp_stats_complete:
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_timer:
Timer Start Time=320954553, Timer Value=60000(ms)
Jul 24 17:02:25.106: htsp_process_event: [0/0/0, FXOLS_ONHOOK, E_HTSP_RELEASE_REQ]fxols_onhook_r elease
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_process_event:
[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/act_wrelease_release:
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_play_busy_timer_stop:
Timer Stop Time=320954553
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_timer_stop:
Timer Stop Time=320954553
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_do_call_history:
Jul 24 17:02:25.106: //13765/80B992BC5318/VTSP:(0/0/0):-1:1:1/vtsp_do_call_history:
Coder Rate=-1
Jul 24 17:02:25.110: //13765/80B992BC5318/VTSP:(0/0/0):-1:-1:-1/vtsp_dsm_closed_cb:
Jul 24 17:02:25.110: //13765/80B992BC5318/VTSP:(0/0/0):-1:-1:-1/vtsp_process_event:
[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]
Jul 24 17:02:25.110: //13765/80B992BC5318/VTSP:(0/0/0):-1:-1:-1/act_terminate:
Jul 24 17:02:25.110: //13765/80B992BC5318/VTSP:(0/0/0):-1:-1:-1/vtsp_timer_stop:
Timer Stop Time=320954553
Jul 24 17:02:25.110: //13765/80B992BC5318/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x47204008, Tag=0x0, Call Id=13765,
Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)
Jul 24 17:02:25.110: //13765/80B992BC5318/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Jul 24 17:02:25.110: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 24 17:02:25.110: :cc_free_feature_vsa freeing 482FB260
Jul 24 17:02:25.110: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 24 17:02:25.110: vsacount in free is 1
Jul 24 17:02:25.110: //-1/80B992BC5318/VTSP:(0/0/0):-1:-1:-1/vtsp_free_cdb:
CDB=0x46421518
Jul 24 17:02:25.114: //13764/80B992BC5318/CCAPI/ccCallDisconnect:
Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Jul 24 17:02:25.114: //13764/80B992BC5318/CCAPI/ccCallDisconnect:
Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)
Jul 24 17:02:25.114: //13764/80B992BC5318/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null

Jul 24 17:02:25.114: //13764/80B992BC5318/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x473ABA6C, Tag=0x0, Call Id=13764,
Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)
Jul 24 17:02:25.118: //13764/80B992BC5318/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Jul 24 17:02:25.118: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 24 17:02:25.118: :cc_free_feature_vsa freeing 482FB340
Jul 24 17:02:25.118: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jul 24 17:02:25.118: vsacount in free is 0

Jul 24 17:02:43.722: %RADIUS-4-RADIUS_DEAD: RADIUS server 192.168.6.210:1645,1646 is not respond ing.
Jul 24 17:02:43.722: %RADIUS-4-RADIUS_ALIVE: RADIUS server 192.168.6.210:1645,1646 is being mark ed alive.
**********************************************************************************************************
debug h225 asn1:

H.225 ASN1 Messages debugging is on

Jul 24 17:12:21.310: H225.0 INCOMING ENCODE BUFFER::= 20B0060008914A000501401F004500640073006F006E0020004D006F006E0074006500690072006F00000000000000000000000000000000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E6167657200310001070033887BCC5A57B7B080480221DE21619757185701C0A806A000D50D80000700C0A8060306B8110080480221DE21619757185701C0A806A0010001000100010010A001000F0140B5000012088164000400010300
Jul 24 17:12:21.310:
Jul 24 17:12:21.314: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body setup :
{
protocolIdentifier { 0 0 8 2250 0 5 }
sourceAddress
{
h323-ID : {"Edson Monteiro..."}
}
sourceInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
productId '436973636F43616C6C4D616E61676572'H
versionId '31'H
}
terminal
{
}
mc FALSE
undefinedNode FALSE
}
destinationAddress
{
dialedDigits : "005548992724848"
}
activeMC FALSE
conferenceID '80480221DE21619757185701C0A806A0'H
conferenceGoal create : NULL
callType pointToPoint : NULL
sourceCallSignalAddress ipAddress :
{
ip 'C0A80603'H
port 1720
}
callIdentifier
{
guid '80480221DE21619757185701C0A806A0'H
}
mediaWaitForConnect FALSE
canOverlapSend FALSE
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
nonStandardControl
{

{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
data '8164000400010300'H
}
}
}
}

Jul 24 17:12:21.318: H225 NONSTD INCOMING ENCODE BUFFER::= 8164000400010300
Jul 24 17:12:21.318:
Jul 24 17:12:21.318: H225 NONSTD INCOMING PDU ::=

value H323_UU_NonStdInfo ::=
{
callMgrParam
{
interclusterVersion 3
enterpriseID {}
}
}

Jul 24 17:12:21.334: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body callProceeding :
{
protocolIdentifier { 0 0 8 2250 0 4 }
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
}
gateway
{
protocol
{
voice :
{
supportedPrefixes
{
}
}
}
}
mc FALSE
undefinedNode FALSE
}
h245Address ipAddress :
{
ip 'C0A80601'H
port 60961
}
callIdentifier
{
guid '80480221DE21619757185701C0A806A0'H
}
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
}
}

Jul 24 17:12:21.338: H225.0 OUTGOING ENCODE BUFFER::=
21C0060008914A00042800B500001240013C05010000C0A80601EE21110C110080480221DE21619757185701C0A806A00100010010800100
Jul 24 17:12:21.338:

Jul 24 17:12:22.586: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 4 }
callIdentifier
{
guid '80480221DE21619757185701C0A806A0'H
}
}
h245Tunneling FALSE
}
}

Jul 24 17:12:22.590: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A00041100110080480221DE21619757185701C0A806A010800100
Jul 24 17:12:22.590:

Jul 24 17:12:45.078: %RADIUS-4-RADIUS_DEAD: RADIUS server 192.168.7.81:1645,1646 is not responding.
Jul 24 17:12:45.078: %RADIUS-4-RADIUS_ALIVE: RADIUS server 192.168.7.81:1645,1646 is being marked alive.
Jul 24 17:12:56.150: %RADIUS-4-RADIUS_DEAD: RADIUS server 192.168.7.81:1645,1646 is not responding.
Jul 24 17:12:56.150: %RADIUS-4-RADIUS_ALIVE: RADIUS server 192.168.7.81:1645,1646 is being marked alive.
*******************************************************************************************************************************

debug h245 asn1: doesn't show nothing

***************************************************************************************************************

DNA:

DNA Analysis Output

  • Results Summary
    • Calling Party Information
      • Calling Party = 1169
      • Partition =
      • Device CSS =
      • Line CSS = CSS_TOTAL
      • AAR Group Name =
      • AAR CSS =
    • Dialed Digits = 900351939491221
    • Match Result = RouteThisPattern
    • Matched Pattern Information
      • Pattern = 9.!
      • Partition = PT_INTERNACIONAIS
      • Time Schedule = All the time
    • Called Party Number = 00351939491221
    • Time Zone = Greenwich Standard Time
    • End Device = RL-SISTEC1
    • Call Classification = OffNet
    • InterDigit Timeout = NO
    • Device Override = Disabled
    • Outside Dial Tone = NO
  • Call Flow
    • TranslationPattern :Pattern=
      • Positional Match List = 00351939491221
      • Calling Party Number = 1169
      • PreTransform Calling Party Number =
      • PreTransform Called Party Number =
      • Calling Party Transformations
        • External Phone Number Mask = NO
        • Calling Party Mask =
        • Prefix =
        • CallingLineId Presentation =
        • CallingName Presentation =
        • Calling Party Number = 1169
      • ConnectedParty Transformations
        • ConnectedLineId Presentation =
        • ConnectedName Presentation =
      • Called Party Transformations
        • Called Party Mask =
        • Discard Digits Instruction =
        • Prefix =
        • Called Number =
    • Route Pattern :Pattern= 9.!
      • Positional Match List = 00351939491221
      • DialPlan =
      • Route Filter
        • Filter Name =
        • Filter Clause =
      • Require Forced Authorization Code = No
      • Authorization Level = 0
      • Require Client Matter Code = No
      • Call Classification =
      • PreTransform Calling Party Number = 1169
      • PreTransform Called Party Number = 900351939491221
      • Calling Party Transformations
        • External Phone Number Mask = NO
        • Calling Party Mask =
        • Prefix =
        • CallingLineId Presentation = Default
        • CallingName Presentation = Default
        • Calling Party Number = 1169
      • ConnectedParty Transformations
        • ConnectedLineId Presentation = Default
        • ConnectedName Presentation = Default
      • Called Party Transformations
        • Called Party Mask =
        • Discard Digits Instruction = PreDot
        • Prefix =
        • Called Number = 00351939491221
    • Route List :Route List Name= RL-SISTEC1
      • RouteGroup :RouteGroup Name= RG-SISTEC1
        • PreTransform Calling Party Number = 1169
        • PreTransform Called Party Number = 900351939491221
        • Calling Party Transformations
          • External Phone Number Mask = Default
          • Calling Party Mask =
          • Prefix =
          • Calling Party Number = 1169
        • Called Party Transformations
          • Called Party Mask =
          • Discard Digits Instructions = PreDot
          • Prefix =
          • Called Number = 00351939491221
        • Device :Type= H323Gateway
          • End Device Name = 192.168.6.1
          • PortNumber = 0
          • Device Status = UnKnown
          • AAR Group Name =
          • AAR Calling Search Space =
          • AAR Prefix Digits =
          • Inbound Fast Start = Disabled
          • Outbound Fast Start = Disabled
          • Call Classification = Use System Default
          • Tunneled Protocol = None
          • Calling Party Transformations
            • PreTransform Calling Party Number = 1169
            • Calling Party Selection = Originator
            • Calling Party Presentation = Default
            • CallerID DN =
            • Calling Party Number = 1169
        • Device :Type= H323Gateway
          • End Device Name = 192.168.6.6
          • PortNumber = 0
          • Device Status = UnKnown
          • AAR Group Name =
          • AAR Calling Search Space =
          • AAR Prefix Digits =
          • Inbound Fast Start = Disabled
          • Outbound Fast Start = Disabled
          • Call Classification = Use System Default
          • Tunneled Protocol = None
          • Calling Party Transformations
            • PreTransform Calling Party Number = 1169
            • Calling Party Selection = Originator
            • Calling Party Presentation = Default
            • CallerID DN =
            • Calling Party Number = 1169
  • Alternate Matches
    • Note: Information Not Available

Hi,

Its looks like the call is getting disconnected with cause code 34, can you do shut \ no shut to FXO card, or perform a router reboot

No circuit/channel available

Typical scenarios include:

No B-channels are available to make the selected call.

34

Indicates that there is no appropriate circuit or channel presently available to handle the call.

(Rate if it helps)

JB

Dear Jitender, thanks for your reply

i rebooted the router but it not work

This is very constraining. Will not it be a problem at the route pattern in cucm?

Dennis Mink
VIP Alumni
VIP Alumni

first run the dialed number analyzer and check if the interational number is router through the right route pattern and is hitting the right gateway.

then debug dial peer on the gateway itself to see if the call is hitting the voice gateway at all.

Please rate if helpful

Please remember to rate useful posts, by clicking on the stars below.