Buy or Renew
Buy or Renew
Cisco + Splunk: It’s a new day for your data. Learn more.
 
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Start a conversation
2768
Views
0
Helpful
22
Replies

FXO port, hears 5 rings before the call is connected

Go to solution
nguyentienthanh
Level 1
Level 1

‎02-16-2012 07:21 AM - edited ‎03-16-2019 09:38 AM

Dear all,

When an incoming call rings on a Cisco 2921 FXO port, the caller hears 5 rings before the call is connected.  Does anyone know of a way to shorten or eliminate  this delay?

Please find attached the configuration and sceanario as following:

Cisco 2921 04 FXO with H323 configuration and 4 FXS with MGCP configuration.

Cisco Call Manager 8.6

Thanks you for your help.

Labels:
122617-HO-VG-02_1602.txt.zip
0 Helpful
22 Replies 22

Go to solution
acampbell
VIP Alumni
VIP Alumni
In response to paolo bevilacqua

‎02-20-2012 03:34 AM

Hi,

Can you make the test call then answer it on your ip phone.
Keep the call alive and display the following command on the router

sh voice call status

I want to see which dial peers are being used

Can you post this output

Regards
Alex

Regards, Alex. Please rate useful posts.
0 Helpful

Go to solution
nguyentienthanh
Level 1
Level 1
In response to paolo bevilacqua

‎02-20-2012 06:06 AM

Hi all,

Please find for full trace.

=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2012.02.20 17:28:52 =~=~=~=~=~=~=~=~=~=~=~=

HO-VG-02#

Feb 20 10:28:55.203: //-1/xxxxxxxxxxxx/CCAPI/ccAppShutdownMode:

   ccAppShutdownMode: remove it from the queue

HO-VG-02#

