Dropping call before beep on USR Sportster 33.6 voice

"Brian J. McGovern" (mcgovern@spoon.beta.com)
Wed, 11 Feb 1998 03:43:35 +0100
Messages sorted by:[ date ][ thread ][ subject ][ author ]
Ok. Here is the updated log at log level 9, as per your request, Gert. It 
looks like (towards the end) that the voice modem isn't flushing an AT
command, and vgetty is barfing on the AT in the queue.

In any event, let me know what you think.
	-Brian

--
02/10 18:14:53 yd2 vgetty: experimental test release 0.7.4 / 21Jan98
02/10 18:14:53 yd2 mgetty: experimental test release 1.1.12-Jan27
02/10 18:14:53 yd2 reading generic configuration from config file /usr/local/etc/mgetty+sendfax/voice.conf
02/10 18:14:53 yd2 reading /usr/local/etc/mgetty+sendfax/voice.conf...
02/10 18:14:53 yd2 conf lib: read: 'part generic'
02/10 18:14:53 yd2 section: part generic, **found**
02/10 18:14:53 yd2 conf lib: read: 'voice_log_level 6'
02/10 18:14:53 yd2 conf lib: read: 'voice_dir /var/spool/voice'
02/10 18:14:53 yd2 conf lib: read: 'phone_owner root'
02/10 18:14:53 yd2 conf lib: read: 'phone_group dialer'
02/10 18:14:53 yd2 conf lib: read: 'phone_mode 0660'
02/10 18:14:53 yd2 conf lib: read: 'message_flag_file .flag'
02/10 18:14:53 yd2 conf lib: read: 'receive_dir incoming'
02/10 18:14:53 yd2 conf lib: read: 'message_dir messages'
02/10 18:14:53 yd2 conf lib: read: 'message_list Index'
02/10 18:14:53 yd2 conf lib: read: 'backup_message standard.rmd'
02/10 18:14:53 yd2 conf lib: read: 'port_speed 57600'
02/10 18:14:53 yd2 conf lib: read: 'voice_shell /usr/local/bin/bash'
02/10 18:14:53 yd2 conf lib: read: 'port_timeout 10'
02/10 18:14:53 yd2 conf lib: read: 'dial_timeout 90'
02/10 18:14:53 yd2 conf lib: read: 'command_delay 100'
02/10 18:14:53 yd2 conf lib: read: 'dtmf_len 30'
02/10 18:14:53 yd2 conf lib: read: 'dtmf_threshold 40'
02/10 18:14:53 yd2 conf lib: read: 'dtmf_wait 7'
02/10 18:14:53 yd2 conf lib: read: 'ignore_fax_dle false'
02/10 18:14:53 yd2 conf lib: read: 'raw_data false'
02/10 18:14:53 yd2 conf lib: read: 'rec_compression 0'
02/10 18:14:53 yd2 conf lib: read: 'rec_speed 0'
02/10 18:14:53 yd2 conf lib: read: 'rec_silence_len 70'
02/10 18:14:53 yd2 conf lib: read: 'rec_silence_threshold 40'
02/10 18:14:53 yd2 conf lib: read: 'rec_remove_silence true'
02/10 18:14:53 yd2 conf lib: read: 'rec_max_len 300'
02/10 18:14:53 yd2 conf lib: read: 'rec_min_len 0'
02/10 18:14:53 yd2 conf lib: read: 'do_hard_flow true'
02/10 18:14:53 yd2 conf lib: read: 'beep_frequency 933'
02/10 18:14:53 yd2 conf lib: read: 'beep_length 1500'
02/10 18:14:53 yd2 conf lib: read: 'max_tries 3'
02/10 18:14:53 yd2 conf lib: read: 'retry_delay 5'
02/10 18:14:53 yd2 conf lib: read: 'watchdog_timeout 60'
02/10 18:14:53 yd2 conf lib: read: 'receive_gain -1'
02/10 18:14:53 yd2 conf lib: read: 'transmit_gain -1'
02/10 18:14:53 yd2 conf lib: read: 'enable_command_echo false'
02/10 18:14:53 yd2 conf lib: read: 'poll_interval 10'
02/10 18:14:53 yd2 conf lib: read: 'program vgetty'
02/10 18:14:53 yd2 found CT_KEYWORD program vgetty
02/10 18:14:53 yd2 conf lib: read: 'rings 3'
02/10 18:14:53 yd2 conf lib: read: 'answer_mode voice:fax:data'
02/10 18:14:53 yd2 conf lib: read: 'force_autodetect false'
02/10 18:14:53 yd2 conf lib: read: 'toll_saver_rings 0'
02/10 18:14:53 yd2 conf lib: read: 'rec_always_keep true'
02/10 18:14:53 yd2 conf lib: read: 'button_program '
02/10 18:14:53 yd2 conf lib: read: 'call_program '
02/10 18:14:53 yd2 conf lib: read: 'dtmf_program dtmf.sh'
02/10 18:14:53 yd2 conf lib: read: 'message_program '
02/10 18:14:53 yd2 conf lib: read: 'do_message_light false'
02/10 18:14:53 yd2 conf lib: read: 'program vm'
02/10 18:14:53 yd2 conf lib: read: 'voice_devices ttyd2'
02/10 18:14:53 yd2 conf lib: read: 'dialout_timeout 90'
02/10 18:14:53 yd2 conf lib: read: 'program pvf'
02/10 18:14:53 yd2 conf lib: read: 'port ttyd2'
02/10 18:14:53 yd2 conf lib: read: 'port ttyS0'
02/10 18:14:53 yd2 conf lib: read: 'port ttyS5'
02/10 18:14:53 yd2 conf lib: read: 'ring_type virtual'
02/10 18:14:53 yd2 conf lib: read: 'answer_mode fax:data'
02/10 18:14:53 yd2 conf lib: read: 'ring_type ring'
02/10 18:14:53 yd2 conf lib: read: 'ring_type ring1'
02/10 18:14:53 yd2 key: 'part', type=6, flags=4, data=(ignored)
02/10 18:14:53 yd2 key: 'program', type=6, flags=4, data=(ignored)
02/10 18:14:53 yd2 key: 'port', type=6, flags=4, data=(ignored)
02/10 18:14:53 yd2 key: 'ring_type', type=6, flags=4, data=(ignored)
02/10 18:14:53 yd2 key: 'voice_log_level', type=0, flags=3, data=6
02/10 18:14:53 yd2 key: 'voice_devices', type=1, flags=0, data=(empty)
02/10 18:14:53 yd2 key: 'port_speed', type=0, flags=3, data=57600
02/10 18:14:53 yd2 key: 'port_timeout', type=0, flags=3, data=10
02/10 18:14:53 yd2 key: 'dtmf_len', type=0, flags=3, data=30
02/10 18:14:53 yd2 key: 'dtmf_threshold', type=0, flags=3, data=40
02/10 18:14:53 yd2 key: 'dtmf_wait', type=0, flags=3, data=7
02/10 18:14:53 yd2 key: 'rec_compression', type=0, flags=3, data=0
02/10 18:14:53 yd2 key: 'rec_speed', type=0, flags=3, data=0
02/10 18:14:53 yd2 key: 'rec_silence_len', type=0, flags=3, data=70
02/10 18:14:53 yd2 key: 'rec_silence_threshold', type=0, flags=3, data=40
02/10 18:14:53 yd2 key: 'rec_remove_silence', type=3, flags=3, data=TRUE
02/10 18:14:53 yd2 key: 'rec_max_len', type=0, flags=3, data=300
02/10 18:14:53 yd2 key: 'receive_gain', type=0, flags=3, data=-1
02/10 18:14:53 yd2 key: 'transmit_gain', type=0, flags=3, data=-1
02/10 18:14:53 yd2 key: 'rings', type=1, flags=1, data=3
02/10 18:14:53 yd2 key: 'answer_mode', type=1, flags=1, data=voice:fax:data
02/10 18:14:53 yd2 key: 'toll_saver_rings', type=0, flags=1, data=0
02/10 18:14:53 yd2 key: 'rec_always_keep', type=3, flags=1, data=TRUE
02/10 18:14:53 yd2 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
02/10 18:14:53 yd2 key: 'phone_owner', type=1, flags=3, data=root
02/10 18:14:53 yd2 key: 'phone_group', type=1, flags=3, data=dialer
02/10 18:14:53 yd2 key: 'phone_mode', type=0, flags=3, data=432
02/10 18:14:53 yd2 key: 'message_flag_file', type=1, flags=3, data=.flag
02/10 18:14:53 yd2 key: 'receive_dir', type=1, flags=3, data=incoming
02/10 18:14:53 yd2 key: 'message_dir', type=1, flags=3, data=messages
02/10 18:14:53 yd2 key: 'message_list', type=1, flags=3, data=Index
02/10 18:14:53 yd2 key: 'backup_message', type=1, flags=3, data=standard.rmd
02/10 18:14:53 yd2 key: 'dialout_timeout', type=0, flags=1, data=90
02/10 18:14:53 yd2 key: 'beep_frequency', type=0, flags=3, data=933
02/10 18:14:53 yd2 key: 'beep_length', type=0, flags=3, data=1500
02/10 18:14:53 yd2 key: 'raw_data', type=3, flags=3, data=FALSE
02/10 18:14:53 yd2 key: 'max_tries', type=0, flags=3, data=3
02/10 18:14:53 yd2 key: 'retry_delay', type=0, flags=3, data=5
02/10 18:14:53 yd2 key: 'voice_shell', type=1, flags=3, data=/usr/local/bin/bash
02/10 18:14:53 yd2 key: 'button_program', type=1, flags=1, data=
02/10 18:14:53 yd2 key: 'call_program', type=1, flags=1, data=
02/10 18:14:53 yd2 key: 'dtmf_program', type=1, flags=1, data=dtmf.sh
02/10 18:14:53 yd2 key: 'message_program', type=1, flags=1, data=
02/10 18:14:53 yd2 key: 'do_message_light', type=3, flags=1, data=FALSE
02/10 18:14:53 yd2 key: 'do_hard_flow', type=3, flags=3, data=TRUE
02/10 18:14:53 yd2 key: 'force_autodetect', type=3, flags=1, data=FALSE
02/10 18:14:53 yd2 key: 'watchdog_timeout', type=0, flags=3, data=60
02/10 18:14:53 yd2 key: 'rec_min_len', type=0, flags=3, data=0
02/10 18:14:53 yd2 key: 'command_delay', type=0, flags=3, data=100
02/10 18:14:53 yd2 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
02/10 18:14:53 yd2 key: 'dial_timeout', type=0, flags=3, data=90
02/10 18:14:53 yd2 key: 'enable_command_echo', type=3, flags=3, data=FALSE
02/10 18:14:53 yd2 key: 'poll_interval', type=0, flags=3, data=10
02/10 18:14:53 yd2 reading program vgetty configuration from config file /usr/local/etc/mgetty+sendfax/voice.conf
02/10 18:14:53 yd2 reading port ttyd2 configuration from config file /usr/local/etc/mgetty+sendfax/voice.conf
02/10 18:14:53 yd2 reading /usr/local/etc/mgetty+sendfax/voice.conf...
02/10 18:14:53 yd2 conf lib: read: 'part generic'
02/10 18:14:53 yd2 found CT_KEYWORD part generic
02/10 18:14:53 yd2 conf lib: read: 'voice_log_level 6'
02/10 18:14:53 yd2 conf lib: read: 'voice_dir /var/spool/voice'
02/10 18:14:53 yd2 conf lib: read: 'phone_owner root'
02/10 18:14:53 yd2 conf lib: read: 'phone_group dialer'
02/10 18:14:53 yd2 conf lib: read: 'phone_mode 0660'
02/10 18:14:53 yd2 conf lib: read: 'message_flag_file .flag'
02/10 18:14:53 yd2 conf lib: read: 'receive_dir incoming'
02/10 18:14:53 yd2 conf lib: read: 'message_dir messages'
02/10 18:14:53 yd2 conf lib: read: 'message_list Index'
02/10 18:14:53 yd2 conf lib: read: 'backup_message standard.rmd'
02/10 18:14:53 yd2 conf lib: read: 'port_speed 57600'
02/10 18:14:53 yd2 conf lib: read: 'voice_shell /usr/local/bin/bash'
02/10 18:14:53 yd2 conf lib: read: 'port_timeout 10'
02/10 18:14:53 yd2 conf lib: read: 'dial_timeout 90'
02/10 18:14:53 yd2 conf lib: read: 'command_delay 100'
02/10 18:14:53 yd2 conf lib: read: 'dtmf_len 30'
02/10 18:14:53 yd2 conf lib: read: 'dtmf_threshold 40'
02/10 18:14:53 yd2 conf lib: read: 'dtmf_wait 7'
02/10 18:14:53 yd2 conf lib: read: 'ignore_fax_dle false'
02/10 18:14:53 yd2 conf lib: read: 'raw_data false'
02/10 18:14:53 yd2 conf lib: read: 'rec_compression 0'
02/10 18:14:53 yd2 conf lib: read: 'rec_speed 0'
02/10 18:14:53 yd2 conf lib: read: 'rec_silence_len 70'
02/10 18:14:53 yd2 conf lib: read: 'rec_silence_threshold 40'
02/10 18:14:53 yd2 conf lib: read: 'rec_remove_silence true'
02/10 18:14:53 yd2 conf lib: read: 'rec_max_len 300'
02/10 18:14:53 yd2 conf lib: read: 'rec_min_len 0'
02/10 18:14:53 yd2 conf lib: read: 'do_hard_flow true'
02/10 18:14:53 yd2 conf lib: read: 'beep_frequency 933'
02/10 18:14:53 yd2 conf lib: read: 'beep_length 1500'
02/10 18:14:53 yd2 conf lib: read: 'max_tries 3'
02/10 18:14:53 yd2 conf lib: read: 'retry_delay 5'
02/10 18:14:53 yd2 conf lib: read: 'watchdog_timeout 60'
02/10 18:14:53 yd2 conf lib: read: 'receive_gain -1'
02/10 18:14:53 yd2 conf lib: read: 'transmit_gain -1'
02/10 18:14:53 yd2 conf lib: read: 'enable_command_echo false'
02/10 18:14:53 yd2 conf lib: read: 'poll_interval 10'
02/10 18:14:53 yd2 conf lib: read: 'program vgetty'
02/10 18:14:53 yd2 conf lib: read: 'rings 3'
02/10 18:14:53 yd2 conf lib: read: 'answer_mode voice:fax:data'
02/10 18:14:53 yd2 conf lib: read: 'force_autodetect false'
02/10 18:14:53 yd2 conf lib: read: 'toll_saver_rings 0'
02/10 18:14:53 yd2 conf lib: read: 'rec_always_keep true'
02/10 18:14:53 yd2 conf lib: read: 'button_program '
02/10 18:14:53 yd2 conf lib: read: 'call_program '
02/10 18:14:53 yd2 conf lib: read: 'dtmf_program dtmf.sh'
02/10 18:14:53 yd2 conf lib: read: 'message_program '
02/10 18:14:53 yd2 conf lib: read: 'do_message_light false'
02/10 18:14:53 yd2 conf lib: read: 'program vm'
02/10 18:14:53 yd2 conf lib: read: 'voice_devices ttyd2'
02/10 18:14:53 yd2 conf lib: read: 'dialout_timeout 90'
02/10 18:14:53 yd2 conf lib: read: 'program pvf'
02/10 18:14:53 yd2 conf lib: read: 'port ttyd2'
02/10 18:14:53 yd2 section: port ttyd2, **found**
02/10 18:14:53 yd2 conf lib: read: 'port ttyS0'
02/10 18:14:53 yd2 section: port ttyS0, ignore
02/10 18:14:53 yd2 conf lib: read: 'port ttyS5'
02/10 18:14:53 yd2 section: port ttyS5, ignore
02/10 18:14:53 yd2 conf lib: read: 'ring_type virtual'
02/10 18:14:53 yd2 conf lib: read: 'answer_mode fax:data'
02/10 18:14:53 yd2 conf lib: read: 'ring_type ring'
02/10 18:14:53 yd2 conf lib: read: 'ring_type ring1'
02/10 18:14:53 yd2 key: 'part', type=6, flags=4, data=(ignored)
02/10 18:14:53 yd2 key: 'program', type=6, flags=4, data=(ignored)
02/10 18:14:53 yd2 key: 'port', type=6, flags=4, data=(ignored)
02/10 18:14:53 yd2 key: 'ring_type', type=6, flags=4, data=(ignored)
02/10 18:14:53 yd2 key: 'voice_log_level', type=0, flags=3, data=6
02/10 18:14:53 yd2 key: 'voice_devices', type=1, flags=0, data=(empty)
02/10 18:14:53 yd2 key: 'port_speed', type=0, flags=3, data=57600
02/10 18:14:53 yd2 key: 'port_timeout', type=0, flags=3, data=10
02/10 18:14:53 yd2 key: 'dtmf_len', type=0, flags=3, data=30
02/10 18:14:53 yd2 key: 'dtmf_threshold', type=0, flags=3, data=40
02/10 18:14:53 yd2 key: 'dtmf_wait', type=0, flags=3, data=7
02/10 18:14:53 yd2 key: 'rec_compression', type=0, flags=3, data=0
02/10 18:14:53 yd2 key: 'rec_speed', type=0, flags=3, data=0
02/10 18:14:53 yd2 key: 'rec_silence_len', type=0, flags=3, data=70
02/10 18:14:53 yd2 key: 'rec_silence_threshold', type=0, flags=3, data=40
02/10 18:14:53 yd2 key: 'rec_remove_silence', type=3, flags=3, data=TRUE
02/10 18:14:53 yd2 key: 'rec_max_len', type=0, flags=3, data=300
02/10 18:14:53 yd2 key: 'receive_gain', type=0, flags=3, data=-1
02/10 18:14:53 yd2 key: 'transmit_gain', type=0, flags=3, data=-1
02/10 18:14:53 yd2 key: 'rings', type=1, flags=3, data=3
02/10 18:14:53 yd2 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
02/10 18:14:53 yd2 key: 'toll_saver_rings', type=0, flags=3, data=0
02/10 18:14:53 yd2 key: 'rec_always_keep', type=3, flags=3, data=TRUE
02/10 18:14:53 yd2 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
02/10 18:14:53 yd2 key: 'phone_owner', type=1, flags=3, data=root
02/10 18:14:53 yd2 key: 'phone_group', type=1, flags=3, data=dialer
02/10 18:14:53 yd2 key: 'phone_mode', type=0, flags=3, data=432
02/10 18:14:53 yd2 key: 'message_flag_file', type=1, flags=3, data=.flag
02/10 18:14:53 yd2 key: 'receive_dir', type=1, flags=3, data=incoming
02/10 18:14:53 yd2 key: 'message_dir', type=1, flags=3, data=messages
02/10 18:14:53 yd2 key: 'message_list', type=1, flags=3, data=Index
02/10 18:14:53 yd2 key: 'backup_message', type=1, flags=3, data=standard.rmd
02/10 18:14:53 yd2 key: 'dialout_timeout', type=0, flags=1, data=90
02/10 18:14:53 yd2 key: 'beep_frequency', type=0, flags=3, data=933
02/10 18:14:53 yd2 key: 'beep_length', type=0, flags=3, data=1500
02/10 18:14:53 yd2 key: 'raw_data', type=3, flags=3, data=FALSE
02/10 18:14:53 yd2 key: 'max_tries', type=0, flags=3, data=3
02/10 18:14:53 yd2 key: 'retry_delay', type=0, flags=3, data=5
02/10 18:14:53 yd2 key: 'voice_shell', type=1, flags=3, data=/usr/local/bin/bash
02/10 18:14:53 yd2 key: 'button_program', type=1, flags=3, data=
02/10 18:14:53 yd2 key: 'call_program', type=1, flags=3, data=
02/10 18:14:53 yd2 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
02/10 18:14:53 yd2 key: 'message_program', type=1, flags=3, data=
02/10 18:14:53 yd2 key: 'do_message_light', type=3, flags=3, data=FALSE
02/10 18:14:53 yd2 key: 'do_hard_flow', type=3, flags=3, data=TRUE
02/10 18:14:53 yd2 key: 'force_autodetect', type=3, flags=3, data=FALSE
02/10 18:14:53 yd2 key: 'watchdog_timeout', type=0, flags=3, data=60
02/10 18:14:53 yd2 key: 'rec_min_len', type=0, flags=3, data=0
02/10 18:14:53 yd2 key: 'command_delay', type=0, flags=3, data=100
02/10 18:14:53 yd2 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
02/10 18:14:53 yd2 key: 'dial_timeout', type=0, flags=3, data=90
02/10 18:14:53 yd2 key: 'enable_command_echo', type=3, flags=3, data=FALSE
02/10 18:14:53 yd2 key: 'poll_interval', type=0, flags=3, data=10
02/10 18:14:53 yd2 check for lockfiles
02/10 18:14:53 yd2 checklock: stat failed, no file
02/10 18:14:53 yd2 locking the line
02/10 18:14:53 yd2 makelock(ttyd2) called
02/10 18:14:53 yd2 do_makelock: lock='/var/spool/lock/LCK..ttyd2'
02/10 18:14:53 yd2 lock made
02/10 18:14:56 yd2 tio_get_rs232_lines: status: RTS CTS DTR
02/10 18:14:56 yd2 WARNING: DSR is off - modem turned off or bad cable?
02/10 18:14:56 yd2 lowering DTR to reset Modem
02/10 18:14:56 yd2 tss: set speed to 115200 (341000)
02/10 18:14:56 yd2 tio_set_flow_control( HARD )
02/10 18:14:56 yd2 waiting for line to clear (VTIME), read: 
02/10 18:14:56 yd2 send: \dATQ0V1H0[0d]
02/10 18:14:57 yd2 waiting for ``OK''
02/10 18:14:57 yd2 got: [0d][0a]OK ** found **
02/10 18:14:57 yd2 send: ATS0=0Q0&D2&C1&A3&B1&K1&M4&N0&S1[0d]
02/10 18:14:57 yd2 waiting for ``OK''
02/10 18:14:57 yd2 got: [0d]
02/10 18:14:57 yd2 CND: OK[0a][0d][0a]OK ** found **
02/10 18:14:57 yd2 mdm_send: 'ATI'
02/10 18:14:57 yd2 got:[0d][0a][0d][0a]3361[0d]
02/10 18:14:57 yd2 mdm_gis: string 1: '3361'
02/10 18:14:57 yd2 got:[0a][0d][0a]OK[0d]
02/10 18:14:57 yd2 mdm_identify: string '3361'
02/10 18:14:57 yd2 USR Courier/Sportster V.34(+) detected
02/10 18:14:57 yd2 mdm_send: 'ATI3'
02/10 18:14:57 yd2 got:[0a][0d][0a]USRobotics Sportster Voice 33600 Fax RS Rev. 2.0[0d]
02/10 18:14:57 yd2 mdm_gis: string 1: 'USRobotics Sportster Voice 33600 Fax RS Rev. 2.0'
02/10 18:14:57 yd2 got:[0a][0d][0a]OK[0d]
02/10 18:14:57 yd2 additional info: 'USRobotics Sportster Voice 33600 Fax RS Rev. 2.0'
02/10 18:14:57 yd2 mdm_send: 'AT+FCLASS=2.0'
02/10 18:14:57 yd2 got:[0a][0d][0a]OK[0d]
02/10 18:14:58 yd2 mdm_command: string 'OK' -> OK
02/10 18:14:58 yd2 mdm_send: 'AT+FAA=1;+FCR=1'
02/10 18:14:58 yd2 got:[0a][0d][0a]OK[0d]
02/10 18:14:58 yd2 mdm_command: string 'OK' -> OK
02/10 18:14:58 yd2 mdm_send: 'AT+FBO=1'
02/10 18:14:58 yd2 got:[0a][0d][0a]OK[0d]
02/10 18:14:58 yd2 mdm_command: string 'OK' -> OK
02/10 18:14:58 yd2 mdm_send: 'AT+FNR=1,1,1,0'
02/10 18:14:58 yd2 got:[0a][0d][0a]OK[0d]
02/10 18:14:58 yd2 mdm_command: string 'OK' -> OK
02/10 18:14:58 yd2 mdm_send: 'AT+FLI="49 89 xxxxxxxx"'
02/10 18:14:58 yd2 got:[0a][0d][0a]OK[0d]
02/10 18:14:58 yd2 mdm_command: string 'OK' -> OK
02/10 18:14:58 yd2 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
02/10 18:14:58 yd2 got:[0a][0d][0a]OK[0d]
02/10 18:14:59 yd2 mdm_command: string 'OK' -> OK
02/10 18:14:59 yd2 tss: set speed to 57600 (160400)
02/10 18:14:59 yd2 tio_set_flow_control( HARD )
02/10 18:14:59 yd2 detecting voice modem type
02/10 18:14:59 yd2 vgetty: ATE0
02/10 18:14:59 yd2 voice command: 'ATI' -> ''
02/10 18:14:59 yd2 vgetty: ATI
02/10 18:14:59 yd2 serial port: OK
02/10 18:14:59 yd2 serial port: 3361
02/10 18:14:59 yd2 US Robotics: OK
02/10 18:14:59 yd2 US Robotics detected
02/10 18:14:59 yd2 vgetty: queued event RESET_WATCHDOG at position 0000
02/10 18:14:59 yd2 US Robotics voice modem
02/10 18:14:59 yd2 This is a driver beta version. V0.4.b3
02/10 18:14:59 yd2 voice command: 'AT&H1&R2&I0' -> 'OK'
02/10 18:14:59 yd2 vgetty: AT&H1&R2&I0
02/10 18:14:59 yd2 US Robotics: OK
02/10 18:14:59 yd2 tio_set_flow_control( HARD )
02/10 18:14:59 yd2 voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
02/10 18:15:00 yd2 vgetty: AT#VTD=3F,3F,3F
02/10 18:15:00 yd2 US Robotics: OK
02/10 18:15:00 yd2 VTD setup successful
02/10 18:15:00 yd2 voice command: 'AT#VSD=1#VSS=1#VSP=70' -> 'OK'
02/10 18:15:00 yd2 vgetty: AT#VSD=1#VSS=1#VSP=70
02/10 18:15:00 yd2 US Robotics: OK
02/10 18:15:00 yd2 waiting for line to clear (VTIME), read: 
02/10 18:15:00 yd2 removing lock file
02/10 18:15:00 yd2 waiting...
02/10 18:16:20 yd2 select returned 1
02/10 18:16:20 yd2 checking lockfiles, locking the line
02/10 18:16:20 yd2 makelock(ttyd2) called
02/10 18:16:20 yd2 do_makelock: lock='/var/spool/lock/LCK..ttyd2'
02/10 18:16:20 yd2 lock made
02/10 18:16:20 yd2 vgetty: number of rings (3) was set directly
02/10 18:16:21 yd2 vgetty: decremented number of rings (3) by 0
02/10 18:16:21 yd2 waiting for ``RING_''
02/10 18:16:21 yd2 got: [0d]
02/10 18:16:21 yd2 CND: OK[0a]RING[0d]
02/10 18:16:21 yd2 CND: RING ** found **
02/10 18:16:21 yd2 waiting for ``RING_''
02/10 18:16:21 yd2 got: [0a][0d][0a]RING[0d]
02/10 18:16:26 yd2 CND: RING ** found **
02/10 18:16:26 yd2 waiting for ``RING_''
02/10 18:16:26 yd2 got: [0a][0d][0a]RING[0d]
02/10 18:16:32 yd2 CND: RING ** found **
02/10 18:16:32 yd2 reading ring_type ring configuration from config file /usr/local/etc/mgetty+sendfax/voice.conf
02/10 18:16:32 yd2 reading /usr/local/etc/mgetty+sendfax/voice.conf...
02/10 18:16:32 yd2 conf lib: read: 'part generic'
02/10 18:16:32 yd2 found CT_KEYWORD part generic
02/10 18:16:32 yd2 conf lib: read: 'voice_log_level 6'
02/10 18:16:32 yd2 conf lib: read: 'voice_dir /var/spool/voice'
02/10 18:16:32 yd2 conf lib: read: 'phone_owner root'
02/10 18:16:32 yd2 conf lib: read: 'phone_group dialer'
02/10 18:16:32 yd2 conf lib: read: 'phone_mode 0660'
02/10 18:16:32 yd2 conf lib: read: 'message_flag_file .flag'
02/10 18:16:32 yd2 conf lib: read: 'receive_dir incoming'
02/10 18:16:32 yd2 conf lib: read: 'message_dir messages'
02/10 18:16:32 yd2 conf lib: read: 'message_list Index'
02/10 18:16:32 yd2 conf lib: read: 'backup_message standard.rmd'
02/10 18:16:32 yd2 conf lib: read: 'port_speed 57600'
02/10 18:16:32 yd2 conf lib: read: 'voice_shell /usr/local/bin/bash'
02/10 18:16:32 yd2 conf lib: read: 'port_timeout 10'
02/10 18:16:32 yd2 conf lib: read: 'dial_timeout 90'
02/10 18:16:32 yd2 conf lib: read: 'command_delay 100'
02/10 18:16:32 yd2 conf lib: read: 'dtmf_len 30'
02/10 18:16:32 yd2 conf lib: read: 'dtmf_threshold 40'
02/10 18:16:32 yd2 conf lib: read: 'dtmf_wait 7'
02/10 18:16:32 yd2 conf lib: read: 'ignore_fax_dle false'
02/10 18:16:32 yd2 conf lib: read: 'raw_data false'
02/10 18:16:32 yd2 conf lib: read: 'rec_compression 0'
02/10 18:16:32 yd2 conf lib: read: 'rec_speed 0'
02/10 18:16:32 yd2 conf lib: read: 'rec_silence_len 70'
02/10 18:16:32 yd2 conf lib: read: 'rec_silence_threshold 40'
02/10 18:16:32 yd2 conf lib: read: 'rec_remove_silence true'
02/10 18:16:32 yd2 conf lib: read: 'rec_max_len 300'
02/10 18:16:32 yd2 conf lib: read: 'rec_min_len 0'
02/10 18:16:32 yd2 conf lib: read: 'do_hard_flow true'
02/10 18:16:32 yd2 conf lib: read: 'beep_frequency 933'
02/10 18:16:32 yd2 conf lib: read: 'beep_length 1500'
02/10 18:16:32 yd2 conf lib: read: 'max_tries 3'
02/10 18:16:32 yd2 conf lib: read: 'retry_delay 5'
02/10 18:16:32 yd2 conf lib: read: 'watchdog_timeout 60'
02/10 18:16:32 yd2 conf lib: read: 'receive_gain -1'
02/10 18:16:32 yd2 conf lib: read: 'transmit_gain -1'
02/10 18:16:32 yd2 conf lib: read: 'enable_command_echo false'
02/10 18:16:32 yd2 conf lib: read: 'poll_interval 10'
02/10 18:16:32 yd2 conf lib: read: 'program vgetty'
02/10 18:16:32 yd2 conf lib: read: 'rings 3'
02/10 18:16:32 yd2 conf lib: read: 'answer_mode voice:fax:data'
02/10 18:16:32 yd2 conf lib: read: 'force_autodetect false'
02/10 18:16:32 yd2 conf lib: read: 'toll_saver_rings 0'
02/10 18:16:32 yd2 conf lib: read: 'rec_always_keep true'
02/10 18:16:32 yd2 conf lib: read: 'button_program '
02/10 18:16:32 yd2 conf lib: read: 'call_program '
02/10 18:16:32 yd2 conf lib: read: 'dtmf_program dtmf.sh'
02/10 18:16:32 yd2 conf lib: read: 'message_program '
02/10 18:16:32 yd2 conf lib: read: 'do_message_light false'
02/10 18:16:32 yd2 conf lib: read: 'program vm'
02/10 18:16:32 yd2 conf lib: read: 'voice_devices ttyd2'
02/10 18:16:32 yd2 conf lib: read: 'dialout_timeout 90'
02/10 18:16:32 yd2 conf lib: read: 'program pvf'
02/10 18:16:32 yd2 conf lib: read: 'port ttyd2'
02/10 18:16:32 yd2 conf lib: read: 'port ttyS0'
02/10 18:16:32 yd2 conf lib: read: 'port ttyS5'
02/10 18:16:32 yd2 conf lib: read: 'ring_type virtual'
02/10 18:16:32 yd2 section: ring_type virtual, ignore
02/10 18:16:32 yd2 conf lib: read: 'answer_mode fax:data'
02/10 18:16:32 yd2 conf lib: read: 'ring_type ring'
02/10 18:16:32 yd2 section: ring_type ring, **found**
02/10 18:16:32 yd2 conf lib: read: 'ring_type ring1'
02/10 18:16:32 yd2 section: ring_type ring1, ignore
02/10 18:16:32 yd2 key: 'part', type=6, flags=4, data=(ignored)
02/10 18:16:32 yd2 key: 'program', type=6, flags=4, data=(ignored)
02/10 18:16:32 yd2 key: 'port', type=6, flags=4, data=(ignored)
02/10 18:16:32 yd2 key: 'ring_type', type=6, flags=4, data=(ignored)
02/10 18:16:32 yd2 key: 'voice_log_level', type=0, flags=3, data=6
02/10 18:16:32 yd2 key: 'voice_devices', type=1, flags=0, data=(empty)
02/10 18:16:32 yd2 key: 'port_speed', type=0, flags=3, data=57600
02/10 18:16:32 yd2 key: 'port_timeout', type=0, flags=3, data=10
02/10 18:16:32 yd2 key: 'dtmf_len', type=0, flags=3, data=30
02/10 18:16:32 yd2 key: 'dtmf_threshold', type=0, flags=3, data=40
02/10 18:16:32 yd2 key: 'dtmf_wait', type=0, flags=3, data=7
02/10 18:16:32 yd2 key: 'rec_compression', type=0, flags=3, data=0
02/10 18:16:32 yd2 key: 'rec_speed', type=0, flags=3, data=0
02/10 18:16:32 yd2 key: 'rec_silence_len', type=0, flags=3, data=70
02/10 18:16:32 yd2 key: 'rec_silence_threshold', type=0, flags=3, data=40
02/10 18:16:32 yd2 key: 'rec_remove_silence', type=3, flags=3, data=TRUE
02/10 18:16:32 yd2 key: 'rec_max_len', type=0, flags=3, data=300
02/10 18:16:32 yd2 key: 'receive_gain', type=0, flags=3, data=-1
02/10 18:16:32 yd2 key: 'transmit_gain', type=0, flags=3, data=-1
02/10 18:16:32 yd2 key: 'rings', type=1, flags=3, data=3
02/10 18:16:32 yd2 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
02/10 18:16:32 yd2 key: 'toll_saver_rings', type=0, flags=3, data=0
02/10 18:16:32 yd2 key: 'rec_always_keep', type=3, flags=3, data=TRUE
02/10 18:16:32 yd2 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
02/10 18:16:32 yd2 key: 'phone_owner', type=1, flags=3, data=root
02/10 18:16:32 yd2 key: 'phone_group', type=1, flags=3, data=dialer
02/10 18:16:32 yd2 key: 'phone_mode', type=0, flags=3, data=432
02/10 18:16:32 yd2 key: 'message_flag_file', type=1, flags=3, data=.flag
02/10 18:16:32 yd2 key: 'receive_dir', type=1, flags=3, data=incoming
02/10 18:16:32 yd2 key: 'message_dir', type=1, flags=3, data=messages
02/10 18:16:32 yd2 key: 'message_list', type=1, flags=3, data=Index
02/10 18:16:32 yd2 key: 'backup_message', type=1, flags=3, data=standard.rmd
02/10 18:16:32 yd2 key: 'dialout_timeout', type=0, flags=1, data=90
02/10 18:16:32 yd2 key: 'beep_frequency', type=0, flags=3, data=933
02/10 18:16:32 yd2 key: 'beep_length', type=0, flags=3, data=1500
02/10 18:16:32 yd2 key: 'raw_data', type=3, flags=3, data=FALSE
02/10 18:16:32 yd2 key: 'max_tries', type=0, flags=3, data=3
02/10 18:16:32 yd2 key: 'retry_delay', type=0, flags=3, data=5
02/10 18:16:32 yd2 key: 'voice_shell', type=1, flags=3, data=/usr/local/bin/bash
02/10 18:16:32 yd2 key: 'button_program', type=1, flags=3, data=
02/10 18:16:32 yd2 key: 'call_program', type=1, flags=3, data=
02/10 18:16:32 yd2 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
02/10 18:16:32 yd2 key: 'message_program', type=1, flags=3, data=
02/10 18:16:32 yd2 key: 'do_message_light', type=3, flags=3, data=FALSE
02/10 18:16:32 yd2 key: 'do_hard_flow', type=3, flags=3, data=TRUE
02/10 18:16:32 yd2 key: 'force_autodetect', type=3, flags=3, data=FALSE
02/10 18:16:32 yd2 key: 'watchdog_timeout', type=0, flags=3, data=60
02/10 18:16:32 yd2 key: 'rec_min_len', type=0, flags=3, data=0
02/10 18:16:32 yd2 key: 'command_delay', type=0, flags=3, data=100
02/10 18:16:32 yd2 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
02/10 18:16:32 yd2 key: 'dial_timeout', type=0, flags=3, data=90
02/10 18:16:32 yd2 key: 'enable_command_echo', type=3, flags=3, data=FALSE
02/10 18:16:32 yd2 key: 'poll_interval', type=0, flags=3, data=10
02/10 18:16:32 yd2 vgetty: answer mode was set directly
02/10 18:16:32 yd2 vgetty: answer mode is [data|fax|voice]
02/10 18:16:32 yd2 vgetty: opening list file /var/spool/voice/messages/Index
02/10 18:16:32 yd2 vgetty: found 1 messages, picked message number 1
02/10 18:16:32 yd2 vgetty: message name is /var/spool/voice/messages/standard.rmd
02/10 18:16:32 yd2 vgetty: entering voice mode
02/10 18:16:32 yd2 vgetty: Installing signal handlers
02/10 18:16:32 yd2 voice command: 'AT#CLS?' -> ''
02/10 18:16:33 yd2 vgetty: AT#CLS?
02/10 18:16:33 yd2 US Robotics: 0
02/10 18:16:33 yd2 voice command: '' -> 'OK'
02/10 18:16:33 yd2 US Robotics: OK
02/10 18:16:33 yd2 voice command: 'AT#CLS=8' -> 'OK'
02/10 18:16:33 yd2 vgetty: AT#CLS=8
02/10 18:16:33 yd2 US Robotics: OK
02/10 18:16:33 yd2 voice command: 'AT#VLS=4A' -> 'VCON'
02/10 18:16:33 yd2 vgetty: AT#VLS=4A
02/10 18:16:33 yd2 US Robotics: VCON
02/10 18:16:33 yd2 playing voice file /var/spool/voice/messages/standard.rmd
02/10 18:16:33 yd2 vgetty: raw modem data header found
02/10 18:16:33 yd2 vgetty: modem type US Robotics found
02/10 18:16:33 yd2 vgetty: compression method 0x0001, speed 8000, bits 8
02/10 18:16:33 yd2 vgetty: queued event RESET_WATCHDOG at position 0001
02/10 18:16:33 yd2 vgetty: unqueued event RESET_WATCHDOG at position 0000
02/10 18:16:33 yd2 vgetty: voice_handle_event got event RESET_WATCHDOG with data <
02/10 18:16:33 yd2 vgetty: unqueued event RESET_WATCHDOG at position 0001
02/10 18:16:33 yd2 vgetty: voice_handle_event got event RESET_WATCHDOG with data <
02/10 18:16:33 yd2 voice command: 'AT' -> 'OK'
02/10 18:16:33 yd2 vgetty: AT
02/10 18:16:33 yd2 US Robotics: OK
02/10 18:16:33 yd2 tio_set_flow_control( HARD XON_OUT )
02/10 18:16:33 yd2 voice command: 'ATE0#VTX' -> 'CONNECT'
02/10 18:16:34 yd2 vgetty: ATE0#VTX
02/10 18:16:34 yd2 US Robotics: CONNECT
02/10 18:16:47 yd2 vgetty: <VOICE DATA 28404 bytes>
02/10 18:16:47 yd2 vgetty: queued event RESET_WATCHDOG at position 0002
02/10 18:16:47 yd2 vgetty: <STOP PLAY>
02/10 18:16:47 yd2 voice command: '' -> 'OK|VCON'
02/10 18:16:47 yd2 US Robotics: VCON
02/10 18:16:49 yd2 vgetty: unqueued event RESET_WATCHDOG at position 0002
02/10 18:16:49 yd2 vgetty: voice_handle_event got event RESET_WATCHDOG with data <
02/10 18:16:49 yd2 voice command: '' -> 'OK|VCON'
02/10 18:16:49 yd2 US Robotics: OK
02/10 18:16:49 yd2 voice command: 'AT' -> 'OK'
02/10 18:16:49 yd2 vgetty: AT
02/10 18:16:49 yd2 US Robotics: AT
02/10 18:16:49 yd2 vgetty: Invalid modem answer: Undefined error: 0
02/10 18:16:49 yd2 US Robotics: OK
02/10 18:16:49 yd2 vgetty: stop_play_file command failed: Undefined error: 0
02/10 18:16:49 yd2 vgetty: Could not play greeting message: Undefined error: 0
02/10 18:16:49 yd2 vgetty: queued event RESET_WATCHDOG at position 0003
02/10 18:16:49 yd2 voice command: 'AT#VTS=[933,0,15]' -> ''
02/10 18:16:49 yd2 vgetty: AT#VTS=[933,0,15]
02/10 18:16:50 yd2 voice command: '' -> 'OK'
02/10 18:16:50 yd2 US Robotics: AT#VTS=[933,0,15]
02/10 18:16:51 yd2 vgetty: Invalid modem answer: Undefined error: 0
02/10 18:16:51 yd2 US Robotics: OK
02/10 18:16:51 yd2 /dev/ttyd2: Beep command failed: Undefined error: 0
02/10 18:16:51 yd2 recording voice file /var/spool/voice/incoming/v000190.rmd
02/10 18:16:51 yd2 vgetty: queued event RESET_WATCHDOG at position 0004
02/10 18:16:51 yd2 vgetty: unqueued event RESET_WATCHDOG at position 0003
02/10 18:16:51 yd2 vgetty: voice_handle_event got event RESET_WATCHDOG with data <
02/10 18:16:51 yd2 vgetty: unqueued event RESET_WATCHDOG at position 0004
02/10 18:16:51 yd2 vgetty: voice_handle_event got event RESET_WATCHDOG with data <
02/10 18:16:51 yd2 voice command: 'AT' -> 'OK'
02/10 18:16:51 yd2 vgetty: AT
02/10 18:16:51 yd2 US Robotics: AT
02/10 18:16:51 yd2 vgetty: Invalid modem answer: Undefined error: 0
02/10 18:16:51 yd2 US Robotics: OK
02/10 18:16:51 yd2 vgetty: Could not record a message: Undefined error: 0
--
.