Guest

Fax / Modem over IP

Voice Telephony Service Provider Debugging

Cisco - Voice Telephony Service Provider Debugging

Document ID: 40624

Updated: Feb 02, 2006

   Print

Introduction

This document provides two captures of debug vtsp all command output for a single call that successfully passed a fax. The first is for T.38 fax, but it is also relevant for fax relay over Voice over IP (VoIP). This output contains comments to illustrate the process of identifying that the fax codec has been downloaded and used. The second decode is for Voice over Frame Relay (VoFR) and is left uncommented. The debug vtsp all output does not contain a lot of information from a T.30 protocol perspective, but it does provide useful state information from the router such as:

  • Confirmation that the router entered fax mode

  • Fax rate, codec, and whether Error Correction Mode (ECM) was used

  • Confirmation that the terminating Gateway received a fax tone from the terminating fax machine

Before You Begin

Conventions

For more information on document conventions, see the Cisco Technical Tips Conventions.

Prerequisites

There are no specific prerequisites for this document.

Components Used

The information in this document is based primarily on Cisco IOS Software Release 12.2(5), although most of the information should also be useful for other Cisco IOS Software releases.

The information presented in this document was created from devices in a specific lab environment. All of the devices used in this document started with a cleared (default) configuration. If you are working in a live network, ensure that you understand the potential impact of any command before using it.

T.38 debug vtsp all Command Showing Fax Codec Download

For T.38 fax relay, after the call is connected and the router detects the fax tone debug, messages similar to the following will be displayed.

debug vtsp all Command

!--- Prior to the ccCallConnect message and during the capabilities
!--- exchange phase, the fax_rate message may be displayed as follows:

.Jun 4 21:06:43.677: cc_api_caps_ack (dstVdbPtr=0x62F07650, dstCallId=0x18, 
 srcCallId=0x19, caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x0
 codec_bytes=20, signal_type=2})

!--- In this case the fax rate 0x02 refers to the voice rate, which
!--- with this codec (g.729), is less than or equal to 8 kbps.
!--- Listed below are the other possible fax rate values. 

!--- Below is a listing of fax rate values for Cisco IOS Software Release 12.2.x or earlier:
!--- hex value     fax rate value
!--- 0x2           Voice
!--- 0x4           14400
!--- 0x8           9600 
!--- 0x10          7200
!--- 0x20          4800
!--- 0x40          2400
!--- 0x80          1200

!--- Below is a listing of new fax rate values for Cisco IOS Software Release 12.3.x or later:
!--- Note the hex values now map to slightly different speeds than the list above.
!--- /* FAX Capabilities Bits */
!--- #define CC_CAP_FAX_NONE         0x01
!--- #define CC_CAP_FAX_VOICE        0x02
!--- #define CC_CAP_FAX_24           0x04
!--- #define CC_CAP_FAX_48           0x08
!--- #define CC_CAP_FAX_72           0x10
!--- #define CC_CAP_FAX_96           0x20
!--- #define CC_CAP_FAX_120          0x40
!--- #define CC_CAP_FAX_144          0x80
 
!--- Usually, after the call is connected, the ccCallConnect debug 
!--- message is seen as follows:

.Jun 4 21:06:44.841: ccCallConnect (callID=0x18), prog_ind = 0
.Jun 4 21:06:44.841: ssaFlushPeerTagQueue cid(24) peer list: (empty)
.Jun 4 21:06:47.437: vtsp_process_dsp_message: 
 MSG_TX_GET_TX_STAT: rtp_timestamp=0xD4D85071
.Jun 4 21:06:47.437: vtsp:[4/0:15 (25), S_CONNECT, E_DSP_GET_TX]
.Jun 4 21:06:49.049: vtsp_process_dsp_message: 
 MSG_TX_GET_TX_STAT: rtp_timestamp=0xD4D882D1
.Jun 4 21:06:49.049: vtsp:[4/0:15 (25), S_CONNECT, E_DSP_GET_TX]
.Jun 4 21:06:50.129: vtsp_process_dsp_message: 
 MSG_TX_TONE_DETECT: type=0 trigger=1 tone_id=0