Feb 20 10:29:03.067: htsp_process_event: [0/0/2, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

Feb 20 10:29:03.067: htsp_timer - 125 msec

Feb 20 10:29:03.195: htsp_process_event: [0/0/2, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer

Feb 20 10:29:03.195: htsp_timer - 10000 msec

Feb 20 10:29:04.183: htsp_process_event: [0/0/2, FXOLS_RINGING, E_DSP_SIG_0100]

Feb 20 10:29:04.183: fxols_ringing_not

Feb 20 10:29:04.183: htsp_timer_stop

Feb 20 10:29:04.183: htsp_timer_stop3 htsp_setup_ind

Feb 20 10:29:04.183: [0/0/2] get_local_station_id calling num=35129471 calling name=PSTN calling time=02/20 10:29  orig called=

Feb 20 10:29:04.183: //-1/8BC5567D80AF/CCAPI/cc_api_display_ie_subfields:

   cc_api_call_setup_ind_common:

   cisco-username=

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

   cisco-ani=35129471

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=0

   dest=6002

   cisco-desttype=0

   cisco-destplan=0

   cisco-rdie=FFFFFFFF

   cisco-rdn=

   cisco-rdntype=0

   cisco-rdnplan=0

   cisco-rdnpi=0

   cisco-rdnsi=0

   cisco-redirectreason=0   fwd_final_type =0

   final_redirectNumber =

   hunt_group_timeout =0

Feb 20 10:29:04.183: //-1/8BC5567D80AF/CCAPI/cc_api_call_setup_ind_common:

   Interface=0x2B624208, Call Info(

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

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

   Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,

   Incoming Dial-peer=21, 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

Feb 20 10:29:04.183: //-1/8BC5567D80AF/CCAPI/ccCheckClipClir:

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

Feb 20 10:29:04.183: //-1/8BC5567D80AF/CCAPI/ccCheckClipClir:

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

Feb 20 10:29:04.183: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 20 10:29:04.183: :cc_get_feature_vsa malloc success

Feb 20 10:29:04.183: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 20 10:29:04.183:  cc_get_feature_vsa count is 1

Feb 20 10:29:04.183: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 20 10:29:04.183: :FEATURE_VSA attributes are: feature_name:0,feature_time:730651336,feature_id:81

Feb 20 10:29:04.183: //81/8BC5567D80AF/CCAPI/cc_api_call_setup_ind_common:

   Set Up Event Sent;

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

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

Feb 20 10:29:04.187: htsp_process_event: [0/0/2, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

Feb 20 10:29:04.187: fxols_wait_setup_ack:

Feb 20 10:29:04.187: htsp_timer - 6000 msec

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/cc_process_call_setup_ind:

   Event=0x2B5E8670

Feb 20 10:29:04.187: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

   Try with the demoted called number 6002

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/ccCallSetContext:

   Context=0x3003378C

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/cc_process_call_setup_ind:

   >>>>CCAPI handed cid 81 with tag 21 to app "_ManagedAppProcess_Default"

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/ccCallProceeding:

   Progress Indication=NULL(0)

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/ccCallSetupRequest:

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

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

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/ccCheckClipClir:

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

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/ccCheckClipClir:

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

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/ccCallSetupRequest:

   Destination Pattern=[1-6]..., Called Number=6002, Digit Strip=FALSE

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/ccCallSetupRequest:

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

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

   Redirect Number=, Display Info=PSTN

   Account Number=, Final Destination Flag=TRUE,

   Guid=8BC5567D-5AE4-11E1-80AF-DE96B466FA22, Outgoing Dial-peer=1

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/cc_api_display_ie_subfields:

   ccCallSetupRequest:

   cisco-username=

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

   cisco-ani=35129471

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=0

   dest=6002

   cisco-desttype=0

   cisco-destplan=0

   cisco-rdie=FFFFFFFF

   cisco-rdn=

   cisco-rdntype=0

   cisco-rdnplan=0

   cisco-rdnpi=0

   cisco-rdnsi=0

   cisco-redirectreason=0   fwd_final_type =0

   final_redirectNumber =

   hunt_group_timeout =0

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/ccIFCallSetupRequestPrivate:

   Interface=0x30F98260, Interface Type=1, Destination=, Mode=0x0,

   Call Params(Calling Number=35129471,(Calling Name=PSTN)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

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

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

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

Feb 20 10:29:04.187: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 20 10:29:04.187: :cc_get_feature_vsa malloc success

Feb 20 10:29:04.187: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 20 10:29:04.187:  cc_get_feature_vsa count is 2

Feb 20 10:29:04.187: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 20 10:29:04.187: :FEATURE_VSA attributes are: feature_name:0,feature_time:730651112,feature_id:82

Feb 20 10:29:04.187: //82/8BC5567D80AF/CCAPI/ccIFCallSetupRequestPrivate:

   SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1

Feb 20 10:29:04.187: //82/8BC5567D80AF/CCAPI/ccCallSetContext:

   Context=0x3002E72C

Feb 20 10:29:04.187: //81/8BC5567D80AF/CCAPI/ccSaveDialpeerTag:

   Outgoing Dial-peer=1

Feb 20 10:29:04.187: htsp_process_event: [0/0/2, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc

Feb 20 10:29:08.151: htsp_process_event: [0/0/2, FXOLS_PROCEEDING, E_DSP_SIG_0000]fxols_proceed_ring

Feb 20 10:29:08.151: htsp_timer_stop

Feb 20 10:29:08.151: htsp_timer_stop2

Feb 20 10:29:09.239: htsp_process_event: [0/0/2, FXOLS_PROCEEDING, E_DSP_SIG_0100]fxols_proceed_clear

Feb 20 10:29:09.239: htsp_timer_stop2

Feb 20 10:29:09.239: htsp_timer - 6000 msec

Feb 20 10:29:13.203: htsp_process_event: [0/0/2, FXOLS_PROCEEDING, E_DSP_SIG_0000]fxols_proceed_ring

Feb 20 10:29:13.207: htsp_timer_stop

Feb 20 10:29:13.207: htsp_timer_stop2

Feb 20 10:29:14.315: htsp_process_event: [0/0/2, FXOLS_PROCEEDING, E_DSP_SIG_0100]fxols_proceed_clear

Feb 20 10:29:14.315: htsp_timer_stop2

Feb 20 10:29:14.315: htsp_timer - 6000 msec

Feb 20 10:29:18.283: htsp_process_event: [0/0/2, FXOLS_PROCEEDING, E_DSP_SIG_0000]fxols_proceed_ring

Feb 20 10:29:18.283: htsp_timer_stop

Feb 20 10:29:18.283: htsp_timer_stop2

Feb 20 10:29:19.191: //82/8BC5567D80AF/CCAPI/cc_api_call_disconnected:

   Cause Value=18, Interface=0x30F98260, Call Id=82

Feb 20 10:29:19.191: //82/8BC5567D80AF/CCAPI/cc_api_call_disconnected:

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

Feb 20 10:29:19.191: //81/8BC5567D80AF/CCAPI/ccCallReleaseResources:

   release reserved xcoding resource.

Feb 20 10:29:19.191: //82/8BC5567D80AF/CCAPI/ccCallSetAAA_Accounting:

   Accounting=1, Call Id=82

Feb 20 10:29:19.191: //82/8BC5567D80AF/CCAPI/ccCallDisconnect:

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

Feb 20 10:29:19.191: //82/8BC5567D80AF/CCAPI/ccCallDisconnect:

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

Feb 20 10:29:19.191: //82/8BC5567D80AF/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

Feb 20 10:29:19.191: //82/8BC5567D80AF/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x30F98260, Tag=0x0, Call Id=82,

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

Feb 20 10:29:19.191: //82/8BC5567D80AF/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

Feb 20 10:29:19.191: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 20 10:29:19.191: :cc_free_feature_vsa freeing 2B8CD9E0

Feb 20 10:29:19.191: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 20 10:29:19.191:  vsacount in free is 1

Feb 20 10:29:19.191: //81/8BC5567D80AF/CCAPI/ccCallSetupRequest:

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

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

Feb 20 10:29:19.191: //81/8BC5567D80AF/CCAPI/ccCheckClipClir:

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

Feb 20 10:29:19.191: //81/8BC5567D80AF/CCAPI/ccCheckClipClir:

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

Feb 20 10:29:19.191: //81/8BC5567D80AF/CCAPI/ccCallSetupRequest:

   Destination Pattern=[1-6]..., Called Number=6002, Digit Strip=FALSE

Feb 20 10:29:19.191: //81/8BC5567D80AF/CCAPI/ccCallSetupRequest:

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

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

   Redirect Number=, Display Info=PSTN

   Account Number=, Final Destination Flag=TRUE,

   Guid=8BC5567D-5AE4-11E1-80AF-DE96B466FA22, Outgoing Dial-peer=2

Feb 20 10:29:19.191: //81/8BC5567D80AF/CCAPI/cc_api_display_ie_subfields:

   ccCallSetupRequest:

   cisco-username=

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

   cisco-ani=35129471

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=0

   dest=6002

   cisco-desttype=0

   cisco-destplan=0

   cisco-rdie=FFFFFFFF

   cisco-rdn=

   cisco-rdntype=0

   cisco-rdnplan=0

   cisco-rdnpi=0

   cisco-rdnsi=0

   cisco-redirectreason=0   fwd_final_type =0

   final_redirectNumber =

   hunt_group_timeout =0

Feb 20 10:29:19.191: //81/8BC5567D80AF/CCAPI/ccIFCallSetupRequestPrivate:

   Interface=0x30F98260, Interface Type=1, Destination=, Mode=0x0,

   Call Params(Calling Number=35129471,(Calling Name=PSTN)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

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

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

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

Feb 20 10:29:19.191: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 20 10:29:19.191: :cc_get_feature_vsa malloc success

Feb 20 10:29:19.191: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 20 10:29:19.191:  cc_get_feature_vsa count is 2

Feb 20 10:29:19.191: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 20 10:29:19.191: :FEATURE_VSA attributes are: feature_name:0,feature_time:730651112,feature_id:83

Feb 20 10:29:19.191: //83/8BC5567D80AF/CCAPI/ccIFCallSetupRequestPrivate:

   SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1

Feb 20 10:29:19.191: //83/8BC5567D80AF/CCAPI/ccCallSetContext:

   Context=0x30032374

Feb 20 10:29:19.191: //81/8BC5567D80AF/CCAPI/ccSaveDialpeerTag:

   Outgoing Dial-peer=2

Feb 20 10:29:19.199: //83/8BC5567D80AF/CCAPI/cc_api_set_called_ccm_detected:

   CallInfo(called ccm detected=TRUE ccmVersion 3)

Feb 20 10:29:19.199: //83/8BC5567D80AF/CCAPI/cc_api_call_proceeding:

   Interface=0x30F98260, Progress Indication=NULL(0)

Feb 20 10:29:19.231: //83/8BC5567D80AF/CCAPI/cc_api_set_called_ccm_detected:

   CallInfo(called ccm detected=TRUE ccmVersion 3)

Feb 20 10:29:19.231: //83/8BC5567D80AF/CCAPI/cc_api_set_delay_xport:

   CallInfo(delay xport=TRUE)

Feb 20 10:29:19.231: //83/8BC5567D80AF/CCAPI/cc_api_call_alert:

   Interface=0x30F98260, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)

Feb 20 10:29:19.231: //83/8BC5567D80AF/CCAPI/cc_api_call_alert:

   Call Entry(Retry Count=0, Responsed=TRUE)

Feb 20 10:29:19.231: //81/8BC5567D80AF/CCAPI/ccCallAlert:

   Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)

Feb 20 10:29:19.231: //81/8BC5567D80AF/CCAPI/ccCallAlert:

   Call Entry(Responsed=TRUE, Alert Sent=TRUE)htsp_alert_notify

Feb 20 10:29:19.231: htsp_process_event: [0/0/2, FXOLS_PROCEEDING, E_HTSP_ALERT]fxols_offhook_alert

Feb 20 10:29:19.231: //83/8BC5567D80AF/CCAPI/cc_api_set_called_ccm_detected:

   CallInfo(called ccm detected=TRUE ccmVersion 3)

Feb 20 10:29:19.231: //83/8BC5567D80AF/CCAPI/cc_api_call_notify:

   Data Bitmask=0x5, Interface=0x30F98260, Call Id=83

Feb 20 10:29:19.231: //83/8BC5567D80AF/CCAPI/cc_api_get_ssCTreRoutingNotSupported:

   CallInfo(ssCTreRoutingNotSupported=FALSE)

Feb 20 10:29:19.235: //83/8BC5567D80AF/CCAPI/cc_api_get_ccm_detected:

   CallInfo(ccm detected=TRUE)

Feb 20 10:29:19.235: //81/8BC5567D80AF/CCAPI/ccCallNotify:

   Data Bitmask=0x5, Call Id=81htsp_call_service_msghtsp_call_service_msg not EFXS (2)

Feb 20 10:29:19.251: //83/8BC5567D80AF/CCAPI/ccIsInfoRingback:

   Returning dpRingBack=0

Feb 20 10:29:19.267: //83/8BC5567D80AF/CCAPI/cc_api_call_connected:

   Interface=0x30F98260, Data Bitmask=0x0, Progress Indication=NULL(0),

   Connection Handle=0

Feb 20 10:29:19.267: //83/8BC5567D80AF/CCAPI/cc_api_call_connected:

   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)

Feb 20 10:29:19.267: //83/8BC5567D80AF/CCAPI/cc_api_set_called_ccm_detected:

   CallInfo(called ccm detected=TRUE ccmVersion 3)

Feb 20 10:29:19.267: //83/8BC5567D80AF/CCAPI/cc_api_call_notify:

   Data Bitmask=0x5, Interface=0x30F98260, Call Id=83

Feb 20 10:29:19.267: //81/8BC5567D80AF/CCAPI/ccGenerateToneInfo:

   Stop Tone On Digit=FALSE, Tone=Null,

   Tone Direction=Network, Params=0x0, Call Id=81

Feb 20 10:29:19.267: //81/8BC5567D80AF/CCAPI/ccConferenceCreate:

   (confID=0x2B93E19C, callID1=0x51, gcid=8BC5567D-5AE411E1-80AFDE96-B466FA22, tag=0x0)

Feb 20 10:29:19.267: //83/8BC5567D80AF/CCAPI/ccConferenceCreate:

   (confID=0x2B93E19C, callID2=0x53, gcid=8BC5567D-5AE411E1-80AFDE96-B466FA22, tag=0x0)

Feb 20 10:29:19.267: //81/8BC5567D80AF/CCAPI/ccConferenceCreate:

   Conference Id=0x2B93E19C, Call Id1=81, Call Id2=83, Tag=0x0

Feb 20 10:29:19.267: htsp_call_bridged invoked

Feb 20 10:29:19.267: //81/8BC5567D80AF/CCAPI/cc_api_bridge_done:

   Conference Id=0x13, Source Interface=0x2B624208, Source Call Id=81,

   Destination Call Id=83, Disposition=0x0, Tag=0xFFFFFFFF

Feb 20 10:29:19.267: //83/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Feb 20 10:29:19.267: cc_api_get_xcode_stream : 4702

Feb 20 10:29:19.267: //83/8BC5567D80AF/CCAPI/cc_api_bridge_done:

   Conference Id=0x13, Source Interface=0x30F98260, Source Call Id=83,

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

Feb 20 10:29:19.267: //81/8BC5567D80AF/CCAPI/cc_generic_bridge_done:

   Conference Id=0x13, Source Interface=0x30F98260, Source Call Id=83,

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

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/ccConferenceCreate:

   Call Entry(Conference Id=0x13, Destination Call Id=83)

Feb 20 10:29:19.271: //83/8BC5567D80AF/CCAPI/ccConferenceCreate:

   Call Entry(Conference Id=0x13, Destination Call Id=81)

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/cc_api_caps_ind:

   Destination Interface=0x30F98260, Destination Call Id=83, Source Call Id=81,

   Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,

   Modem=0x2, Codec Bytes=20, Signal Type=3)

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/cc_api_caps_ind:

   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),

   Playout Max=1000(ms), Fax Nom=300(ms))

Feb 20 10:29:19.271: //83/8BC5567D80AF/CCAPI/cc_api_get_ssCTreRoutingNotSupported:

   CallInfo(ssCTreRoutingNotSupported=FALSE)

Feb 20 10:29:19.271: //83/8BC5567D80AF/CCAPI/cc_api_get_ccm_detected:

   CallInfo(ccm detected=TRUE)

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/ccCallNotify:

   Data Bitmask=0x5, Call Id=81htsp_call_service_msghtsp_call_service_msg not EFXS (2)

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/cc_process_notify_bridge_done:

   Conference Id=0x13, Call Id1=81, Call Id2=83

Feb 20 10:29:19.271: //83/8BC5567D80AF/CCAPI/cc_api_caps_ind:

   Destination Interface=0x2B624208, Destination Call Id=81, Source Call Id=83,

   Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2,

   Modem=0x0, Codec Bytes=160, Signal Type=2)

