Hi All,
When i turned on debugging at FXO port to troubleshoot an issue, when call lands on Gateway, it should play an prompt of "Welcome to XXX Company" but i hear a ringtone and then immediately an re-order / Fast Busy tone. Can anyone help me to interpret this output? I can see my cell number as "calling number", Please find below the out of "sh dial-peer voice summary" and "show debugging"
Thanks in advance.
abc-def-ghi#sh dial-peer voice summary
dial-peer hunt 0
AD PRE PASS OUT
TAG TYPE MIN OPER PREFIX DEST-PATTERN FER THRU SESS-TARGET STAT
PORT
110 pots up up 9.T 0 up
0/2/1
111 pots up up 0 down
0/2/1
3000 voip up up 0 syst
3001 voip up up 1000 0 syst ipv4:10.255.63.254
3002 voip up up 1300 0 syst ipv4:10.255.63.254
3003 voip down down 1000 2 syst ipv4:10.163.242.2
abc-def-ghi#
*Oct 16 03:35:00.795: htsp_process_event: [0/2/3, FXOLS_ONHOOK, E_DSP_SIG_0000]f
xols_onhook_ringing
*Oct 16 03:35:00.799: TGRM: reg_invoke_tgrm_call_update(0, 2, 3, 65535, 1, TGRM_
CALL_PENDING, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)
*Oct 16 03:35:00.799: htsp_timer - 125 msec
*Oct 16 03:35:00.927: htsp_process_event: [0/2/3, FXOLS_WAIT_RING_MIN, E_HTSP_EV
ENT_TIMER]fxols_wait_ring_min_timer
*Oct 16 03:35:00.927: htsp_timer - 10000 msec
*Oct 16 03:35:00.927: htsp_timer3 - 5600 msec
*Oct 16 03:35:00.927: [0/2/3] htsp_start_caller_id_rx:BELLCORE
*Oct 16 03:35:00.927: //-1/313233343930/DSM:(0/2/3):-1/dsp_stream_mgr_create: .
*Oct 16 03:35:00.927: //-1/313233343930/DSM:(0/2/3):-1/set_echo_canceller_data:
echo_cancel: 0
*Oct 16 03:35:00.927: //-1/313233343930/DSM:(0/2/3):-1/set_echo_canceller_data:
echo_flags: 38, echo_len: 512
*Oct 16 03:35:00.927: //-1/313233343930/DSM:(0/2/3):-1/dsp_stream_mgr_implement_
feature:
*Oct 16 03:35:00.927: dsp_stream_mgr_implement_feature: feature id = 7
*Oct 16 03:35:00.927: //-1/313233343930/DSM:(0/2/3):-1/dsmp_dsmapi_reserve_resou
rce_cb: .
*Oct 16 03:35:00.927: [0/2/3] htsp_dsm_create_success returns 1
*Oct 16 03:35:00.927: //-1/313233343930/DSM:(0/2/3):-1/dsmp_dsmapi_reserve_resou
rce_cb: .
*Oct 16 03:35:00.927: [0/2/3] htsp_dsm_is_sig_done_cb
*Oct 16 03:35:01.891: htsp_process_event: [0/2/3, FXOLS_RINGING, E_DSP_SIG_0100]
*Oct 16 03:35:01.891: fxols_ringing_not
*Oct 16 03:35:01.891: htsp_timer_stop
*Oct 16 03:35:01.891: htsp_timer - 10000 msec
*Oct 16 03:35:02.931: //-1/313233343930/DSM:(0/2/3):-1/dsmp_dsmapi_detection_cb:
.
*Oct 16 03:35:02.931: [0/2/3] htsp_dsm_feature_notify_cb returns 2 id=DSM_FEATU
RE_SM_CALLERID_RX
*Oct 16 03:35:02.931: htsp_process_event: [0/2/3, FXOLS_RINGING, E_HTSP_CALLERID
_RX_DONE]
*Oct 16 03:35:02.931: htsp_timer_stop
*Oct 16 03:35:02.931: [0/2/3] htsp_stop_caller_id_rx. message length 28
*Oct 16 03:35:02.931: //-1/313233343930/DSM:(0/2/3):-1/dsp_stream_mgr_destroy: .
*Oct 16 03:35:02.931: //-1/313233343930/DSM:(0/2/3):-1/dsm_delete_all_streams: .
*Oct 16 03:35:02.931: //-1/313233343930/DSM:(0/2/3):-1/dsmapi_accept_modem_passt
hrough_session: : dmgr: 4778EFE0, active sessions 0, max sessions: 16 rejected
sessions till now: 0htsp_setup_ind
*Oct 16 03:35:02.935: [0/2/3] get_fxo_caller_id:Caller ID received. Message type
=128 length=26 checksum=7B
*Oct 16 03:35:02.935: [0/2/3] Caller ID String 80 17 01 08 31 30 31 36 30 38 32
31 02 0B 30 33 30 31 38 35 36 39 36 36 39 7B
*Oct 16 03:35:02.935: [0/2/3] get_fxo_caller_id calling num=03018569669 calling
name= calling time=10/16 08:21
*Oct 16 03:35:02.935: TGRM: reg_invoke_tgrm_accept_call(1, TGRM_CALL_VOICE, TGRM
_DIRECTION_IN, 0, 2, 3, 65535)
*Oct 16 03:35:02.935: TGRM: Calling vtsp_tsp_call_setup_ind().
*Oct 16 03:35:02.935: fxols_callerid_done: call being answered
*Oct 16 03:35:02.939: htsp_process_event: [0/2/3, FXOLS_WAIT_SETUP_ACK, E_HTSP_S
ETUP_ACK]
*Oct 16 03:35:02.939: fxols_wait_setup_ack:
*Oct 16 03:35:02.939: [0/2/3] set signal state = 0xC timestamp = 0
*Oct 16 03:35:02.939: dsp_set_sig_state: [0/2/3] packet_len=12 channel_id=131 pa
cket_id=39 state=0xC timestamp=0x0fxols_check_auto_call
*Oct 16 03:35:02.947: flex_dsprm_close_cleanup
*Oct 16 03:35:02.947: //-1/313233343930/DSM:(0/2/3):-1/dsmp_dsmapi_reserve_resou
rce_cb: .
*Oct 16 03:35:02.947: [0/2/3] htsp_dsm_close_done
*Oct 16 03:35:02.951: htsp_process_event: [0/2/3, FXOLS_PROCEEDING, E_HTSP_PROCE
EDING]fxols_offhook_proc
*Oct 16 03:35:02.951: htsp_timer - 120000 msec
*Oct 16 03:35:02.955: htsp_pre_connect_disconnect, cdb = 46049110 cause = 26
*Oct 16 03:35:02.959: htsp_process_event: [0/2/3, FXOLS_PROCEEDING, E_HTSP_PRE_C
ONN_DISC]
*Oct 16 03:35:03.243: htsp_process_event: [0/2/3, FXOLS_OFFHOOK, E_DSP_SIG_0110]
fxols_offhook_rvs_batteryhtsp_release_req: cause 38, no_onhook 0
*Oct 16 03:35:05.955: htsp_process_event: [0/2/3, FXOLS_CONNECT, E_HTSP_RELEASE_
REQ]fxols_offhook_release
*Oct 16 03:35:05.955: htsp_timer_stop
*Oct 16 03:35:05.955: htsp_timer_stop2
*Oct 16 03:35:05.955: htsp_timer_stop3
*Oct 16 03:35:05.955: [0/2/3] set signal state = 0x4 timestamp = 0
*Oct 16 03:35:05.955: dsp_set_sig_state: [0/2/3] packet_len=12 channel_id=131 pa
cket_id=39 state=0x4 timestamp=0x0
*Oct 16 03:35:05.955: TGRM: reg_invoke_tgrm_call_update(0, 2, 3, 65535, 1, TGRM_
CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)
*Oct 16 03:35:05.955: htsp_timer - 2000 msec
*Oct 16 03:35:05.955: TGRM: reg_invoke_tgrm_call_update(0, 2, 3, 65535, 1, TGRM_
CALL_BUSY, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT)
*Oct 16 03:35:05.955: flex_dsprm_close_cleanup
*Oct 16 03:35:06.227: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT, E_DSP_SIG_011
0]
*Oct 16 03:35:07.955: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT, E_HTSP_EVENT_
TIMER]fxols_guard_out_timeout
*Oct 16 03:35:07.955: TGRM: reg_invoke_tgrm_call_update(0, 2, 3, 65535, 1, TGRM_
CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT)
*Oct 16 03:35:07.955: dsp_req_sig_state: [0/2/3] packet_len=8 channel_id=131 pac
ket_id=40
*Oct 16 03:35:07.959: htsp_process_event: [0/2/3, FXOLS_ONHOOK, E_DSP_SIG_0100]
#