.Jun 4 21:06:50.129: vtsp:[4/0:15 (25), S_CONNECT, E_DSP_TONE_DETECT]
.Jun 4 21:06:50.129: vtsp_modem_proto_from_cdb: cap_modem_proto 0
.Jun 4 21:06:50.129: cc_api_call_feature: (vdbPtr=0x63387EDC, 
 callID=0x19,feature_ind.type=1
.Jun 4 21:06:50.129: act_lfax_switch: cap_modem_proto=16, 
 fax_relay_on=1, state=19
.Jun 4 21:06:50.129: vtsp_t38_switchover:2 - data_mode:1

!--- Note that 2 means T.38; 1 means Cisco proprietary.

.Jun 4 21:06:50.129: cc_api_t38_fax_start (dstVdbPtr=0x62F07650, 
 dstCallId=0x18, srcCallId=0x19, caps={codec=0x10000, fax_rate=0x2, 
 vad=0x2, modem=0x0 codec_bytes=20, signal_type=2})
.Jun 4 21:06:50.129: vtsp_timer: 20362254
.Jun 4 21:06:50.129: sess_appl: ev(28=CC_EV_CALL_FEATURE), cid(25), disp(0)
.Jun 4 21:06:50.129: cid(25)st(SSA_CS_ACTIVE)ev(SSA_EV_CALL_FEATURE)
 oldst(SSA_CS_CONFERENCED_ALERT)cfid(8)csize(0)in(0)fDest(0)
.Jun 4 21:06:50.129: -cid2(24)st2(SSA_CS_ACTIVE)oldst2
 (SSA_CS_CONFERENCING_ALERT)
.Jun 4 21:06:50.129: ccCallFeature (callID=0x18, feature.type=1)
.Jun 4 21:06:50.249: vtsp_process_dsp_message: 
 MSG_TX_TONE_DETECT: type=0 trigger=0 tone_id=0
.Jun 4 21:06:50.249: vtsp:[4/0:15 (25), 
 S_LFAX_WAIT_CAPS_ACK, E_DSP_TONE_DETECT]
.Jun 4 21:06:50.249: vtsp_process_event(): prev_state = 0.11 ,
 state = S_LFAX_WAIT_CAPS_ACK, event = E_DSP_TONE_DETECT 
.Jun 4 21:06:50.389: vtsp_process_dsp_message: 
 MSG_TX_TONE_DETECT: type=0 trigger=1 tone_id=0
.Jun 4 21:06:50.389: vtsp:[4/0:15 (25), 
 S_LFAX_WAIT_CAPS_ACK, E_DSP_TONE_DETECT]
.Jun 4 21:06:50.389: vtsp_process_event(): prev_state = 0.11 ,
 state = S_LFAX_WAIT_CAPS_ACK, event = E_DSP_TONE_DETECT 
.Jun 4 21:06:50.489: vtsp_process_dsp_message: 
 MSG_TX_TONE_DETECT: type=0 trigger=0 tone_id=0
.Jun 4 21:06:50.489: vtsp:[4/0:15 (25), 
 S_LFAX_WAIT_CAPS_ACK, E_DSP_TONE_DETECT]
.Jun 4 21:06:50.489: vtsp_process_event(): prev_state = 0.11 ,
 state = S_LFAX_WAIT_CAPS_ACK, 
 event = E_DSP_TONE_DETECT Invalid FSM Input on channel 4/0:15 (25)
.Jun 4 21:06:50.529: cc_api_t38_fax_start (dstVdbPtr=0x63387EDC, 
 dstCallId=0x19, srcCallId=0x18, caps={codec=0x10000, fax_rate=0x2, 
 vad=0x2, modem=0x0 codec_bytes=160, signal_type=2})
.Jun 4 21:06:50.529: vtsp:[4/0:15 (25), 
 S_LFAX_WAIT_CAPS_ACK, E_CC_T38_START]
.Jun 4 21:06:50.529: act_caps_ack_lfax_dnld 
.Jun 4 21:06:50.529: vtsp_timer_stop: 20362294
.Jun 4 21:06:50.529: dsp_idle_mode: [4/0:15 (25)] packet_len=8 
 channel_id=1 packet_id=68
.Jun 4 21:06:50.529: vtsp:[4/0:15 (25), 
 S_LFAX_DOWNLOAD, E_DSPRM_PEND_SUCCESS]
.Jun 4 21:06:50.529: act_lfax_mode 
.Jun 4 21:06:50.529: cc_api_local_codec_dnld_done 
 (dstVdbPtr=0x62F07650, dstCallId=0x18, srcCallId=0x19)

!--- The previous three messages confirm that the fax codec was successfully
!--- downloaded into the DSP. You may not see all the messages above
!--- and below. The important point is that you do not see error 
!--- messages saying that the fax codec was not downloaded correctly.
 
.Jun 4 21:06:50.529: vtsp_timer: 20362294
.Jun 4 21:06:50.929: cc_api_t38_fax_start (dstVdbPtr=0x63387EDC, 
 dstCallId=0x19, srcCallId=0x18, caps={codec=0x10000, fax_rate=0x2, 
 vad=0x2, modem=0x0 codec_bytes=160, signal_type=2})
.Jun 4 21:06:50.929: cc_api_remote_codec_dnld_done (dstVdbPtr=0x63387EDC, 
 dstCallId=0x19, srcCallId=0x18)

.Jun 4 21:06:50.929: vtsp:[4/0:15 (25), S_LFAX_WAIT_FAX, E_CC_T38_START]
.Jun 4 21:06:50.929: vtsp:[4/0:15 (25), S_LFAX_WAIT_FAX, E_CC_T30_CAP_ACK]
.Jun 4 21:06:50.929: act_t38_lfax_mode 
.Jun 4 21:06:50.929: vtsp_timer_stop: 20362334
.Jun 4 21:06:50.929: dsp_idle_mode: [4/0:15 (25)] packet_len=8 
 channel_id=1 packet_id=68
.Jun 4 21:06:50.929: dsp_encap_config: T38
.Jun 4 21:06:50.929: dsp_fax_mode: [4/0:15 (25)] FaxRate 0x2, Codec 0x10000 
 dsp_fax_mode() ECM_DISABLE not set, debug_info requested 
.Jun 4 21:06:50.929: dsp_fax_mode: [] packet_len=28 channel_id=1 packet_id=69 
 max_trans=3 info_size=20, fax_protocol_type=3, ls_data_red=0, hs_data_red=0, 
 tcf_handling=2, fax_relay_cntl=0x2 nsf_country = 0xAD, nsf_mfg = 0x0051

!--- During the fax transmission the Cisco IOS Software polls the DSP 
!--- statistics periodically, and the following message appears:

.Jun 4 21:06:50.929: vtsp:[4/0:15 (25), S_FAX, E_DSP_GET_TX

The trace may look different in different versions of Cisco IOS software, but you should always be able to understand whether the fax codec was loaded correctly and which fax protocol was used by the output following the "FAX" keyword.

If the fax codec was not loaded correctly, there is probably a software problem. Make sure that the latest maintenance release is used and, if there is still a problem, open a case with the Technical Assistance Center (TAC).

Working Example of the VoFR debug vtsp all Command

Originating Router

debug vtsp all - Originating Router
vdtl-2600-2d#
Jun  5 10:10:34.053: vtsp_tsp_call_setup_ind (sdb=0x81FD48DC, tdm_info=0x0, 
 tsp_info=0x81FF74DC, calling_number= calling_oct3 = 0x0, 
 called_number= called_oct3 = 0x81, oct3a=0x0): peer_tag=0
Jun  5 10:10:34.053: : ev.clg.clir is 0 ev.clg.clid_transparent 
 is 0 ev.clg.null_orig_clg is 1 ev.clg.calling_translated is false


Jun  5 10:10:34.057: vtsp_do_call_setup_ind
Jun  5 10:10:34.057: vtsp_allocate_cdb,cdb 0x821D9344
Jun  5 10:10:34.057: vtsp_do_call_setup_ind: Call ID=65589, guid=821D9874
Jun  5 10:10:34.057: vtsp_do_call_setup_ind: type=0, under_spec=-2119571356, 
 name=f<0HNhf< 1=@, id0=1, id1=1, id2=-32567, calling=, 
 called= subscriber=RegularLine
Jun  5 10:10:34.057: vtsp_do_normal_call_setup_ind
Jun  5 10:10:34.057: vtsp_insert_cdb,cdb 0x821D9344
Jun  5 10:10:34.057: vtsp_open_voice_and_set_params 
Jun  5 10:10:34.057: dsp_close_voice_channel: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=75
Jun  5 10:10:34.057: dsp_open_voice_channel_20: [1/1:0:27] packet_len=16 
 channel_id=2 packet_id=74 alaw_ulaw_select=0 
 associated_signaling_channel=129 time_slot=1 serial_port=0
Jun  5 10:10:34.057: vtsp_modem_proto_from_cdb: cap_modem_proto 1073741824
Jun  5 10:10:34.057: vtsp_modem_proto_from_cdb: cap_modem_proto 1073741824
Jun  5 10:10:34.057: dsp_encap_config: [1/1:0:27] packet_len=30 
 channel_id=2 packet_id=92 TransportProtocol 2 t_ssrc=0x0 r_ssrc=0x0 
 t_vpxcc=0x0 r_vpxcc=0x0 sid_support=1, tse_payload=65535, seq_num=0x0, 
 redundancy=0
Jun  5 10:10:34.061: dsp_set_playout_delay
Jun  5 10:10:34.061: dsp_set_playout: [1/1:0:27] packet_len=18 
 channel_id=2 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
 dsp_set_playout_delay_config
Jun  5 10:10:34.061: dsp_set_playout_config
Jun  5 10:10:34.061:  mode 0, init 60, min 40, max 200 playout default

Jun  5 10:10:34.061: dsp_set_playout_config:mode 0, init 60, min 40, max 200 
Jun  5 10:10:34.061: dsp_set_playout_config: [1/1:0:27] packet_len=18 
 channel_id=2 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
Jun  5 10:10:34.061: dsp_echo_canceler_control: echo_cancel: 1
Jun  5 10:10:34.061: dsp_echo_canceler_control: [1/1:0:27] echo_cancel 1, 
 disable_hpf 0, flags=0x0
Jun  5 10:10:34.061: dsp_echo_canceler_control: [1/1:0:27] packet_len=10 
 channel_id=2 packet_id=66 flags=0x0
Jun  5 10:10:34.061: sgf: FXx/E&M:  msg->message.set_codec_gains.out_gain=0
Jun  5 10:10:34.061: dsp_set_gains: [1/1:0:27] packet_len=12 
 channel_id=2 packet_id=91 in_gain=0 out_gain=0
Jun  5 10:10:34.061: dsp_vad_enable: [1/1:0:27] enable: packet_len=12 
 channel_id=2 packet_id=78 thresh=-38
Jun  5 10:10:34.061: vtsp_report_digit_control: 
 enable=1: digit reporting enabled
Jun  5 10:10:34.061: : vtsp_get_digit_timeouts
Jun  5 10:10:34.065: vtsp:[1/1:0:27, S_SETUP_INDICATED, E_CC_SETUP_ACK]
Jun  5 10:10:34.065: act_setup_ind_ack 
Jun  5 10:10:34.065: vtsp_modem_proto_from_cdb: cap_modem_proto 0
Jun  5 10:10:34.065: vtsp_modem_proto_from_cdb: cap_modem_proto 0
Jun  5 10:10:34.065: dsp_encap_config: [1/1:0:27] packet_len=30 
 channel_id=2 packet_id=92 TransportProtocol 2 t_ssrc=0x0 r_ssrc=0x0 
 t_vpxcc=0x0 r_vpxcc=0x0 sid_support=1, tse_payload=65535, seq_num=0x0, 
 redundancy=0
Jun  5 10:10:34.065: dsp_voice_mode: [1/1:0:27] cdb 821D9344, 
 cdb->codec_params.modem 2, inband_detect flags 0x21
Jun  5 10:10:34.065: map_dtmf_relay_type--digit relay mode: 2
Jun  5 10:10:34.065: dsp_voice_mode: [1/1:0:27] packet_len=24 
 channel_id=2 packet_id=73 coding_type=1 voice_field_size=80 VAD_flag=0 
 echo_length=64 comfort_noise=1 inband_detect=33 digit_relay_mode=2 
 AGC_flag=0act_setup_ind_ack: modem_mode = 0, fax_relay_on = 1
Jun  5 10:10:34.065: act_setup_ind_ack(): 
 dsp_dtmf_mode()dsp_dtmf_mode(VTSP_TONE_DTMF_MODE)

Jun  5 10:10:34.065: dsp_dtmf_mode: [1/1:0:27] packet_len=10 
 channel_id=2 packet_id=65 dtmf_or_mf=0
Jun  5 10:10:34.065: vtsp_timer: 43525550
Jun  5 10:10:34.065: vtsp:[1/1:0:27, S_DIGIT_COLLECT, E_CC_GEN_TONE]
Jun  5 10:10:34.065: act_gen_tone
Jun  5 10:10:34.065: dsp_cp_tone_off: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=71
Jun  5 10:10:34.065: dsp_cp_tone_on: [1/1:0:27] packet_len=38 
 channel_id=2 packet_id=72 tone_id=4 n_freq=2 freq_of_first=350 
 freq_of_second=440 amp_of_first=5514 amp_of_second=5514 direction=1 
 on_time_first=65535 off_time_first=0 on_time_second=0 off_time_second=0
Jun  5 10:10:34.065: vtsp:[1/1:0:27, S_DIGIT_COLLECT, E_CC_GEN_TONE]
Jun  5 10:10:34.069: act_gen_tone
Jun  5 10:10:34.069: dsp_cp_tone_off: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=71
Jun  5 10:10:34.069: dsp_cp_tone_on: [1/1:0:27] packet_len=38 
 channel_id=2 packet_id=72 tone_id=4 n_freq=2 freq_of_first=350 
 freq_of_second=440 amp_of_first=5514 amp_of_second=5514 direction=1 
 on_time_first=65535 off_time_first=0 on_time_second=0 off_time_second=0
Jun  5 10:10:35.544: vtsp_process_dsp_message: 
 MSG_TX_DTMF_DIGIT_BEGIN: digit=4, rtp_timestamp=0xD455BA67

Jun  5 10:10:35.544: vtsp:[1/1:0:27, S_DIGIT_COLLECT,
 E_DSP_DTMF_DIGIT_BEGIN]
Jun  5 10:10:35.544: act_report_digit_begin 
Jun  5 10:10:35.612: vtsp_process_dsp_message: 
 MSG_TX_DTMF_DIGIT_OFF: digit=4, duration=120
Jun  5 10:10:35.616: vtsp:[1/1:0:27, S_DIGIT_COLLECT, E_DSP_DTMF_DIGIT]
Jun  5 10:10:35.616: act_report_digit_end 
Jun  5 10:10:35.616: vtsp_timer_stop: 43525705
Jun  5 10:10:35.616: dsp_cp_tone_off: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=71
Jun  5 10:10:35.616: vtsp_timer: 43525705
Jun  5 10:10:35.732: vtsp_process_dsp_message: 
 MSG_TX_DTMF_DIGIT_BEGIN: digit=0, rtp_timestamp=0xD455BA67
Jun  5 10:10:35.732: vtsp:[1/1:0:27, S_DIGIT_COLLECT,
 E_DSP_DTMF_DIGIT_BEGIN]
Jun  5 10:10:35.732: act_report_digit_begin 
Jun  5 10:10:35.792: vtsp_process_dsp_message: 
 MSG_TX_DTMF_DIGIT_OFF: digit=0, duration=110
Jun  5 10:10:35.792: vtsp:[1/1:0:27, S_DIGIT_COLLECT, E_DSP_DTMF_DIGIT]
Jun  5 10:10:35.792: act_report_digit_end 
Jun  5 10:10:35.792: vtsp_timer_stop: 43525722
Jun  5 10:10:35.796: dsp_cp_tone_off: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=71
Jun  5 10:10:35.796: vtsp_timer: 43525723
Jun  5 10:10:35.933: vtsp_process_dsp_message: 
 MSG_TX_DTMF_DIGIT_BEGIN: digit=0, rtp_timestamp=0xD455BA67
Jun  5 10:10:35.933: vtsp:[1/1:0:27, S_DIGIT_COLLECT,
 E_DSP_DTMF_DIGIT_BEGIN]
Jun  5 10:10:35.933: act_report_digit_begin 
Jun  5 10:10:36.005: vtsp_process_dsp_message: 
 MSG_TX_DTMF_DIGIT_OFF: digit=0, duration=120
Jun  5 10:10:36.005: vtsp:[1/1:0:27, S_DIGIT_COLLECT,
 E_DSP_DTMF_DIGIT]
Jun  5 10:10:36.005: act_report_digit_end 
Jun  5 10:10:36.005: vtsp_timer_stop: 43525744
Jun  5 10:10:36.005: dsp_cp_tone_off: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=71
Jun  5 10:10:36.005: vtsp_timer: 43525744
Jun  5 10:10:36.133: vtsp_process_dsp_message: 
 MSG_TX_DTMF_DIGIT_BEGIN: digit=1, rtp_timestamp=0xD455BA67
Jun  5 10:10:36.133: vtsp:[1/1:0:27, S_DIGIT_COLLECT,
 E_DSP_DTMF_DIGIT_BEGIN]
Jun  5 10:10:36.133: act_report_digit_begin 
Jun  5 10:10:36.193: vtsp_process_dsp_message: 
 MSG_TX_DTMF_DIGIT_OFF: digit=1, duration=110
Jun  5 10:10:36.193: vtsp:[1/1:0:27, S_DIGIT_COLLECT,
 E_DSP_DTMF_DIGIT]
Jun  5 10:10:36.193: act_report_digit_end 
Jun  5 10:10:36.193: vtsp_timer_stop: 43525762
Jun  5 10:10:36.193: dsp_cp_tone_off: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=71
Jun  5 10:10:36.193: vtsp_timer: 43525762
Jun  5 10:10:36.197: vtsp_report_digit_control: 
 enable=0: digit reporting disabled
Jun  5 10:10:36.197: : vtsp_get_digit_timeouts
Jun  5 10:10:36.197: vtsp_save_dialpeer_tag: tag = 4001
Jun  5 10:10:36.197: vtsp:[1/1:0:27, S_DIGIT_COLLECT, E_CC_PROCEEDING]
Jun  5 10:10:36.197: act_dcollect_proc 
Jun  5 10:10:36.205: vtsp_get_dialpeer_tag: tag = 4001
Jun  5 10:10:36.890: vtsp_get_dialpeer_tag: tag = 4001
Jun  5 10:10:36.890: vtsp:[1/1:0:27, S_PROCEEDING, E_CC_PROGRESS]
Jun  5 10:10:36.890: act_progress 
Jun  5 10:10:36.890: vtsp_timer_stop: 43525832
Jun  5 10:10:36.890: vtsp:[1/1:0:27, S_PROCEEDING, E_CC_BRIDGE]
Jun  5 10:10:36.890: act_bridge 
Jun  5 10:10:36.890: vtsp:[1/1:0:27, S_PROCEEDING, E_CC_CAPS_IND]
Jun  5 10:10:36.890: act_caps_ind 
Jun  5 10:10:36.890: act_caps_ind: passthrough: cap_modem_proto 0, 
 cap_modem_codec 0, cap_modem_redundancy 0, payload 0
Jun  5 10:10:36.890: act_caps_ind:CC_CAP_ENCAP_FRF11 Encap 2, Vad 2, 
 Codec 0x4, CodecBytes 30, FaxRate 2, FaxBytes 30, FaxNsf 0xAD0051 
 Sub-channel 8, Bitmask 0x0 SignalType 2, SeqNumStart 0x0
Jun  5 10:10:36.890: act_caps_ind: [ mode:0,init:60, min:40, max:200]
Jun  5 10:10:36.890: vtsp:[1/1:0:27, S_PROCEEDING, E_CC_CAPS_ACK]
Jun  5 10:10:36.890: act_caps_ack 
Jun  5 10:10:36.894: act_caps_ack: passthrough: cap_modem_proto 0, 
 cap_modem_codec 0, cap_modem_redundancy 0, payload 0
Jun  5 10:10:36.894: dsp_idle_mode: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=68
Jun  5 10:10:36.894: act_caps_ack: codec = 16, ret = 2
Jun  5 10:10:36.894: vtsp_timer: 43525833
Jun  5 10:10:36.894: vtsp:[1/1:0:27, S_NULL, E_DSPRM_PEND_SUCCESS]
Jun  5 10:10:36.894: act_pend_codec_success 
Jun  5 10:10:36.894: vtsp_timer_stop: 43525833
Jun  5 10:10:36.894: vtsp_open_voice_and_set_params 
Jun  5 10:10:36.894: dsp_close_voice_channel: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=75
Jun  5 10:10:36.894: dsp_open_voice_channel_20: [1/1:0:27] packet_len=16 
 channel_id=2 packet_id=74 alaw_ulaw_select=0 
 associated_signaling_channel=129 time_slot=1 serial_port=0
Jun  5 10:10:36.894: dsp_encap_config: [1/1:0:27] packet_len=18 
 channel_id=2 packet_id=92 TransportProtocol 3 SID_support=0 
 sequence_number=0 rotate_flag=0 header_bytes 0x80
Jun  5 10:10:36.894: dsp_set_playout_delay
Jun  5 10:10:36.894: dsp_set_playout: [1/1:0:27] packet_len=18 
 channel_id=2 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
 dsp_set_playout_delay_config
Jun  5 10:10:36.894: dsp_set_playout_config
Jun  5 10:10:36.894:  mode 0, init 60, min 40, max 200 playout default
Jun  5 10:10:36.894: dsp_set_playout_config:mode 0, init 60, min 40, max 200 
Jun  5 10:10:36.894: dsp_set_playout_config: [1/1:0:27] packet_len=18 
 channel_id=2 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
Jun  5 10:10:36.894: dsp_echo_canceler_control: echo_cancel: 1
Jun  5 10:10:36.894: dsp_echo_canceler_control: [1/1:0:27] echo_cancel 1, 
 disable_hpf 0, flags=0x0
Jun  5 10:10:36.898: dsp_echo_canceler_control: [1/1:0:27] packet_len=10 
 channel_id=2 packet_id=66 flags=0x0
Jun  5 10:10:36.898: sgf: FXx/E&M:  msg->message.set_codec_gains.out_gain=0
Jun  5 10:10:36.898: dsp_set_gains: [1/1:0:27] packet_len=12 
 channel_id=2 packet_id=91 in_gain=0 out_gain=0
Jun  5 10:10:36.898: dsp_vad_enable: [1/1:0:27] enable: packet_len=12 
 channel_id=2 packet_id=78 thresh=-38
Jun  5 10:10:36.898: dsp_voice_mode: [1/1:0:27] cdb 821D9344, 
 cdb->codec_params.modem 0, inband_detect flags 0x1
Jun  5 10:10:36.898: map_dtmf_relay_type--digit relay mode: 2
Jun  5 10:10:36.898: dsp_voice_mode: [1/1:0:27] packet_len=24 
 channel_id=2 packet_id=73 coding_type=19 voice_field_size=30 
 VAD_flag=250 echo_length=64 comfort_noise=1 inband_detect=1 
 digit_relay_mode=2 AGC_flag=0
Jun  5 10:10:43.024: vtsp_get_dialpeer_tag: tag = 4001
Jun  5 10:10:43.024: vtsp:[1/1:0:27, S_PROCEEDING, E_CC_CONNECT]
Jun  5 10:10:43.024: act_connect
Jun  5 10:10:43.024: vtsp_ring_noan_timer_stop: 43526446

Jun  5 10:10:48.710: vtsp:[1/1:0:27, S_CONNECT, E_PH_CODEC_FAX]
Jun  5 10:10:48.710: act_rfax_switch 
Jun  5 10:10:48.710: dsp_idle_mode: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=68
Jun  5 10:10:48.710: vtsp_timer_stop: 43527014
Jun  5 10:10:48.910: vtsp:[1/1:0:27, S_RFAX_DOWNLOAD, E_DSPRM_PEND_SUCCESS]
Jun  5 10:10:48.910: act_rfax_mode 
Jun  5 10:10:48.910: vtsp:[1/1:0:27, S_RFAX_WAIT_ACK, E_PH_CODEC_ACK]
Jun  5 10:10:48.910: act_wait_ack_ack 
Jun  5 10:10:48.910: vtsp_timer_stop: 43527034
Jun  5 10:10:48.914: dsp_fax_mode: [1/1:0:27] FaxRate 0x2, Codec 0x4  
 dsp_fax_mode() ECM_DISABLE not set, debug_info not requested 
Jun  5 10:10:48.914: dsp_fax_mode: [1/1:0:27] packet_len=28 
 channel_id=2 packet_id=69 max_trans=3 info_size=30, fax_protocol_type=2, 
 hs_data_len=0, ls_data_red=0, hs_data_red=0, tcf_handling=0, 
fax_relay_cntl=0x0 nsf_country = 0xAD, nsf_mfg = 0x0051

!--- fax codecs: enum {
!--- DSP_TELOGY_FAX = 1,
!--- DSP_FRF11_FAX,
!--- DSP_T38_FAX

vdtl-2600-2d#
vdtl-2600-2d#
vdtl-2600-2d#
Jun  5 10:11:21.516: vtsp_process_dsp_message: MSG_TX_FAX_CLEARDOWN:
Jun  5 10:11:21.516: vtsp:[1/1:0:27, S_FAX, E_DSP_FAX_CLEARDOWN]
Jun  5 10:11:21.516: act_fax_to_voice 
Jun  5 10:11:21.516: vtsp_timer_stop: 43530295
Jun  5 10:11:21.520: dsp_idle_mode: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=68
Jun  5 10:11:21.520: dsp_close_voice_channel: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=75act_fax_to_voice codec= 16 
Jun  5 10:11:21.520: vtsp_timer: 43530295
Jun  5 10:11:21.520: vtsp:[1/1:0:27, S_FAX_TERM, E_DSPRM_PEND_SUCCESS]
Jun  5 10:11:21.520: vtsp_timer_stop: 43530295
Jun  5 10:11:21.520: act_fax_complete 
Jun  5 10:11:21.520: vtsp_open_voice_and_set_params 
Jun  5 10:11:21.520: dsp_close_voice_channel: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=75
Jun  5 10:11:21.520: dsp_open_voice_channel_20: [1/1:0:27] packet_len=16 
 channel_id=2 packet_id=74 alaw_ulaw_select=0 
 associated_signaling_channel=129 time_slot=1 serial_port=0
Jun  5 10:11:21.520: dsp_encap_config: [1/1:0:27] packet_len=18 
 channel_id=2 packet_id=92 TransportProtocol 3 SID_support=0 
 sequence_number=0 rotate_flag=0 header_bytes 0x80
Jun  5 10:11:21.520: dsp_set_playout_delay
Jun  5 10:11:21.520: dsp_set_playout: [1/1:0:27] packet_len=18 
 channel_id=2 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
 dsp_set_playout_delay_config
Jun  5 10:11:21.520: dsp_set_playout_config
Jun  5 10:11:21.520:  mode 0, init 60, min 40, max 200 playout default
Jun  5 10:11:21.520: dsp_set_playout_config:mode 0, init 60, min 40, max 200 
Jun  5 10:11:21.520: dsp_set_playout_config: [1/1:0:27] packet_len=18 
 channel_id=2 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
Jun  5 10:11:21.520: dsp_echo_canceler_control: echo_cancel: 1
Jun  5 10:11:21.520: dsp_echo_canceler_control: [1/1:0:27] echo_cancel 1, 
 disable_hpf 0, flags=0x0
Jun  5 10:11:21.525: dsp_echo_canceler_control: [1/1:0:27] packet_len=10 
 channel_id=2 packet_id=66 flags=0x0
Jun  5 10:11:21.525: sgf: FXx/E&M:  msg->message.set_codec_gains.out_gain=0
Jun  5 10:11:21.525: dsp_set_gains: [1/1:0:27] packet_len=12 
 channel_id=2 packet_id=91 in_gain=0 out_gain=0
Jun  5 10:11:21.525: dsp_vad_enable: [1/1:0:27] enable: packet_len=12 
 channel_id=2 packet_id=78 thresh=-38
Jun  5 10:11:21.525: dsp_voice_mode: [1/1:0:27] cdb 821D9344, 
 cdb->codec_params.modem 0, inband_detect flags 0x1
Jun  5 10:11:21.525: map_dtmf_relay_type--digit relay mode: 2
Jun  5 10:11:21.525: dsp_voice_mode: [1/1:0:27] packet_len=24 
 channel_id=2 packet_id=73 coding_type=19 voice_field_size=30 
 VAD_flag=250 echo_length=64 comfort_noise=1 inband_detect=1 
 digit_relay_mode=2 AGC_flag=0
Jun  5 10:11:24.033: vtsp:[1/1:0:27, S_CONNECT, E_TSP_DISCONNECT_IND]
Jun  5 10:11:24.033: act_generate_disc 
Jun  5 10:11:24.033: vtsp_ring_noan_timer_stop: 43530546
Jun  5 10:11:24.033: vtsp_timer_stop: 43530546
Jun  5 10:11:24.033: vtsp_pcm_tone_detect_timer_stop: 43530546
Jun  5 10:11:24.033: vtsp_pcm_switchover_timer_stop: 43530546
Jun  5 10:11:24.033: vtsp:[1/1:0:27, S_CONNECT, E_CC_BRIDGE_DROP]
Jun  5 10:11:24.033: act_bdrop 
Jun  5 10:11:24.033: vtsp:[1/1:0:27, S_CONNECT, E_CC_DISCONNECT]
Jun  5 10:11:24.033: act_disconnect 
Jun  5 10:11:24.033: vtsp_ring_noan_timer_stop: 43530546
Jun  5 10:11:24.033: vtsp_pcm_tone_detect_timer_stop: 43530546
Jun  5 10:11:24.033: vtsp_pcm_switchover_timer_stop: 43530546
Jun  5 10:11:24.033: vtsp_cot_timer_stop: 43530546
Jun  5 10:11:24.033: vtsp_timer_stop: 43530546
Jun  5 10:11:24.033: vtsp_timer: 43530546
Jun  5 10:11:24.037: vtsp:[1/1:0:27, S_WAIT_STATS, E_DSP_GET_ERROR]
Jun  5 10:11:24.037: act_get_error 
Jun  5 10:11:24.037: 1/1:0:27: rx_dropped=0 tx_dropped=0 rx_control=51 
 tx_control=14 tx_control_dropped=0 dsp_mode_channel_1=0
 dsp_mode_channel_2=2  c[0]=68 c[1]=3 c[2]=10 c[3]=69 c[4]=68 c[5]=75
 c[6]=75 c[7]=74 c[8]=92 c[9]=76 c[10]=76 c[11]=66 c[12]=91 c[13]=78
 c[14]=73 c[15]=6
Jun  5 10:11:24.037: dsp_get_levels: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=89
Jun  5 10:11:24.041: vtsp:[1/1:0:27, S_WAIT_STATS, E_DSP_GET_LEVELS]
Jun  5 10:11:24.041: act_get_levels 
Jun  5 10:11:24.045: vtsp_process_dsp_message: 
 MSG_TX_GET_TX_STAT: rtp_timestamp=0xFD908919
Jun  5 10:11:24.045: vtsp:[1/1:0:27, S_WAIT_STATS, E_DSP_GET_TX]
Jun  5 10:11:24.049: act_stats_complete 
Jun  5 10:11:24.049: vtsp_timer_stop: 43530548
Jun  5 10:11:24.049: vtsp_ring_noan_timer_stop: 43530548
Jun  5 10:11:24.049: dsp_idle_mode: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=68
Jun  5 10:11:24.049: vtsp_timer: 43530548
Jun  5 10:11:24.850: vtsp:[1/1:0:27, S_WAIT_RELEASE, E_TSP_CALL_FEATURE_IND]
Jun  5 10:11:24.850: act_call_feature_ind 
Jun  5 10:11:24.850: vtsp:[1/1:0:27, S_WAIT_RELEASE, E_TSP_DISCONNECT_CONF]
Jun  5 10:11:24.850: act_wrelease_release 
Jun  5 10:11:24.850: vtsp_timer_stop: 43530628
Jun  5 10:11:24.854: dsp_cp_tone_off: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=71
Jun  5 10:11:24.854: dsp_idle_mode: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=68
Jun  5 10:11:24.854: dsp_close_voice_channel: [1/1:0:27] packet_len=8 
 channel_id=2 packet_id=75vtsp_do_call_historyvtsp_do_call_history
 CoderRate 20
Jun  5 10:11:24.854: vtsp:[1/1:0:27, S_CLOSE_DSPRM, E_DSPRM_CLOSE_COMPLETE]
Jun  5 10:11:24.854: act_terminate 
Jun  5 10:11:24.854: vtsp_free_cdb,cdb 0x821D9344
vdtl-2600-2d#

Terminating Router

debug vtsp all - Terminating Router
vdtl-7200-2c#
Jun  5 10:10:36.200: vtsp_do_call_setup_req
Jun  5 10:10:36.200: vtsp_allocate_cdb,cdb 0x620F01FC
Jun  5 10:10:36.200: vtsp_insert_cdb,cdb 0x620F01FC
Jun  5 10:10:36.200: vtsp_do_call_setup_req calling oct3 0x0, called oct3 0x0
Jun  5 10:10:36.200: vtsp_do_call_setup_req: Call ID=55, guid=62F8AC6C
Jun  5 10:10:36.200: digit_strip:1, pcn:4001, poa:4001
Jun  5 10:10:36.200: pcn:, poa:
Jun  5 10:10:36.200: Final pcn:, poa:, dial_string:
Jun  5 10:10:36.200: calling oct3 0x0, called oct3 0x0
Jun  5 10:10:36.200: vtsp:[2/1:0 (39), S_SETUP_REQUEST, E_TSP_PROCEEDING]
Jun  5 10:10:36.200: act_setup_pend_proceeding 
Jun  5 10:10:36.200: vtsp_open_voice_and_set_params 
Jun  5 10:10:36.200: dsp_close_voice_channel: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=75
Jun  5 10:10:36.200: dsp_open_voice_channel_20: [2/1:0 (39)] packet_len=16 
 channel_id=1 packet_id=74 alaw_ulaw_select=0 
 associated_signaling_channel=128 time_slot=0 serial_port=0
Jun  5 10:10:36.200: vtsp_modem_proto_from_cdb: cap_modem_proto 1073741824
Jun  5 10:10:36.204: vtsp_modem_proto_from_cdb: cap_modem_proto 1073741824
Jun  5 10:10:36.204: dsp_encap_config: [2/1:0 (39)] packet_len=30 
 channel_id=1 packet_id=92 TransportProtocol 2 t_ssrc=0x0 r_ssrc=0x0 
 t_vpxcc=0x0 r_vpxcc=0x0 sid_support=1, tse_payload=65535, seq_num=0x0, 
 redundancy=0
Jun  5 10:10:36.204: dsp_set_playout_delay
Jun  5 10:10:36.204: dsp_set_playout: [2/1:0 (39)] packet_len=18 
 channel_id=1 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
 dsp_set_playout_delay_config
Jun  5 10:10:36.204: dsp_set_playout_config
Jun  5 10:10:36.204:  mode 0, init 60, min 40, max 200 playout default 


Jun  5 10:10:36.204: dsp_set_playout_config:mode 0, init 60, min 40, max 200 
Jun  5 10:10:36.204: dsp_set_playout_config: [2/1:0 (39)] packet_len=18 
 channel_id=1 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
Jun  5 10:10:36.204: dsp_echo_canceler_control: echo_cancel: 1
Jun  5 10:10:36.204: dsp_echo_canceler_control: [2/1:0 (39)] echo_cancel 1, 
 disable_hpf 0, flags=0x0
Jun  5 10:10:36.204: dsp_echo_canceler_control: [2/1:0 (39)] packet_len=10 
 channel_id=1 packet_id=66 flags=0x0
Jun  5 10:10:36.204: sgf: FXx/E&M:  msg->message.set_codec_gains.out_gain=0
Jun  5 10:10:36.204: dsp_set_gains: [2/1:0 (39)] packet_len=12 
 channel_id=1 packet_id=91 in_gain=0 out_gain=0
Jun  5 10:10:36.204: dsp_vad_enable: [2/1:0 (39)] enable: packet_len=12 
 channel_id=1 packet_id=78 thresh=-38
Jun  5 10:10:36.888: vtsp:[2/1:0 (39), S_SETUP_REQ_PROC, E_TSP_PROGRESS]
Jun  5 10:10:36.888: act_setup_pend_progress 
Jun  5 10:10:36.888: vtsp:[2/1:0 (39), S_SETUP_REQ_PROC, E_CC_BRIDGE]
Jun  5 10:10:36.888: act_bridge 
Jun  5 10:10:36.888: vtsp:[2/1:0 (39), S_SETUP_REQ_PROC, E_CC_CAPS_IND]
Jun  5 10:10:36.888: act_caps_ind 
Jun  5 10:10:36.888: act_caps_ind: passthrough: cap_modem_proto 0, 
 cap_modem_codec 0, cap_modem_redundancy 0, payload 0
Jun  5 10:10:36.888: act_caps_ind:CC_CAP_ENCAP_FRF11 Encap 2, Vad 2, 
 Codec 0x4, CodecBytes 30, 
              FaxRate 2, FaxBytes 30, FaxNsf 0xAD0051
              Sub-channel 8, Bitmask 0x0 
              SignalType 2, SeqNumStart 0x0
Jun  5 10:10:36.888: act_caps_ind: [ mode:0,init:60, min:40, max:200]
Jun  5 10:10:36.888: vtsp:[2/1:0 (39), S_SETUP_REQ_PROC, E_CC_CAPS_ACK]
Jun  5 10:10:36.888: act_caps_ack 
Jun  5 10:10:36.888: act_caps_ack: passthrough: cap_modem_proto 0, 
 cap_modem_codec 0, cap_modem_redundancy 0, payload 0
Jun  5 10:10:36.888: dsp_idle_mode: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=68
Jun  5 10:10:36.888: act_caps_ack: codec = 16, ret = 2

Jun  5 10:10:36.888: vtsp_timer: 94714681
Jun  5 10:10:36.888: vtsp:[2/1:0 (39), S_NULL, E_DSPRM_PEND_SUCCESS]
Jun  5 10:10:36.888: act_pend_codec_success 
Jun  5 10:10:36.888: vtsp_timer_stop: 94714681
Jun  5 10:10:36.888: vtsp_open_voice_and_set_params 
Jun  5 10:10:36.888: dsp_close_voice_channel: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=75
Jun  5 10:10:36.888: dsp_open_voice_channel_20: [2/1:0 (39)] packet_len=16 
 channel_id=1 packet_id=74 alaw_ulaw_select=0 
 associated_signaling_channel=128 time_slot=0 serial_port=0
Jun  5 10:10:36.888: dsp_encap_config: [2/1:0 (39)] packet_len=18 
 channel_id=1 packet_id=92 TransportProtocol 3 SID_support=0 
 sequence_number=0 rotate_flag=0 header_bytes 0x80
Jun  5 10:10:36.888: dsp_set_playout_delay
Jun  5 10:10:36.888: dsp_set_playout: [2/1:0 (39)] packet_len=18 
 channel_id=1 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
 dsp_set_playout_delay_config
Jun  5 10:10:36.888: dsp_set_playout_config
Jun  5 10:10:36.888:  mode 0, init 60, min 40, max 200 playout default

Jun  5 10:10:36.888: dsp_set_playout_config:mode 0, init 60, min 40, max 200 
Jun  5 10:10:36.888: dsp_set_playout_config: [2/1:0 (39)] packet_len=18 
 channel_id=1 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
Jun  5 10:10:36.888: dsp_echo_canceler_control: echo_cancel: 1
Jun  5 10:10:36.888: dsp_echo_canceler_control: [2/1:0 (39)] echo_cancel 1, 
 disable_hpf 0, flags=0x0
Jun  5 10:10:36.888: dsp_echo_canceler_control: [2/1:0 (39)] packet_len=10 
 channel_id=1 packet_id=66 flags=0x0
Jun  5 10:10:36.888: sgf: FXx/E&M:  msg->message.set_codec_gains.out_gain=0
Jun  5 10:10:36.888: dsp_set_gains: [2/1:0 (39)] packet_len=12 channel_id=1 
 packet_id=91 in_gain=0 out_gain=0
Jun  5 10:10:36.888: dsp_vad_enable: [2/1:0 (39)] enable: packet_len=12 
 channel_id=1 packet_id=78 thresh=-38
Jun  5 10:10:36.888: dsp_voice_mode: [2/1:0 (39)] cdb 620F01FC, 
 cdb->codec_params.modem 0, inband_detect flags 0x1
Jun  5 10:10:36.888: map_dtmf_relay_type--digit relay mode: 2
Jun  5 10:10:36.888: dsp_voice_mode: [2/1:0 (39)] packet_len=24 
 channel_id=1 packet_id=73 coding_type=19 voice_field_size=30 VAD_flag=250 
 echo_length=64 comfort_noise=1
 inband_detect=1 digit_relay_mode=2 AGC_flag=0
Jun  5 10:10:43.024: vtsp:[2/1:0 (39), S_SETUP_REQ_PROC, E_TSP_CONNECT]
Jun  5 10:10:43.024: act_setup_pend_connect 
Jun  5 10:10:43.024: vtsp_ring_noan_timer_stop: 94715294
Jun  5 10:10:43.024: dsp_cp_tone_off: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=71

Jun  5 10:10:48.492: vtsp_process_dsp_message: 
 MSG_TX_TONE_DETECT: type=0 trigger=1 tone_id=0
Jun  5 10:10:48.492: vtsp:[2/1:0 (39), S_CONNECT, E_DSP_TONE_DETECT]
Jun  5 10:10:48.492: vtsp_modem_proto_from_cdb: cap_modem_proto 0
Jun  5 10:10:48.492: act_lfax_switch: cap_modem_proto=16, 
 fax_relay_on=1, state=19

!--- sys/if/dsp_cntl_msgs.h * Definitions for the tone detect
!--- */
!--- #define TONE_TYPE_V_21     0x0000
!--- #define TONE_TYPE_CED      0x0001 /* CED 2100Hz */
!--- #define TONE_TYPE_CNG      0x0002 /* CNG 1100Hz */
!--- #define TONE_SDT_DISC      0x0003
!--- #define TONE_TYPE_COT      0x0004 /* COT tone detected */
!--- #define TONE_TYPE_MODEM    0x0005 /* Modem answer signal detected */
!--- #define SILENCE_DETECTED   0x0006 /* Silence detection */
!--- #define VOICE_DETECTED     0x0007 /* Voice detection */
!--- #define DUAL_TONE_DETECTED 0x0008 /* configurable dual tone detection */
!--- #define SEQ_TONE_DETECTED  0x0009 /* configurable seq tone detection */
!--- #define TONE_DETECT_ON     0x0001
!--- #define TONE_DETECT_OFF    ~TONE_DETECT_

Jun  5 10:10:48.492: vtsp_timer: 94715841
Jun  5 10:10:48.492: vtsp:[2/1:0 (39), S_LFAX_WAIT_ACK, E_PH_CODEC_ACK]
Jun  5 10:10:48.492: act_lfax_download 
Jun  5 10:10:48.492: vtsp_timer_stop: 94715841
Jun  5 10:10:48.492: dsp_idle_mode: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=68
Jun  5 10:10:48.492: vtsp:[2/1:0 (39), S_LFAX_DOWNLOAD, E_DSPRM_PEND_SUCCESS]
Jun  5 10:10:48.492: act_lfax_mode 
Jun  5 10:10:48.492: vtsp:[2/1:0 (39), S_LFAX_WAIT_FAX, E_PH_CODEC_FAX]
Jun  5 10:10:48.492: act_wait_fax_fax ph_set_codec_ack
Jun  5 10:10:48.492: dsp_fax_mode: [2/1:0 (39)] FaxRate 0x2, Codec 0x4  
 dsp_fax_mode() ECM_DISABLE not set, debug_info not requested 
Jun  5 10:10:48.492: dsp_fax_mode: [2/1:0 (39)] packet_len=28 
 channel_id=1 packet_id=69 max_trans=3 info_size=30, fax_protocol_type=2, 
 hs_data_len=0, ls_data_red=0, hs_data_red=0, tcf_handling=0, 
 fax_relay_cntl=0x0 nsf_country = 0xAD, nsf_mfg = 0x0051
vdtl-7200-2c#
vdtl-7200-2c#
vdtl-7200-2c#
Jun  5 10:11:21.736: vtsp_process_dsp_message: MSG_TX_FAX_CLEARDOWN:
Jun  5 10:11:21.740: vtsp:[2/1:0 (39), S_FAX, E_DSP_FAX_CLEARDOWN]
Jun  5 10:11:21.740: act_fax_to_voice 
Jun  5 10:11:21.740: vtsp_timer_stop: 94719166
Jun  5 10:11:21.740: dsp_idle_mode: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=68
Jun  5 10:11:21.740: dsp_close_voice_channel: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=75act_fax_to_voice codec= 16 

Jun  5 10:11:21.740: vtsp_timer: 94719166
Jun  5 10:11:21.740: vtsp:[2/1:0 (39), S_FAX_TERM, E_DSPRM_PEND_SUCCESS]
Jun  5 10:11:21.740: vtsp_timer_stop: 94719166
Jun  5 10:11:21.740: act_fax_complete 
Jun  5 10:11:21.740: vtsp_open_voice_and_set_params 
Jun  5 10:11:21.740: dsp_close_voice_channel: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=75
Jun  5 10:11:21.740: dsp_open_voice_channel_20: [2/1:0 (39)] packet_len=16 
 channel_id=1 packet_id=74 alaw_ulaw_select=0
 associated_signaling_channel=128 time_slot=0 serial_port=0
Jun  5 10:11:21.740: dsp_encap_config: [2/1:0 (39)] packet_len=18 
 channel_id=1 packet_id=92 TransportProtocol 3 SID_support=0
 sequence_number=0 rotate_flag=0 header_bytes 0x80
Jun  5 10:11:21.740: dsp_set_playout_delay
Jun  5 10:11:21.740: dsp_set_playout: [2/1:0 (39)] packet_len=18 
 channel_id=1 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
 dsp_set_playout_delay_config
Jun  5 10:11:21.740: dsp_set_playout_config
Jun  5 10:11:21.740:  mode 0, init 60, min 40, max 200 playout default 

Jun  5 10:11:21.740: dsp_set_playout_config:mode 0, init 60, min 40, max 200 
Jun  5 10:11:21.740: dsp_set_playout_config: [2/1:0 (39)] packet_len=18 
 channel_id=1 packet_id=76 mode=1 initial=60 min=40 max=200 fax_nom=300 
Jun  5 10:11:21.740: dsp_echo_canceler_control: echo_cancel: 1
Jun  5 10:11:21.740: dsp_echo_canceler_control: [2/1:0 (39)] echo_cancel 1, 
 disable_hpf 0, flags=0x0
Jun  5 10:11:21.740: dsp_echo_canceler_control: [2/1:0 (39)] packet_len=10 
 channel_id=1 packet_id=66 flags=0x0
Jun  5 10:11:21.740: sgf: FXx/E&M: msg->message.set_codec_gains.out_gain=0
Jun  5 10:11:21.740: dsp_set_gains: [2/1:0 (39)] packet_len=12 
 channel_id=1 packet_id=91 in_gain=0 out_gain=0
Jun  5 10:11:21.740: dsp_vad_enable: [2/1:0 (39)] enable: packet_len=12 
 channel_id=1 packet_id=78 thresh=-38
Jun  5 10:11:21.740: dsp_voice_mode: [2/1:0 (39)] cdb 620F01FC, 
 cdb->codec_params.modem 0, inband_detect flags 0x1
Jun  5 10:11:21.740: map_dtmf_relay_type--digit relay mode: 2
Jun  5 10:11:21.740: dsp_voice_mode: [2/1:0 (39)] packet_len=24 channel_id=1 
 packet_id=73 coding_type=19 voice_field_size=30 VAD_flag=250 echo_length=64 
 comfort_noise=1 inband_detect=1 digit_relay_mode=2 AGC_flag=0
Jun  5 10:11:24.036: vtsp:[2/1:0 (39), S_CONNECT, E_CC_BRIDGE_DROP]
Jun  5 10:11:24.036: act_bdrop 
Jun  5 10:11:24.036: vtsp:[2/1:0 (39), S_CONNECT, E_CC_DISCONNECT]
Jun  5 10:11:24.036: act_disconnect 
Jun  5 10:11:24.036: vtsp_ring_noan_timer_stop: 94719396
Jun  5 10:11:24.036: vtsp_pcm_tone_detect_timer_stop: 94719396
Jun  5 10:11:24.036: vtsp_pcm_switchover_timer_stop: 94719396
Jun  5 10:11:24.036: vtsp_cot_timer_stop: 94719396
Jun  5 10:11:24.036: vtsp_timer_stop: 94719396
Jun  5 10:11:24.036: vtsp_timer: 94719396
Jun  5 10:11:24.036: vtsp:[2/1:0 (39), S_WAIT_STATS, E_DSP_GET_ERROR]
Jun  5 10:11:24.036: act_get_error 
Jun  5 10:11:24.036: 2/1:0 (39): rx_dropped=1 tx_dropped=0 rx_control=39 
 tx_control=5 tx_control_dropped=0 dsp_mode_channel_1=2 dsp_mode_channel_2=0 
 c[0]=0 c[1]=0 c[2]=73 c[3]=71 c[4]=68 c[5]=69 c[6]=68 c[7]=75 c[8]=75 
 c[9]=74 c[10]=92 c[11]=76 c[12]=76 c[13]=66 c[14]=91 c[15]=78
Jun  5 10:11:24.036: dsp_get_levels: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=89
Jun  5 10:11:24.036: vtsp:[2/1:0 (39), S_WAIT_STATS, E_DSP_GET_LEVELS]
Jun  5 10:11:24.036: act_get_levels 
Jun  5 10:11:24.040: vtsp_process_dsp_message: 
 MSG_TX_GET_TX_STAT: rtp_timestamp=0x94D00079
Jun  5 10:11:24.040: vtsp:[2/1:0 (39), S_WAIT_STATS, E_DSP_GET_TX]
Jun  5 10:11:24.040: act_stats_complete 
Jun  5 10:11:24.040: vtsp_timer_stop: 94719396
Jun  5 10:11:24.040: vtsp_ring_noan_timer_stop: 94719396
Jun  5 10:11:24.040: dsp_idle_mode: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=68
Jun  5 10:11:24.040: vtsp_timer: 94719396
Jun  5 10:11:24.840: vtsp:[2/1:0 (39), S_WAIT_RELEASE, E_TSP_CALL_FEATURE_IND]
Jun  5 10:11:24.840: act_call_feature_ind 
Jun  5 10:11:24.840: vtsp:[2/1:0 (39), S_WAIT_RELEASE, E_TSP_DISCONNECT_CONF]
Jun  5 10:11:24.840: act_wrelease_release 
Jun  5 10:11:24.840: vtsp_timer_stop: 94719476
Jun  5 10:11:24.840: dsp_cp_tone_off: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=71
Jun  5 10:11:24.840: dsp_idle_mode: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=68
Jun  5 10:11:24.840: dsp_close_voice_channel: [2/1:0 (39)] packet_len=8 
 channel_id=1 packet_id=75vtsp_do_call_historyvtsp_do_call_history 
 CoderRate 20
Jun  5 10:11:24.840: vtsp:[2/1:0 (39), S_CLOSE_DSPRM, E_DSPRM_CLOSE_COMPLETE]
Jun  5 10:11:24.840: act_terminate 
Jun  5 10:11:24.840: vtsp_free_cdb,cdb 0x620F01FC

Related Information

Updated: Feb 02, 2006
Document ID: 40624