音声 : Fax / Modem over IP

音声テレフォニーのサービス プロバイダーによるデバッグ

2003 年 2 月 26 日 - ライター翻訳版
その他のバージョン: PDFpdf | 機械翻訳版 (2013 年 8 月 21 日) | 英語版 (2006 年 2 月 2 日) | フィードバック

目次

概要
はじめに
     表記法
     前提条件
     使用するコンポーネント
ファックス コーデックのダウンロードを示す T.38 の debug vtsp all コマンド
VoFR の debug vtsp all コマンドの動作例
     発信ルータ
     終端ルータ
関連するシスコ サポート コミュニティ ディスカッション
関連情報

概要

この文書では、ファックスを正常に通過させた 1 つのコールに対する debug vtsp all コマンド出力の 2 つのキャプチャを紹介します。最初のキャプチャは T.38 ファックスに対する出力ですが、これは Voice over IP(VoIP)のファックス リレーにも関係しています。この出力結果には、ファックス コーデックがダウンロードされて使用されたかどうかを確認するプロセスを説明するコメントが含まれています。2 番目のデコードは Voice over Frame Relay(VoFR)に対する出力で、コメントはありません。debug vtsp all の出力結果には、T.30 プロトコル側から見た情報の多くは含まれませんが、ルータからの有用な状態情報が提供されます。たとえば次のような情報です。

  • ルータがファックス モードになったという確認

  • ファックス レート、コーデック、および Error Correction Mode(ECM)が使用されたかどうか

  • 終端のゲートウェイが、終端ファックス マシンからのファックス音を受信したことの確認

はじめに

表記法

文書表記の詳細は、「シスコ テクニカル ティップスの表記法」を参照してください。

前提条件

この文書に関する特別な前提条件はありません。

使用するコンポーネント

この文書の情報は主に Cisco IOS ソフトウェア リリース 12.2(5) に基づいていますが、ほとんどの情報は他の Cisco IOS ソフトウェア リリースにも役立ちます。

この文書の情報は、特定のラボ環境にあるデバイスに基づいて作成されています。この文書内で使用されているデバイスはすべて、クリアーな状態(デフォルト)から設定作業を始めています。実稼動中のネットワークで作業をしている場合、実際にコマンドを使用する前に、その潜在的な影響について理解しておく必要があります。

ファックス コーデックのダウンロードを示す T.38 の debug vtsp all コマンド

T.38 のファックス リレーに関しては、コールが接続されてルータがファックス音のデバッグを検出した後に、次のようなメッセージが表示されます。

debug vtsp all コマンド


!--- ccCallConnect メッセージの前と、機能交換フェーズの間では、
!--- fax_rate メッセージが次のように表示されます。

.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})

!--- この場合、ファックス レート 0x02 は音声レートを指します。
!--- これは、このコーデック(g.729)で 8 kbps もしくはそれ以下です。
!--- 下記はその他の可能なファックス レート値の一覧です。

!--- 16 進値     ファックス レート値
!--- 0x2           音声
!--- 0x4           14400
!--- 0x8           9600
!--- 0x10          7200
!--- 0x20          4800
!--- 0x40          2400
!--- 0x80          1200

!--- コールが接続されると、通常は下記のように
!--- ccCallConnect debug メッセージが表示されます。

.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

!--- 2 は T.38 を意味し、1 はシスコの著作権であることを意味します。

.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)

!--- 直前の 3 つのメッセージは、ファックス コーデックが正常に DSP に
!--- ダウンロードされたことを示します。これらの上と下にあるメッセージすべてが
!--- 表示されるとは限りません。重要なのは、ファックス コーデックが
!--- 正しくダウンロードされなかったというメッセージが表示されないということです。

.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

!--- ファックスの伝送中、Cisco IOS ソフトウェアは定期的に DSP 統計情報
!--- にポーリングします。すると、次のメッセージが表示されます。

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

トレースの表示は、Cisco IOS ソフトウェアのバージョンごとに異なる場合がありますが、ファックス コーデックが正しくロードされたかどうか、そしてキーワード「FAX」の後の出力にどのファックス プロトコルが使用されたかは常にわかるようになっています。

ファックス コーデックが正しくロードされなかった場合は、おそらくソフトウェアに障害があります。最新のメンテナンス リリースが使用されているかどうかを確認し、問題が依然として解決しない場合は、Technical Assistance Center(TAC)でケースをオープンしてください。

VoFR debug vtsp all コマンドの動作例

発信側ルータ

debug vtsp all - 発信側ルータ

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

!--- ファックス コーデック: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#

終端ルータ

debug vtsp all - 終端ルータ

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

u!--- sys/if/dsp_cntl_msgs.h * トーン検出の定義
!--- */
!--- #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 トーンが検出された */
!--- #define TONE_TYPE_MODEM    0x0005 /* モデム応答信号が検出された */
!--- #define SILENCE_DETECTED   0x0006 /* 無音検出*/
!--- #define VOICE_DETECTED     0x0007 /* 音声検出 */
!--- #define DUAL_TONE_DETECTED 0x0008 /* 設定可能なデュアル トーンの検出*/
!--- #define DUAL_TONE_DETECTED 0x0009 /* 設定可能なシーケンス トーンの検出 */
!--- #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

関連情報

関連するシスコ サポート コミュニティ ディスカッション

シスコ サポート コミュニティは、どなたでも投稿や回答ができる情報交換スペースです。



Document ID: 40624