08-20-2012 02:35 PM - edited 03-16-2019 12:47 PM
Tested this gateway before we deployed it in another town and have run into a problem One problem was inbound dialing which I corrected by battery-reversal on the FXO ports. I still have problem outbound though. I see the port go off-hook for a second and then back on-hook. Get a fast busy on the call. It appears to be hitting the right dial-peer in the ccapi debug but when I do a debug dialpeer I don't see anything. Like I said, it worked perfectly at the corporate office while testing. Not sure where else to look. Any ideas???
Here is the debug ccapi. Dialed number is 9 9857916891
BN-USHUM01-RTR01#
Aug 20 21:29:23.855: //-1/801D293F0D00/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=Houma Test
----- ccCallInfo IE subfields -----
cisco-ani=9858763175
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=99857916981
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
Aug 20 21:29:23.859: //-1/801D293F0D00/CCAPI/cc_api_call_setup_ind_common:
Interface=0x2AEED678, Call Info(
Calling Number=9858763175,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=99857916981(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=0, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=47
Aug 20 21:29:23.859: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Aug 20 21:29:23.859: :cc_get_feature_vsa malloc success
Aug 20 21:29:23.859: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Aug 20 21:29:23.859: cc_get_feature_vsa count is 1
Aug 20 21:29:23.859: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Aug 20 21:29:23.859: :FEATURE_VSA attributes are: feature_name:0,feature_time:1106321696,feature_id:47
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=9858763175(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=99857916981(TON=Unknown, NPI=Unknown))
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/cc_process_call_setup_ind:
Event=0x2B0CB348
Aug 20 21:29:23.859: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 99857916981
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/ccCallSetContext:
Context=0x420E248C
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 47 with tag 0 to app "_ManagedAppProcess_Default"
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=6, Params=0x420E399C, Progress Indication=NULL(0)
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/ccCheckClipClir:
In: Calling Number=9858763175(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/ccCheckClipClir:
Out: Calling Number=9858763175(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/ccCallSetupRequest:
Destination Pattern=9[2-9]........., Called Number=99857916981, Digit Strip=TRUE
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/ccCallSetupRequest:
Calling Number=9858763175(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=99857916981(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=
Account Number=Houma Test, Final Destination Flag=TRUE,
Guid=801D293F-70AC-2103-0D00-A0020A200710, Outgoing Dial-peer=6
Aug 20 21:29:23.859: //47/801D293F0D00/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=Houma Test
----- ccCallInfo IE subfields -----
cisco-ani=9858763175
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=99857916981
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
Aug 20 21:29:23.863: //47/801D293F0D00/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0xC1A17924, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=9858763175,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=99857916981(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=6, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
Aug 20 21:29:23.863: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Aug 20 21:29:23.863: :cc_get_feature_vsa malloc success
Aug 20 21:29:23.863: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Aug 20 21:29:23.863: cc_get_feature_vsa count is 2
Aug 20 21:29:23.863: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Aug 20 21:29:23.863: :FEATURE_VSA attributes are: feature_name:0,feature_time:1106321472,feature_id:48
Aug 20 21:29:23.863: //48/801D293F0D00/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
Aug 20 21:29:23.863: //48/801D293F0D00/CCAPI/ccCallSetContext:
Context=0x420E394C
Aug 20 21:29:23.863: //47/801D293F0D00/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=6
Aug 20 21:29:23.863: //48/801D293F0D00/CCAPI/cc_api_call_proceeding:
Interface=0xC1A17924, Progress Indication=NULL(0)
Aug 20 21:29:25.115: //48/801D293F0D00/CCAPI/cc_api_call_disconnected:
Cause Value=34, Interface=0xC1A17924, Call Id=48
Aug 20 21:29:25.115: //48/801D293F0D00/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=34, Retry Count=0)
Aug 20 21:29:25.115: //47/801D293F0D00/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
Aug 20 21:29:25.115: //48/801D293F0D00/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=48
Aug 20 21:29:25.115: //48/801D293F0D00/CCAPI/ccCallDisconnect:
Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=34)
Aug 20 21:29:25.115: //48/801D293F0D00/CCAPI/ccCallDisconnect:
Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)
Aug 20 21:29:25.115: //48/801D293F0D00/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0xC1A17924, Tag=0x0, Call Id=48,
Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)
Aug 20 21:29:25.115: //48/801D293F0D00/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Aug 20 21:29:25.115: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Aug 20 21:29:25.115: :cc_free_feature_vsa freeing 41F12038
Aug 20 21:29:25.115: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Aug 20 21:29:25.115: vsacount in free is 1
Aug 20 21:29:25.119: //47/801D293F0D00/CCAPI/ccCallDisconnect:
Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Aug 20 21:29:25.119: //47/801D293F0D00/CCAPI/ccCallDisconnect:
Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)
Aug 20 21:29:25.119: //47/801D293F0D00/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
Aug 20 21:29:25.119: //47/801D293F0D00/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x2AEED678, Tag=0x0, Call Id=47,
Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)
Aug 20 21:29:25.119: //47/801D293F0D00/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Aug 20 21:29:25.119: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Aug 20 21:29:25.119: :cc_free_feature_vsa freeing 41F12118
Aug 20 21:29:25.119: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Aug 20 21:29:25.119: vsacount in free is 0
Config is attached
08-20-2012 02:36 PM
Take debug vpm signal.
08-20-2012 02:38 PM
Actually the debug dial peer is working. Here is the vpm debug.
BN-USHUM01-RTR01#
Aug 20 21:37:10.327: htsp_timer_stop3 htsp_setup_req
Aug 20 21:37:10.327: htsp_process_event: [0/0/0, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup
Aug 20 21:37:10.327: [0/0/0] set signal state = 0xC timestamp = 0
Aug 20 21:37:10.327: htsp_timer - 1300 msec
Aug 20 21:37:10.579: htsp_process_event: [0/0/0, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected
Aug 20 21:37:10.579: htsp_timer2 - 1000 msec
Aug 20 21:37:10.579: htsp_timer_stop
Aug 20 21:37:11.579: htsp_process_event: [0/0/0, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER2]fxols_power_den_disc
Aug 20 21:37:11.579: htsp_timer_stop
Aug 20 21:37:11.579: htsp_timer_stop2
Aug 20 21:37:11.579: [0/0/0] set signal state = 0x4 timestamp = 0
Aug 20 21:37:11.579: htsp_process_event: [0/0/0, FXOLS_ONHOOK, E_HTSP_RELEASE_REQ]fxols_onhook_release
BN-USHUM01-RTR01#
08-20-2012 03:11 PM
E_DSP_SIG_1100]fxols_power_denial_detected
There is some issue with the circuit, low or unrealiable loop current.
08-20-2012 04:46 PM
Battery reversal fixed inbound calling? Makes me start to think you might be hitting a ground start circuit but your debugs are showing it as loop start. Try switching the signaling and see if that helps. Make sure you do a shut no shut on the voice port for the change to take place.
Sent from Cisco Technical Support iPad App
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