发信人: v10198(梦泪)
整理人: firphoenix(2001-11-05 10:06:55), 站内信件
|
Ccisco#show debug CAS:
Channel Associated Signaling debugging is on
CSM Voice:
Voice Call Switching Module debugging is on
Voice Telephony session debugging is on
Voice Telephony dsp debugging is on
Voice Telephony error debugging is on
eefje#
eefje#
eefje#
Jan 6 10:41:28.677: from NEAT(0): (0/0): Rx SEIZURE (ABCD=0001)
Jan 6 10:41:28.717: VDEV_ALLOCATE: failed to allocate a device
Jan 6 10:41:28.717: VDEV_ALLOCATE: 1/28 is allocated
Jan 6 10:41:28.721: csm_vtsp_init_tdm (voice_vdev=0x620BF874)
Jan 6 10:41:28.721: csm_vtsp_init_tdm: dsprm_tdm_allocate: tdm slot 2, dspm 1, dsp 5,
dsp_channel 1
Jan 6 10:41:28.721: csm_vtsp_init_tdm: dsprm_tdm_allocate: tdm stream 5, channel 3,
bank 1, bp_channel 4, BP_stream 255
Jan 6 10:41:28.721: CSM_RX_CAS_EVENT_FROM_NEAT:(cid0018): EVENT_CALL_DIAL_IN at slot 2
and port 16
Jan 6 10:41:28.721: CSM_PROC_IDLE: CSM_EVENT_START_DIGIT_COLLECT at slot 2,
port 16
Jan 6 10:41:28.721: csm_vtsp_start_digit_collect (voice_vdev=0x620BF874)
Jan 6 10:41:28.721: Enter csm_connect_pri_vdev function
Jan 6 10:41:28.721: csm_connect_pri_vdev:tdm_allocate_BP_ts()call. BP TS allocated
at BP_stream0, BP_Ch28,vdev_common 0x6 20BF8E4
Jan 6 10:41:28.721: to NEAT:(cid0018) EVENT_CHANNEL_LOCK for slot0 ctlr0 chan0
Jan 6 10:41:28.721: vtsp_do_call_setup_ind
Jan 6 10:41:28.721: vtsp_do_call_setup_ind: Call ID=65681, guid=61FAF610
Jan 6 10:41:28.721: vtsp_do_call_setup_ind: type=0, under_spec=0, name=, id0=0, id1=0,
id2=0, calling=, called=
Jan 6 10:41:28.721: vtsp_do_call_setup_ind: redirect DN = reason =
0vtsp_open_voice_and_set_params
Jan 6 10:41:28.721: dsp_close_voice_channel: [0:1:0] packet_len=8 channel_id
=8529 packet_id=75
Jan 6 10:41:28.721: dsp_open_voice_channel_20: [0:1:0] packet_Len=16 channel_id
=8529 packet_id=74 alaw_ulaw_select=1 associated_signaling_channel=0 time_slot=0
serial_port=0
Jan 6 10:41:28.721: dsp_encap_config_20: [0:1:0] packet_Len=24 channel_id=8529
packet_id=92 TransportProtocol 2 t_ssrc=0x0 r_ssrc=0x0 t_vpxcc=0x0 r_vpxcc=0x0
Jan 6 10:41:28.721: dsp_set_playout: [0:1:0] packet_Len=18 channel_id=8529
packet_id=76 mode=1 initial=60 min=4 max=200 fax_nom=300
Jan 6 10:41:28.721: dsp_echo_canceller_control: [0:1:0] packet_Len=10 channel_id=8529
packet_id=66 flags=0x0
Jan 6 10:41:28.721: dsp_set_gains: [0:1:0] packet_Len=12 channel_id=8529 packet_id=91
in_gain=0 out_gain=0
Jan 6 10:41:28.721: dsp_vad_enable: [0:1:0] packet_Len=10 channel_id=8529 packet_id=78
thresh=-38
Jan 6 10:41:28.721: dsp_voice_mode: [0:1:0] packet_Len=24 channel_id=8529 packet_id=73
coding_type=1 voice_field_size=80 V AD_flag=0 echo_length=64 comfort_noise=1
inband_detect=1 digit_relay=2 AGC_flag=0vtsp_do_r2_start_digit(): dsp_dtmf_mode()
dsp_dtmf_mode(VTSP_TONE_R2_MF_FORWARD_MODE)
Jan 6 10:41:28.725: dsp_dtmf_mode: [0:1:0] packet_Len=10 channel_id=8529
packet_id=65 dtmf_or_mf=1vtsp_do_r2_start_digit():fsm_push(vtsp_r2_state_table)
Jan 6 10:41:28.725: csm_vtsp_call_setup_resp (vdev_info=0x620BF874, vtsp_cdb=0x621C5F3C)
Jan 6 10:41:28.725: csm_vtsp_call_setup_resp:vdev_common BP TS allocatedat BP_stream0,
BP_Ch28
Jan 6 10:41:28.725: csm_vtsp_call_setup_resp:dst_tdm_chnl call. BP TS allocatedat
stream 5, chan 3,BP_stream 255, BP_ch 4
Jan 6 10:41:28.725: csm_vtsp_call_setup_resp:DST_tdm_chnl call. BP TS allocatedat
stream 5, chan 3,BP_stream 0, BP_ch 28
Jan 6 10:41:28.725: CSM_PROC_IC1_COLLECT_ADDR_INFO: CSM_EVENT_MODEM_OFFHOOK
(DNIS=, ANI=) at slot 2, port 16
Jan 6 10:41:28.725: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE: R2_IN_IDLE R2
Got Event R2_START
Jan 6 10:41:28.821: CSM_RX_CAS_EVENT_FROM_NEAT:(0018):EVENT_START_RX_TONE at slot 2
and port 16
Jan 6 10:41:28.821: from NEAT(0): (0/0): TX SEIZURE_ACK (ABCD=1101)
! --
! -- Digit 5 is sent : Forward Signal Group I-5
! --
Jan 6 10:41:29.233: vtsp_process_dsp_message: MSG_TX_DTMF_DIGIT_BEGIN: digit=5,
rtp_timestamp=0x0CA95D43 dc_digit_up
Jan 6 10:41:29.233: csm_vtsp_digit_ready_up (vtsp_cdb=0x621C5F3C) received digit (5)
Jan 6 10:41:29.233: CSM voice (2/16): Rcvd Digit detected(5)
Jan 6 10:41:29.233: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE: R2_IN_COLLECT_DNIS R2
Got Event 5
Jan 6 10:41:29.365: vtsp_process_dsp_message: MSG_TX_DTMF_DIGIT_OFF: digit=5,
duration=8321dc_digit
Jan 6 10:41:29.365: csm_vtsp_digit_ready (vtsp_cdb=0x621C5F3C) received digit (5)
Jan 6 10:41:29.365: CSM voice (2/16): Rcvd Digit detected(5)
Jan 6 10:41:29.365: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE:R2_IN_COLLECT_DNIS R2
Got Event R2_TONE_OFF
! ---
! --- Digit 6 is sent : Forward Signal Group I-6
! ---
Jan 6 10:41:29.593: vtsp_process_dsp_message: MSG_TX_DTMF_DIGIT_BEGIN: digit=6,
rtp_timestamp=0x0CA95D43 dc_digit_up
Jan 6 10:41:29.593: csm_vtsp_digit_ready_up (vtsp_cdb=0x621C5F3C) received digit (6)
Jan 6 10:41:29.593: CSM voice (2/16): Rcvd Digit detected(6)
Jan 6 10:41:29.593: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE: R2_IN_COLLECT_DNIS R2
Got Event 6
Jan 6 10:41:29.725: vtsp_process_dsp_message: MSG_TX_DTMF_DIGIT_OFF: digit=6,
duration=8321dc_digit
Jan 6 10:41:29.725: csm_vtsp_digit_ready (vtsp_cdb=0x621C5F3C) received digit (6)
Jan 6 10:41:29.725: CSM voice (2/16): Rcvd Digit detected(6)
Jan 6 10:41:29.725: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE: R2_IN_COLLECT_DNIS R2
Got Event R2_TONE_OFF
! ---
! --- Digit 7 is sent : Forward Signal Group I-7
! ---
Jan 6 10:41:29.953: vtsp_process_dsp_message: MSG_TX_DTMF_DIGIT_BEGIN: digit=7, rtp_timestamp=0x0CA95D43 dc_digit_up
Jan 6 10:41:29.953: csm_vtsp_digit_ready_up (vtsp_cdb=0x621C5F3C)
received digit (7)
Jan 6 10:41:29.953: CSM voice (2/16): Rcvd Digit detected(7)
Jan 6 10:41:29.953: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE:R2_IN_COLLECT_DNIS R2
Got Event 7
Jan 6 10:41:30.085: vtsp_process_dsp_message: MSG_TX_DTMF_DIGIT_OFF: digit=7, duration=8321dc_digit
Jan 6 10:41:30.085: csm_vtsp_digit_ready (vtsp_cdb=0x621C5F3C)received digit (7)
Jan 6 10:41:30.085: CSM voice (2/16): Rcvd Digit detected(7)
Jan 6 10:41:30.085: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE: R2_IN_COLLECT_DNIS R2
Got Event R2_TONE_OFF
! ---
! --- Timeout : 3 seconds (default timer - AS5300 assumes DNIS is finished)
! ---
Jan 6 10:41:32.953: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE: R2_IN_COLLECT_DNIS R2
Got Event R2_TONE_TIMER
! ---
! --- We send digit 6 : Backward Signal Group B-6 (subscriber's line free- charge)
! ---
Jan 6 10:41:32.953: vtsp_r2_generate_digits: vdev_common=0x620BF8E4, string=567dc_dial()
vtsp_dial_nopush dsp_dtmf_dialing(): dial_string = 6#
! ---
Jan 6 10:41:32.953: dsp_dtmf_dialing: [0:1:0] packet_Len=36 channel_id=8529 packet_id=90 string=6# digits=2, time_on=150, time_off=30
Jan 6 10:41:32.953:& digit=e, components=2, freq_of_first=900, freq_of_second=780, amp_of_first=8192, amp_of_second=8192
Jan 6 10:41:32.953: digit=o, components=2, freq_of_first=0, freq_of_second=0, amp_of_first=1, amp_of_second=1
Jan 6 10:41:33.313: vtsp_process_dsp_message: MSG_TX_DIALING_DONE dc_dialing_done()Jan 6 10:41:33.313: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE:R2_IN_ANSWER_PULSE R2
Got Event R2_DIGITS_GENR2_ALERTINGJan 6 10:41:34.313: R2 Incoming Voice(2/16): DSX (E1 0:0): STATE: R2_IN_ANSWER_PULSE R2
Got Event R2_TONE_TIMER
Jan 6 10:41:34.313: R2_IN_IDLE:2 r2_in_connect called
Jan 6 10:41:34.313: CSM_PROC_IC1_COLLECT_ADDR_INFO:
CSM_EVENT_ADDR_INFO_COLLECTED (DNIS=567, ANI=) at slot 2, port 16Jan 6 10:41:34.313: vtsp_tsp_call_accept_check (sdb=0x61B8F0E0, calling_number=
called_number=567): peer_tag=0
Jan 6 10:41:34.313: VDEV_ALLOCATE: failed to allocate a device
Jan 6 10:41:34.313: VDEV_ALLOCATE_ALMOST_READY: failed to allocate a non-idle modem
Jan 6 10:41:34.313: VDEV_ALLOCATE: failed to allocate a device
Jan 6 10:41:34.313: VDEV_ALLOCATE_ALMOST_READY: failed to allocate a non-idle modem
Jan 6 10:41:34.313: VDEV_ALLOCATE: failed to allocate a device
Jan 6 10:41:34.313: VDEV_ALLOCATE_ALMOST_READY: failed to allocate a non-idle modem
Jan 6 10:41:34.313: CSM_PROC_IC3_WAIT_FOR_RES_RESP: CSM_EVENT_RESOURCE_OK at slot 2,
port 16
Jan 6 10:41:34.313: vtsp_ic_switch : (voice_vdev= 0x620BF874)
Jan 6 10:41:34.313: vtsp_tsp_call_switch_ind (cdb=0x621C5F3C, tsp_info=0x620BF874,
calling_number= called_number=567 redir ect_number=): peer_tag=123dc_switch: fsm_pop()Jan 6 10:41:34.313: vtsp_do_call_setup_ind
Jan 6 10:41:34.313: vtsp_do_call_setup_ind: Call ID=65683, guid=61FAF610
Jan 6 10:41:34.313: vtsp_do_call_setup_ind: type=0, under_spec=0,
name=ab^Lx, id0=1, id1=0, id2=0, calling=123, called=567Jan 6 10:41:34.317: dsp_cp_tone_off: [] packet_Len=8 channel_id=8529 packet_id=71
Jan 6 10:41:34.317: dsp_idle_mode: [] packet_Len=8 channel_id=8529 packet_id=68
Jan 6 10:41:34.317: dsp_close_voice_channel: [] packet_Len=8 channel_id=8529 packet_id=75
Jan 6 10:41:34.317: vtsp_timer_stop: 67475758
Jan 6 10:41:34.317: csm_vtsp_call_setup_resp (vdev_info=0x620BF874, vtsp_cdb=0x621C5F3C)
Jan 6 10:41:34.317: csm_vtsp_call_setup_resp:vdev_common BP TS allocatedat BP_stream0,
BP_Ch28
Jan 6 10:41:34.317: csm_vtsp_call_setup_resp:DST_tdm_chnl call. BP TS allocatedat
stream 5, chan 3,BP_stream 0, BP_ch 28
Jan 6 10:41:34.317: csm_vtsp_call_setup_resp:DST_tdm_chnl call. BP TS allocatedat
stream 5, chan 3,BP_stream 0, BP_ch 28vt sp_open_voice_and_set_params
Jan 6 10:41:34.317: dsp_close_voice_channel: [0:1 (54)] packet_Len=8 channel_id=8529
packet_id=75
Jan 6 10:41:34.317: dsp_open_voice_channel_20: [0:1 (54)] packet_Len=16 channel_id=8529
packet_id=74 alaw_ulaw_select=1 associated_signaling_channel=0 time_slot=0 serial_port=0
Jan 6 10:41:34.317: dsp_encap_config_20: [0:1 (54)] packet_Len=24 channel_id=8529
packet_id=92 TransportProtocol 2 t_ssrc=0x0 r_ssrc=0x0 t_vpxcc=0x0 r_vpxcc=0x0
Jan 6 10:41:34.317: dsp_set_playout: [0:1 (54)] packet_Len=18 channel_id=8529
packet_id=76 mode=1 initial=60 min=4 max=200 fax_nom=300
Jan 6 10:41:34.317: dsp_echo_canceller_control: [0:1 (54)] packet_Len=10 channel_id=8529
packet_id=66 flags=0x0
Jan 6 10:41:34.317: dsp_set_gains: [0:1 (54)] packet_Len=12 channel_id=8529 packet_id=91
in_gain=0 out_gain=0
Jan 6 10:41:34.317: dsp_vad_enable: [0:1 (54)] packet_Len=10 channel_id=8529 packet_id=78
thresh=-38act_proceeding
Jan 6 10:41:34.321: csm_vtsp_call_proceeding:DST_tdm_chnl call. BP TS allocatedstream 5,
chan 3,BP_stream 0, BP_ch 28act_alert
Jan 6 10:41:34.345: vtsp_ring_noan_timer_start: 67475761
Jan 6 10:41:34.345: csm_vtsp_call_alert (vtsp_cdb=0x621C5F3C)act_bridge act_caps_ind
Jan 6 10:41:34.589: act_caps_ind:Encap 1, Vad 2, Codec 0x4, CodecBytes 20,
FaxRate 2, FaxBytes 20 SignalType 0
DtmfRelay 1, Modem 1act_caps_ack
Jan 6 10:41:34.589: dsp_idle_mode: [0:1 (54)] packet_Len=8 channel_id=8529 packet_id=68
Jan 6 10:41:34.589: act_caps_ack: codec = 15, ret = 1Jan 6 10:41:34.589: dsp_cp_tone_off: [0:1 (54)] packet_Len=8 channel_id=8529
packet_id=71
Jan 6 10:41:34.589: dsp_idle_mode: [0:1 (54)] packet_Len=8 channel_id=8529 packet_id=68
Jan 6 10:41:34.589: dsp_encap_config_20: [0:1 (54)] packet_Len=24 channel_id=8529
packet_id=92 TransportProtocol 2 t_ssrc=0x0 r_ssrc=0x0 t_vpxcc=0x0 r_vpxcc=0x0
Jan 6 10:41:34.589: dsp_voice_mode: [0:1 (54)] packet_Len=24 channel_id=8529
packet_id=73 coding_type=20 voice_field_size=20 VAD_flag=1 echo_length=64
comfort_noise=1 inband_detect=1 digit_relay=2 AGC_flag=0act_alert_connect
Jan 6 10:41:36.857: vtsp_ring_noan_timer_stop: 67476012
Jan 6 10:41:36.857: dsp_cp_tone_off: [0:1 (54)] packet_Len=8 channel_id=8529
packet_id=71
Jan 6 10:41:36.857: csm_vtsp_call_connect (vtsp_cdb=0x621C5F3C, voice_vdev=0x620BF874)
Jan 6 10:41:36.857: CSM_IC5_WAIT_FOR_SWITCH_OVER: CSM_EVENT_MODEM_OFFHOOK
at slot 2, port 16
Jan 6 10:41:36.917: CSM_RX_CAS_EVENT_FROM_NEAT:(0018): EVENT_CHANNEL_CONNECTED
at slot 2 and port 16
Jan 6 10:41:36.917: CSM_PROC_IC6_WAIT_FOR_CONNECT: CSM_EVENT_DSX0_CONNECTED
at slot 2, port 16
Jan 6 10:41:36.921: from NEAT(0): (0/0): TX ANSWERED(ABCD=0101)
eefje# |
|