Feb 20 10:29:19.271: //83/8BC5567D80AF/CCAPI/cc_api_caps_ind:

   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),

   Playout Max=1000(ms), Fax Nom=300(ms))

Feb 20 10:29:19.271: //83/8BC5567D80AF/CCAPI/cc_api_caps_ack:

   Destination Interface=0x2B624208, Destination Call Id=81, Source Call Id=83,

   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),

   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=8431)

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/cc_api_caps_ack:

   Destination Interface=0x30F98260, Destination Call Id=83, Source Call Id=81,

   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),

   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=8431)

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/ccCallConnect:

   Progress Indication=NULL(0), Data Bitmask=0x1

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/ccCallConnect:

   Call Entry(Connected=TRUE, Responsed=TRUE)

Feb 20 10:29:19.271: htsp_process_event: [0/0/2, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_connect

Feb 20 10:29:19.271: [0/0/2] set signal state = 0xC timestamp = 0

Feb 20 10:29:19.271: htsp_timer_stop

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/cc_api_voice_mode_event:

   Call Id=81

Feb 20 10:29:19.271: //81/8BC5567D80AF/CCAPI/cc_api_voice_mode_event:

   Call Entry(Context=0x3003378C)

Feb 20 10:29:19.271: htsp_process_event: [0/0/2, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_connect_proc_voice

Feb 20 10:29:19.475: htsp_process_event: [0/0/2, FXOLS_CONNECT, E_DSP_SIG_0100]fxols_normal_battery

Feb 20 10:29:19.475: htsp_timer_stop2

Feb 20 10:29:36.135: //83/8BC5567D80AF/CCAPI/cc_api_call_disconnected:

   Cause Value=16, Interface=0x30F98260, Call Id=83

Feb 20 10:29:36.135: //83/8BC5567D80AF/CCAPI/cc_api_call_disconnected:

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

Feb 20 10:29:36.135: //81/8BC5567D80AF/CCAPI/ccConferenceDestroy:

   Conference Id=0x13, Tag=0x0

Feb 20 10:29:36.135: //81/8BC5567D80AF/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x13, Source Interface=0x2B624208, Source Call Id=81,

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

Feb 20 10:29:36.135: //83/8BC5567D80AF/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x13, Source Interface=0x30F98260, Source Call Id=83,

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

Feb 20 10:29:36.135: //81/8BC5567D80AF/CCAPI/cc_generic_bridge_done:

   Conference Id=0x13, Source Interface=0x30F98260, Source Call Id=83,

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

Feb 20 10:29:36.139: //81/8BC5567D80AF/CCAPI/ccCallDisconnect:

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

Feb 20 10:29:36.139: //81/8BC5567D80AF/CCAPI/ccCallDisconnect:

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

Feb 20 10:29:36.139: //81/8BC5567D80AF/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

Feb 20 10:29:36.139: //83/8BC5567D80AF/CCAPI/ccCallDisconnect:

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

Feb 20 10:29:36.139: //83/8BC5567D80AF/CCAPI/ccCallDisconnect:

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

Feb 20 10:29:36.139: //83/8BC5567D80AF/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

Feb 20 10:29:36.139: htsp_timer_stop3

Feb 20 10:29:36.143: //83/8BC5567D80AF/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

Feb 20 10:29:36.143: //83/8BC5567D80AF/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x30F98260, Tag=0x0, Call Id=83,

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

Feb 20 10:29:36.143: //83/8BC5567D80AF/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

Feb 20 10:29:36.143: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 20 10:29:36.143: :cc_free_feature_vsa freeing 2B8CD9E0

Feb 20 10:29:36.143: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 20 10:29:36.143:  vsacount in free is 1

Feb 20 10:29:36.151: htsp_process_event: [0/0/2, FXOLS_CONNECT, E_HTSP_RELEASE_REQ]fxols_offhook_release

Feb 20 10:29:36.151: htsp_timer_stop

Feb 20 10:29:36.151: htsp_timer_stop2

Feb 20 10:29:36.151: htsp_timer_stop3

Feb 20 10:29:36.151: [0/0/2] set signal state = 0x4 timestamp = 0

Feb 20 10:29:36.151: htsp_timer - 2000 msec

Feb 20 10:29:36.151: //81/8BC5567D80AF/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x2B624208, Tag=0x0, Call Id=81,

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

Feb 20 10:29:36.151: //81/8BC5567D80AF/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

Feb 20 10:29:36.151: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 20 10:29:36.151: :cc_free_feature_vsa freeing 2B8CDAC0

Feb 20 10:29:36.151: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 20 10:29:36.151:  vsacount in free is 0

Feb 20 10:29:38.151: htsp_process_event: [0/0/2, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout

Feb 20 10:29:38.151: htsp_process_event: [0/0/2, FXOLS_ONHOOK, E_DSP_SIG_0100]

122801-putty.log.zip
0 Helpful

Go to solution
acampbell
VIP Alumni
VIP Alumni
In response to nguyentienthanh

‎02-20-2012 07:05 AM

Hi,

Your router is failing with a cause code 18 on dial peer 1

!

dial-peer voice 1 voip

preference 1

destination-pattern [1-7]...

session target ipv4:172.16.69.10

dtmf-relay h245-alphanumeric

codec g711ulaw

!

dial-peer voice 2 voip

preference 2

destination-pattern [1-7]...

session target ipv4:172.16.69.14

dtmf-relay h245-alphanumeric

codec g711ulaw

This is your preference 1 --- 1st choice

It then timesout and reties on dial peer 2 --  this is succesful.

Look for connectivity issues between the router IP address 172.16.69.18 and the call processor CCM on 172.16.69.10

May be the call manager service is not running or there is no pings etc.

HTH

Alex

Please rate useful posts

Regards, Alex. Please rate useful posts.
0 Helpful

Go to solution
nguyentienthanh
Level 1
Level 1
In response to acampbell

‎02-23-2012 06:03 PM

Dear acampbell,

Your idea is correct, but callmanager service in CCM1 (172.16.69.10) is running. When i delete preference 1, it connected imediately. In CallManager 11, i can ping Gateway and ViceVersace so What is the main cause this problem ?

I attached two log:

1 - DebugOK with only dial peer to CCM2 (172.16.69.14)

2 - DebugNoOK with dial peer to CCM1 (172.16.69.10)

Thanks you very much.

Regards,

Thanh

123216-DebugNoOk.txt.zip
0 Helpful

Go to solution
acampbell
VIP Alumni
VIP Alumni
In response to nguyentienthanh

‎02-24-2012 01:42 AM

Hi,

You have proved that the gateway is GOOD.

Your issue must lie with the CUCM cluster.

Look at the set of your H323 gateway page in CUCM.

Does the Device Pool include a Call Manager Group that contains both

the server 172.16.68.14 & 172.16.69.10

HTH

Alex

Please rate useful posts.

Regards, Alex. Please rate useful posts.
0 Helpful

Go to solution
nguyentienthanh
Level 1
Level 1
In response to acampbell

‎03-04-2012 05:11 AM

Hi Alex,

Thanks for your help, but Device Pool i set is default and include two servers 172.16.68.14 & 172.16.69.10. Any recommend from you ?

Regards,

Thanh

0 Helpful

Go to solution
Kasiraman S
Level 1
Level 1
In response to nguyentienthanh

‎03-04-2012 07:11 AM

Hi Thanh,

Can you make sure if the call manager service is running in CUCM server 172.16.69.10 and also can you check if the IP 172.16.69.10 is listed first in CUCM Group that was selected on device pool page.

Thanks,

Kasi

0 Helpful

Go to solution
nguyentienthanh
Level 1
Level 1
In response to Kasiraman S

‎03-04-2012 07:29 AM

Dear Kasiraman,

I am sure that because, when i tried with another voice gateway (3845), it worked. it just happened on Voice Gateway 2921. I can't understand. Two gateway configuration are the same

Regards,

Thanh

0 Helpful
Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community:

Customers Also Viewed These Support Documents