تصحيح أخطاء موفر خدمة الهاتف الصوتي

!--- 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

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#

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