Vgetty reports: Huh? Junk in the line?
Giovanni Biscuolo (giovanni.biscuolo@libero.it)
Fri, 14 Jan 2000 13:46:31 +0100
This is a multi-part message in MIME format.
--------------2005B621C447DD910AB2EA3A
Content-Type: text/plain; charset=us-ascii
Content-Transfer-Encoding: 7bit
I compiled the mgetty1.1.21-Jul24.tar.gz package patched with the
mgetty1.1.21-to-current.bz2.
My modem is a US Robotics Sportser Flash V.90 (upgraded flashROM).
My modem only answer once, ignoring all following calls, with the "OH"
led blinking.
An abstract of my vgetty log file (log level 6 ;-) ) is attached.
Please help me for I am not able to use vgetty since Sept. 1999 and it
is
really annoing me.
Thank you.
--
____________________________________
Giovanni Biscuolo
mailto: giovanni.biscuolo@libero.it
--------------2005B621C447DD910AB2EA3A
Content-Type: text/plain; charset=us-ascii;
name="voice-log-strange.txt"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline;
filename="voice-log-strange.txt"
01/14 12:09:47 yS0 waiting...
01/14 12:12:55 yS0 select returned 1
01/14 12:12:55 yS0 checking lockfiles, locking the line
01/14 12:12:55 yS0 makelock(ttyS0) called
01/14 12:12:55 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
01/14 12:12:55 yS0 lock made
01/14 12:12:55 yS0 vgetty: number of rings (6) was set directly
01/14 12:12:55 yS0 vgetty: decremented number of rings (6) by 0
01/14 12:12:55 yS0 wfr: waiting for ``RING''
01/14 12:12:55 yS0 got: [0a][0d][0a]RING[0d]
01/14 12:12:55 yS0 CND: RING
01/14 12:12:55 yS0 wfr: rc=0, drn=0
01/14 12:12:55 yS0 wfr: waiting for ``RING''
01/14 12:12:55 yS0 got: [0a][0d][0a]RING[0d]
01/14 12:13:00 yS0 CND: RING
01/14 12:13:00 yS0 wfr: rc=0, drn=0
01/14 12:13:00 yS0 wfr: waiting for ``RING''
01/14 12:13:00 yS0 got: [0a][0d][0a]RING[0d]
01/14 12:13:05 yS0 CND: RING
01/14 12:13:05 yS0 wfr: rc=0, drn=0
01/14 12:13:05 yS0 wfr: waiting for ``RING''
01/14 12:13:05 yS0 got: [0a][0d][0a]RING[0d]
01/14 12:13:10 yS0 CND: RING
01/14 12:13:10 yS0 wfr: rc=0, drn=0
01/14 12:13:10 yS0 wfr: waiting for ``RING''
01/14 12:13:10 yS0 got: [0a][0d][0a]RING[0d]
01/14 12:13:15 yS0 CND: RING
01/14 12:13:15 yS0 wfr: rc=0, drn=0
01/14 12:13:15 yS0 wfr: waiting for ``RING''
01/14 12:13:15 yS0 got: [0a][0d][0a]RING[0d]
01/14 12:13:20 yS0 CND: RING
01/14 12:13:20 yS0 wfr: rc=0, drn=0
01/14 12:13:20 yS0 setenv: 'CALLER_ID=none'
01/14 12:13:20 yS0 setenv: 'CALLED_ID='
01/14 12:13:20 yS0 setenv: 'CONNECT='
01/14 12:13:20 yS0 reading ring_type ring configuration from config file /etc/mgetty+sendfax/voice.conf
01/14 12:13:20 yS0 reading /etc/mgetty+sendfax/voice.conf...
01/14 12:13:20 yS0 conf lib: read: 'part generic'
01/14 12:13:20 yS0 found CT_KEYWORD part generic
01/14 12:13:20 yS0 conf lib: read: 'voice_log_level 6'
01/14 12:13:20 yS0 conf lib: read: 'voice_dir /var/spool/voice'
01/14 12:13:20 yS0 conf lib: read: 'phone_owner root'
01/14 12:13:20 yS0 conf lib: read: 'phone_group root'
01/14 12:13:20 yS0 conf lib: read: 'phone_mode 0664'
01/14 12:13:20 yS0 conf lib: read: 'message_flag_file .flag'
01/14 12:13:20 yS0 conf lib: read: 'receive_dir incoming'
01/14 12:13:20 yS0 conf lib: read: 'message_dir messages'
01/14 12:13:20 yS0 conf lib: read: 'message_list Index'
01/14 12:13:20 yS0 conf lib: read: 'backup_message standard.rmd'
01/14 12:13:20 yS0 conf lib: read: 'port_speed 115200'
01/14 12:13:20 yS0 conf lib: read: 'voice_shell /bin/sh'
01/14 12:13:20 yS0 conf lib: read: 'port_timeout 20'
01/14 12:13:20 yS0 conf lib: read: 'dial_timeout 90'
01/14 12:13:20 yS0 conf lib: read: 'command_delay 100'
01/14 12:13:20 yS0 conf lib: read: 'dtmf_len 100'
01/14 12:13:20 yS0 conf lib: read: 'dtmf_threshold 40'
01/14 12:13:20 yS0 conf lib: read: 'dtmf_wait 9'
01/14 12:13:20 yS0 conf lib: read: 'ignore_fax_dle false'
01/14 12:13:20 yS0 conf lib: read: 'raw_data false'
01/14 12:13:20 yS0 conf lib: read: 'rec_compression 0'
01/14 12:13:20 yS0 conf lib: read: 'rec_speed 0'
01/14 12:13:20 yS0 conf lib: read: 'rec_silence_len 70'
01/14 12:13:20 yS0 conf lib: read: 'rec_silence_threshold 70'
01/14 12:13:20 yS0 conf lib: read: 'rec_remove_silence false'
01/14 12:13:20 yS0 conf lib: read: 'rec_max_len 300'
01/14 12:13:20 yS0 conf lib: read: 'rec_min_len 0'
01/14 12:13:20 yS0 conf lib: read: 'do_hard_flow true'
01/14 12:13:20 yS0 conf lib: read: 'beep_frequency 999'
01/14 12:13:20 yS0 conf lib: read: 'beep_length 1500'
01/14 12:13:20 yS0 conf lib: read: 'max_tries 3'
01/14 12:13:20 yS0 conf lib: read: 'retry_delay 3'
01/14 12:13:20 yS0 conf lib: read: 'watchdog_timeout 60'
01/14 12:13:20 yS0 conf lib: read: 'receive_gain 100%'
01/14 12:13:20 yS0 conf lib: read: 'transmit_gain 100%'
01/14 12:13:20 yS0 conf lib: read: 'enable_command_echo true'
01/14 12:13:20 yS0 conf lib: read: 'poll_interval 13'
01/14 12:13:20 yS0 conf lib: read: 'program vgetty'
01/14 12:13:20 yS0 conf lib: read: 'rings 6'
01/14 12:13:20 yS0 conf lib: read: 'answer_mode voice:fax:data'
01/14 12:13:20 yS0 conf lib: read: 'force_autodetect false'
01/14 12:13:20 yS0 conf lib: read: 'toll_saver_rings 0'
01/14 12:13:20 yS0 conf lib: read: 'rec_always_keep true'
01/14 12:13:20 yS0 conf lib: read: 'button_program '
01/14 12:13:20 yS0 conf lib: read: 'call_program '
01/14 12:13:20 yS0 conf lib: read: 'dtmf_program dtmf.sh'
01/14 12:13:20 yS0 conf lib: read: 'message_program '
01/14 12:13:20 yS0 conf lib: read: 'do_message_light false'
01/14 12:13:20 yS0 conf lib: read: 'program vm'
01/14 12:13:20 yS0 conf lib: read: 'voice_devices ttyS0'
01/14 12:13:20 yS0 conf lib: read: 'dialout_timeout 90'
01/14 12:13:20 yS0 conf lib: read: 'program pvf'
01/14 12:13:20 yS0 key: 'part', type=6, flags=4, data=(ignored)
01/14 12:13:20 yS0 key: 'program', type=6, flags=4, data=(ignored)
01/14 12:13:20 yS0 key: 'port', type=6, flags=4, data=(ignored)
01/14 12:13:20 yS0 key: 'ring_type', type=6, flags=4, data=(ignored)
01/14 12:13:20 yS0 key: 'voice_log_level', type=0, flags=3, data=6
01/14 12:13:20 yS0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
01/14 12:13:20 yS0 key: 'port_speed', type=0, flags=3, data=115200
01/14 12:13:20 yS0 key: 'port_timeout', type=0, flags=3, data=20
01/14 12:13:20 yS0 key: 'dial_timeout', type=0, flags=3, data=90
01/14 12:13:20 yS0 key: 'command_delay', type=0, flags=3, data=100
01/14 12:13:20 yS0 key: 'dtmf_len', type=0, flags=3, data=100
01/14 12:13:20 yS0 key: 'dtmf_threshold', type=0, flags=3, data=40
01/14 12:13:20 yS0 key: 'dtmf_wait', type=0, flags=3, data=9
01/14 12:13:20 yS0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
01/14 12:13:20 yS0 key: 'raw_data', type=3, flags=3, data=FALSE
01/14 12:13:20 yS0 key: 'rec_compression', type=0, flags=3, data=0
01/14 12:13:20 yS0 key: 'rec_speed', type=0, flags=3, data=0
01/14 12:13:20 yS0 key: 'rec_silence_len', type=0, flags=3, data=70
01/14 12:13:20 yS0 key: 'rec_silence_threshold', type=0, flags=3, data=70
01/14 12:13:20 yS0 key: 'rec_remove_silence', type=3, flags=3, data=FALSE
01/14 12:13:20 yS0 key: 'rec_max_len', type=0, flags=3, data=300
01/14 12:13:20 yS0 key: 'rec_min_len', type=0, flags=3, data=0
01/14 12:13:20 yS0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
01/14 12:13:20 yS0 key: 'force_autodetect', type=3, flags=3, data=FALSE
01/14 12:13:20 yS0 key: 'watchdog_timeout', type=0, flags=3, data=60
01/14 12:13:20 yS0 key: 'receive_gain', type=0, flags=3, data=100
01/14 12:13:20 yS0 key: 'transmit_gain', type=0, flags=3, data=100
01/14 12:13:20 yS0 key: 'enable_command_echo', type=3, flags=3, data=TRUE
01/14 12:13:20 yS0 key: 'poll_interval', type=0, flags=3, data=13
01/14 12:13:20 yS0 key: 'rings', type=1, flags=3, data=6
01/14 12:13:20 yS0 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
01/14 12:13:20 yS0 key: 'toll_saver_rings', type=0, flags=3, data=0
01/14 12:13:20 yS0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
01/14 12:13:20 yS0 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
01/14 12:13:20 yS0 key: 'phone_owner', type=1, flags=3, data=root
01/14 12:13:20 yS0 key: 'phone_group', type=1, flags=3, data=root
01/14 12:13:20 yS0 key: 'phone_mode', type=0, flags=3, data=436
01/14 12:13:20 yS0 key: 'message_flag_file', type=1, flags=3, data=.flag
01/14 12:13:20 yS0 key: 'receive_dir', type=1, flags=3, data=incoming
01/14 12:13:20 yS0 key: 'message_dir', type=1, flags=3, data=messages
01/14 12:13:20 yS0 key: 'message_list', type=1, flags=3, data=Index
01/14 12:13:20 yS0 key: 'backup_message', type=1, flags=3, data=standard.rmd
01/14 12:13:20 yS0 key: 'button_program', type=1, flags=3, data=
01/14 12:13:20 yS0 key: 'call_program', type=1, flags=3, data=
01/14 12:13:20 yS0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
01/14 12:13:20 yS0 key: 'message_program', type=1, flags=3, data=
01/14 12:13:20 yS0 key: 'do_message_light', type=3, flags=3, data=FALSE
01/14 12:13:20 yS0 key: 'beep_frequency', type=0, flags=3, data=999
01/14 12:13:20 yS0 key: 'beep_length', type=0, flags=3, data=1500
01/14 12:13:20 yS0 key: 'max_tries', type=0, flags=3, data=3
01/14 12:13:20 yS0 key: 'retry_delay', type=0, flags=3, data=3
01/14 12:13:20 yS0 key: 'dialout_timeout', type=0, flags=1, data=90
01/14 12:13:20 yS0 key: 'ringback_goes_away', type=0, flags=1, data=70
01/14 12:13:20 yS0 key: 'ringback_never_came', type=0, flags=1, data=100
01/14 12:13:20 yS0 key: 'voice_devices', type=1, flags=1, data=
01/14 12:13:20 yS0 vgetty: answer mode was set directly
01/14 12:13:20 yS0 vgetty: answer mode is [data|fax|voice]
01/14 12:13:20 yS0 vgetty: opening list file /var/spool/voice/messages/Index
01/14 12:13:20 yS0 vgetty: found 1 messages, picked message number 1
01/14 12:13:20 yS0 vgetty: message name is /var/spool/voice/messages/primo.rmd
01/14 12:13:20 yS0 vgetty: entering voice mode
01/14 12:13:20 yS0 vgetty: Installing signal handlers
01/14 12:13:20 yS0 voice command: 'AT#CLS?' -> ''
01/14 12:13:20 yS0 vgetty: AT#CLS?
01/14 12:13:20 yS0 US Robotics: AT#CLS?
01/14 12:13:20 yS0 US Robotics: 2.0
01/14 12:13:20 yS0 voice command: '' -> 'OK'
01/14 12:13:20 yS0 US Robotics: OK
01/14 12:13:20 yS0 voice command: 'AT#CLS=8' -> 'OK'
01/14 12:13:20 yS0 vgetty: AT#CLS=8
01/14 12:13:20 yS0 US Robotics: AT#CLS=8
01/14 12:13:20 yS0 US Robotics: OK
01/14 12:13:20 yS0 voice command: 'AT' -> 'OK'
01/14 12:13:20 yS0 vgetty: AT
01/14 12:13:20 yS0 US Robotics: AT
01/14 12:13:21 yS0 US Robotics: OK
01/14 12:13:21 yS0 voice command: 'AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100' -> 'OK'
01/14 12:13:21 yS0 vgetty: AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/14 12:13:21 yS0 US Robotics: AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/14 12:13:21 yS0 US Robotics: OK
01/14 12:13:21 yS0 voice command: 'AT#VLS=0A' -> 'VCON'
01/14 12:13:21 yS0 vgetty: AT#VLS=0A
01/14 12:13:21 yS0 US Robotics: AT#VLS=0A
01/14 12:13:21 yS0 US Robotics: VCON
01/14 12:13:21 yS0 vgetty: queued event RESET_WATCHDOG at position 0001
01/14 12:13:21 yS0 voice command: 'AT#VLS=0' -> 'OK|VCON'
01/14 12:13:21 yS0 vgetty: AT#VLS=0
01/14 12:13:21 yS0 vgetty: unqueued event RESET_WATCHDOG at position 0001
01/14 12:13:21 yS0 vgetty: voice_handle_event got event RESET_WATCHDOG with data <`>
01/14 12:13:21 yS0 US Robotics: AT#VLS=0
01/14 12:13:21 yS0 US Robotics: OK
01/14 12:13:21 yS0 playing voice file /var/spool/voice/messages/primo.rmd
01/14 12:13:21 yS0 vgetty: raw modem data header found
01/14 12:13:21 yS0 vgetty: modem type US Robotics found
01/14 12:13:21 yS0 vgetty: compression method 0x0001, speed 8000, bits 1
01/14 12:13:21 yS0 vgetty: queued event RESET_WATCHDOG at position 0002
01/14 12:13:21 yS0 vgetty: unqueued event RESET_WATCHDOG at position 0002
01/14 12:13:21 yS0 vgetty: voice_handle_event got event RESET_WATCHDOG with data <`>
01/14 12:13:21 yS0 voice command: 'AT' -> 'OK'
01/14 12:13:21 yS0 vgetty: AT
01/14 12:13:21 yS0 US Robotics: AT
01/14 12:13:21 yS0 US Robotics: OK
01/14 12:13:21 yS0 tio_set_flow_control( HARD XON_OUT )
01/14 12:13:21 yS0 voice command: 'ATE0#VTX' -> 'CONNECT'
01/14 12:13:22 yS0 vgetty: ATE0#VTX
01/14 12:13:22 yS0 US Robotics: ATE0#VTX
01/14 12:13:22 yS0 US Robotics: CONNECT
01/14 12:13:28 yS0 vgetty: <VOICE DATA 16404 bytes>
01/14 12:13:28 yS0 vgetty: queued event RESET_WATCHDOG at position 0003
01/14 12:13:28 yS0 vgetty: unqueued event RESET_WATCHDOG at position 0003
01/14 12:13:28 yS0 vgetty: voice_handle_event got event RESET_WATCHDOG with data <H>
01/14 12:13:28 yS0 vgetty: <STOP PLAY>
01/14 12:13:28 yS0 voice command: '' -> 'OK|VCON'
01/14 12:13:29 yS0 US Robotics: VCON
01/14 12:13:30 yS0 voice command: '' -> 'OK|VCON'
01/14 12:13:30 yS0 US Robotics: OK
01/14 12:13:30 yS0 voice command: 'AT' -> 'OK'
01/14 12:13:31 yS0 vgetty: AT
01/14 12:13:31 yS0 US Robotics: AT
01/14 12:13:31 yS0 US Robotics: OK
01/14 12:13:31 yS0 vgetty: queued event RESET_WATCHDOG at position 0004
01/14 12:13:31 yS0 voice command: 'AT#VTS=[999,0,15]' -> ''
01/14 12:13:31 yS0 vgetty: AT#VTS=[999,0,15]
01/14 12:13:31 yS0 vgetty: unqueued event RESET_WATCHDOG at position 0004
01/14 12:13:31 yS0 vgetty: voice_handle_event got event RESET_WATCHDOG with data <H>
01/14 12:13:31 yS0 US Robotics: AT#VTS=[999,0,15]
01/14 12:13:33 yS0 voice command: '' -> 'OK'
01/14 12:13:33 yS0 US Robotics: OK
01/14 12:13:33 yS0 voice command: 'AT#VLS=0A' -> 'VCON'
01/14 12:13:33 yS0 vgetty: AT#VLS=0A
01/14 12:13:33 yS0 US Robotics: AT#VLS=0A
01/14 12:13:33 yS0 US Robotics: VCON
01/14 12:13:33 yS0 recording voice file /var/spool/voice/incoming/vzx4MXa.rmd
01/14 12:13:33 yS0 vgetty: queued event RESET_WATCHDOG at position 0005
01/14 12:13:33 yS0 vgetty: unqueued event RESET_WATCHDOG at position 0005
01/14 12:13:33 yS0 vgetty: voice_handle_event got event RESET_WATCHDOG with data <`>
01/14 12:13:33 yS0 voice command: 'AT' -> 'OK'
01/14 12:13:33 yS0 vgetty: AT
01/14 12:13:33 yS0 US Robotics: AT
01/14 12:13:33 yS0 US Robotics: OK
01/14 12:13:33 yS0 tio_set_flow_control( HARD XON_IN )
01/14 12:13:33 yS0 voice command: 'AT#VRX' -> 'CONNECT'
01/14 12:13:33 yS0 vgetty: AT#VRX
01/14 12:13:33 yS0 US Robotics: AT#VRX
01/14 12:13:33 yS0 US Robotics: CONNECT
01/14 12:13:41 yS0 US Robotics: <DLE> <i>
01/14 12:13:41 yS0 USR_handle_dle: Ignoring <DLE> <i> because the modem is RECORDING.
01/14 12:13:49 yS0 US Robotics: <DLE> <b>
01/14 12:13:49 yS0 vgetty: queued event BUSY_TONE at position 0006
01/14 12:13:49 yS0 vgetty: unqueued event BUSY_TONE at position 0006
01/14 12:13:49 yS0 vgetty: voice_handle_event got event BUSY_TONE with data <H>
01/14 12:13:49 yS0 vgetty: <STOP RECORDING>
01/14 12:13:49 yS0 US Robotics: <VOICE DATA 26409 bytes>
01/14 12:13:49 yS0 US Robotics: <DLE> <ETX>
01/14 12:13:49 yS0 voice command: '' -> 'OK|VCON'
01/14 12:13:50 yS0 US Robotics: VCON
01/14 12:13:50 yS0 vgetty: queued event RESET_WATCHDOG at position 0007
01/14 12:13:50 yS0 voice command: 'AT#VLS=0H0' -> 'OK|VCON'
01/14 12:13:50 yS0 vgetty: AT#VLS=0H0
01/14 12:13:50 yS0 vgetty: unqueued event RESET_WATCHDOG at position 0007
01/14 12:13:50 yS0 vgetty: voice_handle_event got event RESET_WATCHDOG with data <H>
01/14 12:13:50 yS0 US Robotics: AT#VLS=0H0
01/14 12:13:50 yS0 US Robotics: OK
01/14 12:13:50 yS0 vgetty: leaving voice mode
01/14 12:13:50 yS0 voice command: 'AT#CLS=2.0' -> 'OK'
01/14 12:13:50 yS0 vgetty: AT#CLS=2.0
01/14 12:13:50 yS0 US Robotics: AT#CLS=2.0
01/14 12:13:50 yS0 US Robotics: OK
01/14 12:13:50 yS0 voice command: 'AT' -> 'OK'
01/14 12:13:50 yS0 vgetty: AT
01/14 12:13:50 yS0 US Robotics: AT
01/14 12:13:50 yS0 US Robotics: OK
01/14 12:13:50 yS0 vgetty: Restoring signal handlers
01/14 12:13:50 yS0 closing voice modem device
01/14 12:13:50 yS0 removing lock file
01/14 12:13:50 ##### message keep, length=00:00:17, name='', caller=none, dev=ttyS0, pid=3396
01/14 12:13:50 yS0 vgetty: creating message flag file /var/spool/voice/.flag
--
01/14 12:13:50 yS0 vgetty: experimental test release 0.9.9 / 13Nov99
01/14 12:13:50 yS0 mgetty: experimental test release 1.1.21-Jul24
01/14 12:13:50 yS0 reading generic configuration from config file /etc/mgetty+sendfax/voice.conf
01/14 12:13:50 yS0 reading program vgetty configuration from config file /etc/mgetty+sendfax/voice.conf
01/14 12:13:50 yS0 reading /etc/mgetty+sendfax/voice.conf...
01/14 12:13:50 yS0 conf lib: read: 'part generic'
01/14 12:13:50 yS0 found CT_KEYWORD part generic
01/14 12:13:50 yS0 conf lib: read: 'voice_log_level 6'
01/14 12:13:50 yS0 conf lib: read: 'voice_dir /var/spool/voice'
01/14 12:13:50 yS0 conf lib: read: 'phone_owner root'
01/14 12:13:50 yS0 conf lib: read: 'phone_group root'
01/14 12:13:50 yS0 conf lib: read: 'phone_mode 0664'
01/14 12:13:50 yS0 conf lib: read: 'message_flag_file .flag'
01/14 12:13:50 yS0 conf lib: read: 'receive_dir incoming'
01/14 12:13:50 yS0 conf lib: read: 'message_dir messages'
01/14 12:13:50 yS0 conf lib: read: 'message_list Index'
01/14 12:13:50 yS0 conf lib: read: 'backup_message standard.rmd'
01/14 12:13:50 yS0 conf lib: read: 'port_speed 115200'
01/14 12:13:50 yS0 conf lib: read: 'voice_shell /bin/sh'
01/14 12:13:50 yS0 conf lib: read: 'port_timeout 20'
01/14 12:13:50 yS0 conf lib: read: 'dial_timeout 90'
01/14 12:13:50 yS0 conf lib: read: 'command_delay 100'
01/14 12:13:50 yS0 conf lib: read: 'dtmf_len 100'
01/14 12:13:50 yS0 conf lib: read: 'dtmf_threshold 40'
01/14 12:13:50 yS0 conf lib: read: 'dtmf_wait 9'
01/14 12:13:50 yS0 conf lib: read: 'ignore_fax_dle false'
01/14 12:13:50 yS0 conf lib: read: 'raw_data false'
01/14 12:13:50 yS0 conf lib: read: 'rec_compression 0'
01/14 12:13:50 yS0 conf lib: read: 'rec_speed 0'
01/14 12:13:50 yS0 conf lib: read: 'rec_silence_len 70'
01/14 12:13:50 yS0 conf lib: read: 'rec_silence_threshold 70'
01/14 12:13:50 yS0 conf lib: read: 'rec_remove_silence false'
01/14 12:13:50 yS0 conf lib: read: 'rec_max_len 300'
01/14 12:13:50 yS0 conf lib: read: 'rec_min_len 0'
01/14 12:13:50 yS0 conf lib: read: 'do_hard_flow true'
01/14 12:13:50 yS0 conf lib: read: 'beep_frequency 999'
01/14 12:13:50 yS0 conf lib: read: 'beep_length 1500'
01/14 12:13:50 yS0 conf lib: read: 'max_tries 3'
01/14 12:13:50 yS0 conf lib: read: 'retry_delay 3'
01/14 12:13:50 yS0 conf lib: read: 'watchdog_timeout 60'
01/14 12:13:50 yS0 conf lib: read: 'receive_gain 100%'
01/14 12:13:50 yS0 conf lib: read: 'transmit_gain 100%'
01/14 12:13:50 yS0 conf lib: read: 'enable_command_echo true'
01/14 12:13:50 yS0 conf lib: read: 'poll_interval 13'
01/14 12:13:50 yS0 conf lib: read: 'program vgetty'
01/14 12:13:50 yS0 section: program vgetty, **found**
01/14 12:13:50 yS0 conf lib: read: 'rings 6'
01/14 12:13:50 yS0 conf lib: read: 'answer_mode voice:fax:data'
01/14 12:13:50 yS0 conf lib: read: 'force_autodetect false'
01/14 12:13:50 yS0 conf lib: read: 'toll_saver_rings 0'
01/14 12:13:50 yS0 conf lib: read: 'rec_always_keep true'
01/14 12:13:50 yS0 conf lib: read: 'button_program '
01/14 12:13:50 yS0 conf lib: read: 'call_program '
01/14 12:13:50 yS0 conf lib: read: 'dtmf_program dtmf.sh'
01/14 12:13:50 yS0 conf lib: read: 'message_program '
01/14 12:13:50 yS0 conf lib: read: 'do_message_light false'
01/14 12:13:50 yS0 conf lib: read: 'program vm'
01/14 12:13:50 yS0 section: program vm, ignore
01/14 12:13:50 yS0 conf lib: read: 'voice_devices ttyS0'
01/14 12:13:50 yS0 conf lib: read: 'dialout_timeout 90'
01/14 12:13:50 yS0 conf lib: read: 'program pvf'
01/14 12:13:50 yS0 section: program pvf, ignore
01/14 12:13:50 yS0 key: 'part', type=6, flags=4, data=(ignored)
01/14 12:13:50 yS0 key: 'program', type=6, flags=4, data=(ignored)
01/14 12:13:50 yS0 key: 'port', type=6, flags=4, data=(ignored)
01/14 12:13:50 yS0 key: 'ring_type', type=6, flags=4, data=(ignored)
01/14 12:13:50 yS0 key: 'voice_log_level', type=0, flags=3, data=6
01/14 12:13:50 yS0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
01/14 12:13:50 yS0 key: 'port_speed', type=0, flags=3, data=115200
01/14 12:13:50 yS0 key: 'port_timeout', type=0, flags=3, data=20
01/14 12:13:50 yS0 key: 'dial_timeout', type=0, flags=3, data=90
01/14 12:13:50 yS0 key: 'command_delay', type=0, flags=3, data=100
01/14 12:13:50 yS0 key: 'dtmf_len', type=0, flags=3, data=100
01/14 12:13:50 yS0 key: 'dtmf_threshold', type=0, flags=3, data=40
01/14 12:13:50 yS0 key: 'dtmf_wait', type=0, flags=3, data=9
01/14 12:13:50 yS0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'raw_data', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'rec_compression', type=0, flags=3, data=0
01/14 12:13:50 yS0 key: 'rec_speed', type=0, flags=3, data=0
01/14 12:13:50 yS0 key: 'rec_silence_len', type=0, flags=3, data=70
01/14 12:13:50 yS0 key: 'rec_silence_threshold', type=0, flags=3, data=70
01/14 12:13:50 yS0 key: 'rec_remove_silence', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'rec_max_len', type=0, flags=3, data=300
01/14 12:13:50 yS0 key: 'rec_min_len', type=0, flags=3, data=0
01/14 12:13:50 yS0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
01/14 12:13:50 yS0 key: 'force_autodetect', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'watchdog_timeout', type=0, flags=3, data=60
01/14 12:13:50 yS0 key: 'receive_gain', type=0, flags=3, data=100
01/14 12:13:50 yS0 key: 'transmit_gain', type=0, flags=3, data=100
01/14 12:13:50 yS0 key: 'enable_command_echo', type=3, flags=3, data=TRUE
01/14 12:13:50 yS0 key: 'poll_interval', type=0, flags=3, data=13
01/14 12:13:50 yS0 key: 'rings', type=1, flags=3, data=6
01/14 12:13:50 yS0 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
01/14 12:13:50 yS0 key: 'toll_saver_rings', type=0, flags=3, data=0
01/14 12:13:50 yS0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
01/14 12:13:50 yS0 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
01/14 12:13:50 yS0 key: 'phone_owner', type=1, flags=3, data=root
01/14 12:13:50 yS0 key: 'phone_group', type=1, flags=3, data=root
01/14 12:13:50 yS0 key: 'phone_mode', type=0, flags=3, data=436
01/14 12:13:50 yS0 key: 'message_flag_file', type=1, flags=3, data=.flag
01/14 12:13:50 yS0 key: 'receive_dir', type=1, flags=3, data=incoming
01/14 12:13:50 yS0 key: 'message_dir', type=1, flags=3, data=messages
01/14 12:13:50 yS0 key: 'message_list', type=1, flags=3, data=Index
01/14 12:13:50 yS0 key: 'backup_message', type=1, flags=3, data=standard.rmd
01/14 12:13:50 yS0 key: 'button_program', type=1, flags=3, data=
01/14 12:13:50 yS0 key: 'call_program', type=1, flags=3, data=
01/14 12:13:50 yS0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
01/14 12:13:50 yS0 key: 'message_program', type=1, flags=3, data=
01/14 12:13:50 yS0 key: 'do_message_light', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'beep_frequency', type=0, flags=3, data=999
01/14 12:13:50 yS0 key: 'beep_length', type=0, flags=3, data=1500
01/14 12:13:50 yS0 key: 'max_tries', type=0, flags=3, data=3
01/14 12:13:50 yS0 key: 'retry_delay', type=0, flags=3, data=3
01/14 12:13:50 yS0 key: 'dialout_timeout', type=0, flags=1, data=90
01/14 12:13:50 yS0 key: 'ringback_goes_away', type=0, flags=1, data=70
01/14 12:13:50 yS0 key: 'ringback_never_came', type=0, flags=1, data=100
01/14 12:13:50 yS0 key: 'voice_devices', type=1, flags=1, data=
01/14 12:13:50 yS0 reading port ttyS0 configuration from config file /etc/mgetty+sendfax/voice.conf
01/14 12:13:50 yS0 reading /etc/mgetty+sendfax/voice.conf...
01/14 12:13:50 yS0 conf lib: read: 'part generic'
01/14 12:13:50 yS0 found CT_KEYWORD part generic
01/14 12:13:50 yS0 conf lib: read: 'voice_log_level 6'
01/14 12:13:50 yS0 conf lib: read: 'voice_dir /var/spool/voice'
01/14 12:13:50 yS0 conf lib: read: 'phone_owner root'
01/14 12:13:50 yS0 conf lib: read: 'phone_group root'
01/14 12:13:50 yS0 conf lib: read: 'phone_mode 0664'
01/14 12:13:50 yS0 conf lib: read: 'message_flag_file .flag'
01/14 12:13:50 yS0 conf lib: read: 'receive_dir incoming'
01/14 12:13:50 yS0 conf lib: read: 'message_dir messages'
01/14 12:13:50 yS0 conf lib: read: 'message_list Index'
01/14 12:13:50 yS0 conf lib: read: 'backup_message standard.rmd'
01/14 12:13:50 yS0 conf lib: read: 'port_speed 115200'
01/14 12:13:50 yS0 conf lib: read: 'voice_shell /bin/sh'
01/14 12:13:50 yS0 conf lib: read: 'port_timeout 20'
01/14 12:13:50 yS0 conf lib: read: 'dial_timeout 90'
01/14 12:13:50 yS0 conf lib: read: 'command_delay 100'
01/14 12:13:50 yS0 conf lib: read: 'dtmf_len 100'
01/14 12:13:50 yS0 conf lib: read: 'dtmf_threshold 40'
01/14 12:13:50 yS0 conf lib: read: 'dtmf_wait 9'
01/14 12:13:50 yS0 conf lib: read: 'ignore_fax_dle false'
01/14 12:13:50 yS0 conf lib: read: 'raw_data false'
01/14 12:13:50 yS0 conf lib: read: 'rec_compression 0'
01/14 12:13:50 yS0 conf lib: read: 'rec_speed 0'
01/14 12:13:50 yS0 conf lib: read: 'rec_silence_len 70'
01/14 12:13:50 yS0 conf lib: read: 'rec_silence_threshold 70'
01/14 12:13:50 yS0 conf lib: read: 'rec_remove_silence false'
01/14 12:13:50 yS0 conf lib: read: 'rec_max_len 300'
01/14 12:13:50 yS0 conf lib: read: 'rec_min_len 0'
01/14 12:13:50 yS0 conf lib: read: 'do_hard_flow true'
01/14 12:13:50 yS0 conf lib: read: 'beep_frequency 999'
01/14 12:13:50 yS0 conf lib: read: 'beep_length 1500'
01/14 12:13:50 yS0 conf lib: read: 'max_tries 3'
01/14 12:13:50 yS0 conf lib: read: 'retry_delay 3'
01/14 12:13:50 yS0 conf lib: read: 'watchdog_timeout 60'
01/14 12:13:50 yS0 conf lib: read: 'receive_gain 100%'
01/14 12:13:50 yS0 conf lib: read: 'transmit_gain 100%'
01/14 12:13:50 yS0 conf lib: read: 'enable_command_echo true'
01/14 12:13:50 yS0 conf lib: read: 'poll_interval 13'
01/14 12:13:50 yS0 conf lib: read: 'program vgetty'
01/14 12:13:50 yS0 conf lib: read: 'rings 6'
01/14 12:13:50 yS0 conf lib: read: 'answer_mode voice:fax:data'
01/14 12:13:50 yS0 conf lib: read: 'force_autodetect false'
01/14 12:13:50 yS0 conf lib: read: 'toll_saver_rings 0'
01/14 12:13:50 yS0 conf lib: read: 'rec_always_keep true'
01/14 12:13:50 yS0 conf lib: read: 'button_program '
01/14 12:13:50 yS0 conf lib: read: 'call_program '
01/14 12:13:50 yS0 conf lib: read: 'dtmf_program dtmf.sh'
01/14 12:13:50 yS0 conf lib: read: 'message_program '
01/14 12:13:50 yS0 conf lib: read: 'do_message_light false'
01/14 12:13:50 yS0 conf lib: read: 'program vm'
01/14 12:13:50 yS0 conf lib: read: 'voice_devices ttyS0'
01/14 12:13:50 yS0 conf lib: read: 'dialout_timeout 90'
01/14 12:13:50 yS0 conf lib: read: 'program pvf'
01/14 12:13:50 yS0 key: 'part', type=6, flags=4, data=(ignored)
01/14 12:13:50 yS0 key: 'program', type=6, flags=4, data=(ignored)
01/14 12:13:50 yS0 key: 'port', type=6, flags=4, data=(ignored)
01/14 12:13:50 yS0 key: 'ring_type', type=6, flags=4, data=(ignored)
01/14 12:13:50 yS0 key: 'voice_log_level', type=0, flags=3, data=6
01/14 12:13:50 yS0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
01/14 12:13:50 yS0 key: 'port_speed', type=0, flags=3, data=115200
01/14 12:13:50 yS0 key: 'port_timeout', type=0, flags=3, data=20
01/14 12:13:50 yS0 key: 'dial_timeout', type=0, flags=3, data=90
01/14 12:13:50 yS0 key: 'command_delay', type=0, flags=3, data=100
01/14 12:13:50 yS0 key: 'dtmf_len', type=0, flags=3, data=100
01/14 12:13:50 yS0 key: 'dtmf_threshold', type=0, flags=3, data=40
01/14 12:13:50 yS0 key: 'dtmf_wait', type=0, flags=3, data=9
01/14 12:13:50 yS0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'raw_data', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'rec_compression', type=0, flags=3, data=0
01/14 12:13:50 yS0 key: 'rec_speed', type=0, flags=3, data=0
01/14 12:13:50 yS0 key: 'rec_silence_len', type=0, flags=3, data=70
01/14 12:13:50 yS0 key: 'rec_silence_threshold', type=0, flags=3, data=70
01/14 12:13:50 yS0 key: 'rec_remove_silence', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'rec_max_len', type=0, flags=3, data=300
01/14 12:13:50 yS0 key: 'rec_min_len', type=0, flags=3, data=0
01/14 12:13:50 yS0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
01/14 12:13:50 yS0 key: 'force_autodetect', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'watchdog_timeout', type=0, flags=3, data=60
01/14 12:13:50 yS0 key: 'receive_gain', type=0, flags=3, data=100
01/14 12:13:50 yS0 key: 'transmit_gain', type=0, flags=3, data=100
01/14 12:13:50 yS0 key: 'enable_command_echo', type=3, flags=3, data=TRUE
01/14 12:13:50 yS0 key: 'poll_interval', type=0, flags=3, data=13
01/14 12:13:50 yS0 key: 'rings', type=1, flags=3, data=6
01/14 12:13:50 yS0 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
01/14 12:13:50 yS0 key: 'toll_saver_rings', type=0, flags=3, data=0
01/14 12:13:50 yS0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
01/14 12:13:50 yS0 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
01/14 12:13:50 yS0 key: 'phone_owner', type=1, flags=3, data=root
01/14 12:13:50 yS0 key: 'phone_group', type=1, flags=3, data=root
01/14 12:13:50 yS0 key: 'phone_mode', type=0, flags=3, data=436
01/14 12:13:50 yS0 key: 'message_flag_file', type=1, flags=3, data=.flag
01/14 12:13:50 yS0 key: 'receive_dir', type=1, flags=3, data=incoming
01/14 12:13:50 yS0 key: 'message_dir', type=1, flags=3, data=messages
01/14 12:13:50 yS0 key: 'message_list', type=1, flags=3, data=Index
01/14 12:13:50 yS0 key: 'backup_message', type=1, flags=3, data=standard.rmd
01/14 12:13:50 yS0 key: 'button_program', type=1, flags=3, data=
01/14 12:13:50 yS0 key: 'call_program', type=1, flags=3, data=
01/14 12:13:50 yS0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
01/14 12:13:50 yS0 key: 'message_program', type=1, flags=3, data=
01/14 12:13:50 yS0 key: 'do_message_light', type=3, flags=3, data=FALSE
01/14 12:13:50 yS0 key: 'beep_frequency', type=0, flags=3, data=999
01/14 12:13:50 yS0 key: 'beep_length', type=0, flags=3, data=1500
01/14 12:13:50 yS0 key: 'max_tries', type=0, flags=3, data=3
01/14 12:13:50 yS0 key: 'retry_delay', type=0, flags=3, data=3
01/14 12:13:50 yS0 key: 'dialout_timeout', type=0, flags=1, data=90
01/14 12:13:50 yS0 key: 'ringback_goes_away', type=0, flags=1, data=70
01/14 12:13:50 yS0 key: 'ringback_never_came', type=0, flags=1, data=100
01/14 12:13:50 yS0 key: 'voice_devices', type=1, flags=1, data=
01/14 12:13:50 yS0 check for lockfiles
01/14 12:13:50 yS0 checklock: stat failed, no file
01/14 12:13:50 yS0 locking the line
01/14 12:13:50 yS0 makelock(ttyS0) called
01/14 12:13:50 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
01/14 12:13:50 yS0 lock made
01/14 12:13:50 yS0 can't get group 'modem': No such file or directory
01/14 12:13:51 yS0 tio_get_rs232_lines: status: RTS CTS DSR DTR
01/14 12:13:51 yS0 lowering DTR to reset Modem
01/14 12:13:51 yS0 tss: set speed to 38400 (017)
01/14 12:13:51 yS0 tio_set_flow_control( HARD )
01/14 12:13:51 yS0 waiting for line to clear (VTIME), read:
01/14 12:13:52 yS0 send: \dATQ0V1H0[0d]
01/14 12:13:52 yS0 waiting for ``OK''
01/14 12:13:52 yS0 got: ATQ0V1H0[0d]
01/14 12:13:52 yS0 CND: ATQ0V1H0[0d][0a]OK ** found **
01/14 12:13:52 yS0 send: ATS0=0Q0&D3&C1[0d]
01/14 12:13:52 yS0 waiting for ``OK''
01/14 12:13:52 yS0 got: [0d]
01/14 12:13:52 yS0 CND: OK[0a]ATS0=0Q0&D3&C1[0d]
01/14 12:13:52 yS0 CND: ATS0=0Q0&D3&C1[0d][0a]OK ** found **
01/14 12:13:53 yS0 mdm_send: 'ATI'
01/14 12:13:53 yS0 got:[0d][0a]ATI[0d]
01/14 12:13:53 yS0 got:[0d][0a]5601[0d]
01/14 12:13:53 yS0 mdm_gis: string 1: '5601'
01/14 12:13:53 yS0 got:[0a][0d][0a]OK[0d]
01/14 12:13:53 yS0 mdm_identify: string '5601'
01/14 12:13:53 yS0 USR Courier/Sportster 56k detected
01/14 12:13:53 yS0 mdm_send: 'ATI3'
01/14 12:13:53 yS0 got:[0a]ATI3[0d]
01/14 12:13:53 yS0 got:[0d][0a]U.S. Robotics 56K Voice EXT Rev. 12.1.0[0d]
01/14 12:13:53 yS0 mdm_gis: string 1: 'U.S. Robotics 56K Voice EXT Rev. 12.1.0'
01/14 12:13:53 yS0 got:[0a][0d][0a]OK[0d]
01/14 12:13:53 yS0 additional info: 'U.S. Robotics 56K Voice EXT Rev. 12.1.0'
01/14 12:13:53 yS0 mdm_send: 'AT+FCLASS=2.0'
01/14 12:13:53 yS0 got:[0a]AT+FCLASS=2.0[0d]
01/14 12:13:53 yS0 mdm_command: string 'AT+FCLASS=2.0'
01/14 12:13:53 yS0 got:[0d][0a]OK[0d]
01/14 12:13:53 yS0 mdm_command: string 'OK' -> OK
01/14 12:13:53 yS0 mdm_send: 'AT+FAA=1;+FCR=1'
01/14 12:13:53 yS0 got:[0a]AT+FAA=1;+FCR=1[0d]
01/14 12:13:53 yS0 mdm_command: string 'AT+FAA=1;+FCR=1'
01/14 12:13:53 yS0 got:[0d][0a]OK[0d]
01/14 12:13:53 yS0 mdm_command: string 'OK' -> OK
01/14 12:13:53 yS0 mdm_send: 'AT+FBO=1'
01/14 12:13:53 yS0 got:[0a]AT+FBO=1[0d]
01/14 12:13:53 yS0 mdm_command: string 'AT+FBO=1'
01/14 12:13:53 yS0 got:[0d][0a]OK[0d]
01/14 12:13:53 yS0 mdm_command: string 'OK' -> OK
01/14 12:13:54 yS0 mdm_send: 'AT+FNR=1,1,1,0'
01/14 12:13:54 yS0 got:[0a]AT+FNR=1,1,1,0[0d]
01/14 12:13:54 yS0 mdm_command: string 'AT+FNR=1,1,1,0'
01/14 12:13:54 yS0 got:[0d][0a]OK[0d]
01/14 12:13:54 yS0 mdm_command: string 'OK' -> OK
01/14 12:13:54 yS0 mdm_send: 'AT+FLI="49 115 xxxxxxxx"'
01/14 12:13:54 yS0 got:[0a]AT+FLI="49 115 xxxxxxxx"[0d]
01/14 12:13:54 yS0 mdm_command: string 'AT+FLI="49 115 xxxxxxxx"'
01/14 12:13:54 yS0 got:[0d][0a]OK[0d]
01/14 12:13:54 yS0 mdm_command: string 'OK' -> OK
01/14 12:13:54 yS0 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
01/14 12:13:54 yS0 got:[0a]AT+FCC=1,5,0,2,0,0,0,0[0d]
01/14 12:13:54 yS0 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
01/14 12:13:54 yS0 got:[0d][0a]OK[0d]
01/14 12:13:54 yS0 mdm_command: string 'OK' -> OK
01/14 12:13:54 yS0 tss: set speed to 115200 (10002)
01/14 12:13:54 yS0 tio_set_flow_control( HARD )
01/14 12:13:54 yS0 detecting voice modem type
01/14 12:13:54 yS0 vgetty: ATE1
01/14 12:13:54 yS0 voice command: '' -> 'OK|ATE1'
01/14 12:13:54 yS0 serial port: ATE1
01/14 12:13:54 yS0 serial port: OK
01/14 12:13:54 yS0 voice command: 'ATI9' -> ''
01/14 12:13:54 yS0 vgetty: ATI9
01/14 12:13:54 yS0 serial port: ATI9
01/14 12:13:54 yS0 serial port: (1.0USR9180\\Modem\PNPC107\U.S. Robotics 56K Voice EXT)FF
01/14 12:13:55 yS0 serial port: OK
01/14 12:13:55 yS0 voice command: 'ATI' -> ''
01/14 12:13:55 yS0 vgetty: ATI
01/14 12:13:55 yS0 serial port: ATI
01/14 12:13:55 yS0 serial port: 5601
01/14 12:13:55 yS0 US Robotics: OK
01/14 12:13:55 yS0 US Robotics detected
01/14 12:13:56 yS0 vgetty: entering voice mode
01/14 12:13:56 yS0 vgetty: Installing signal handlers
01/14 12:13:56 yS0 voice command: 'AT#CLS?' -> ''
01/14 12:13:56 yS0 vgetty: AT#CLS?
01/14 12:13:56 yS0 US Robotics: AT#CLS?
01/14 12:13:56 yS0 US Robotics: 2.0
01/14 12:13:56 yS0 voice command: '' -> 'OK'
01/14 12:13:56 yS0 US Robotics: OK
01/14 12:13:56 yS0 voice command: 'AT#CLS=8' -> 'OK'
01/14 12:13:56 yS0 vgetty: AT#CLS=8
01/14 12:13:56 yS0 US Robotics: AT#CLS=8
01/14 12:13:56 yS0 US Robotics: OK
01/14 12:13:56 yS0 voice command: 'AT' -> 'OK'
01/14 12:13:56 yS0 vgetty: AT
01/14 12:13:56 yS0 US Robotics: AT
01/14 12:13:56 yS0 US Robotics: OK
01/14 12:13:56 yS0 voice command: 'AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100' -> 'OK'
01/14 12:13:56 yS0 vgetty: AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/14 12:13:56 yS0 US Robotics: AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/14 12:13:57 yS0 US Robotics: OK
01/14 12:13:57 yS0 vgetty: queued event RESET_WATCHDOG at position 0000
01/14 12:13:57 yS0 US Robotics voice modem
01/14 12:13:57 yS0 This is a driver beta version. V0.4.b3
01/14 12:13:57 yS0 voice command: 'AT&H1&R2&I0' -> 'OK'
01/14 12:13:57 yS0 vgetty: AT&H1&R2&I0
01/14 12:13:57 yS0 vgetty: unqueued event RESET_WATCHDOG at position 0000
01/14 12:13:57 yS0 vgetty: voice_handle_event got event RESET_WATCHDOG with data <NUL>
01/14 12:13:57 yS0 US Robotics: AT&H1&R2&I0
01/14 12:13:57 yS0 US Robotics: OK
01/14 12:13:57 yS0 tio_set_flow_control( HARD )
01/14 12:13:57 yS0 voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
01/14 12:13:57 yS0 vgetty: AT#VTD=3F,3F,3F
01/14 12:13:57 yS0 US Robotics: AT#VTD=3F,3F,3F
01/14 12:13:57 yS0 US Robotics: OK
01/14 12:13:57 yS0 VTD setup successful
01/14 12:13:57 yS0 voice command: 'AT#VSD=1#VSS=2#VSP=70' -> 'OK'
01/14 12:13:57 yS0 vgetty: AT#VSD=1#VSS=2#VSP=70
01/14 12:13:57 yS0 US Robotics: AT#VSD=1#VSS=2#VSP=70
01/14 12:13:57 yS0 US Robotics: OK
01/14 12:13:57 yS0 voice command: 'AT#VRA=70#VRN=100' -> 'OK'
01/14 12:13:57 yS0 vgetty: AT#VRA=70#VRN=100
01/14 12:13:57 yS0 US Robotics: AT#VRA=70#VRN=100
01/14 12:13:58 yS0 US Robotics: OK
01/14 12:13:58 yS0 vgetty: leaving voice mode
01/14 12:13:58 yS0 voice command: 'AT#CLS=2.0' -> 'OK'
01/14 12:13:58 yS0 vgetty: AT#CLS=2.0
01/14 12:13:58 yS0 US Robotics: AT#CLS=2.0
01/14 12:13:58 yS0 US Robotics: OK
01/14 12:13:58 yS0 voice command: 'AT' -> 'OK'
01/14 12:13:58 yS0 vgetty: AT
01/14 12:13:58 yS0 US Robotics: AT
01/14 12:13:58 yS0 US Robotics: OK
01/14 12:13:58 yS0 vgetty: Restoring signal handlers
01/14 12:13:58 yS0 waiting for line to clear (VTIME), read:
01/14 12:13:58 yS0 removing lock file
01/14 12:13:58 yS0 waiting...
01/14 12:24:34 yS0 select returned 1
01/14 12:24:34 yS0 checking lockfiles, locking the line
01/14 12:24:34 yS0 makelock(ttyS0) called
01/14 12:24:34 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
01/14 12:24:34 yS0 lock made
01/14 12:24:34 yS0 vgetty: number of rings (6) was set directly
01/14 12:24:34 yS0 vgetty: decremented number of rings (6) by 0
01/14 12:24:34 yS0 wfr: waiting for ``RING''
01/14 12:24:34 yS0 got: [0a]CS[f3]CS[f3]CS[f3]
01/14 12:24:44 yS0 mdm_read_byte: read returned -1: Interrupted system call
01/14 12:24:44 yS0 wfr: timeout waiting for RING
01/14 12:24:44 yS0 wfr: rc=-1, drn=0
01/14 12:24:44 yS0 huh? Junk on the line?
01/14 12:24:44 yS0 removing lock file
--
01/14 12:24:44 yS0 vgetty: experimental test release 0.9.9 / 13Nov99
01/14 12:24:44 yS0 mgetty: experimental test release 1.1.21-Jul24
01/14 12:24:44 yS0 reading generic configuration from config file /etc/mgetty+sendfax/voice.conf
01/14 12:24:44 yS0 reading program vgetty configuration from config file /etc/mgetty+sendfax/voice.conf
01/14 12:24:44 yS0 reading /etc/mgetty+sendfax/voice.conf...
01/14 12:24:44 yS0 conf lib: read: 'part generic'
01/14 12:24:44 yS0 found CT_KEYWORD part generic
01/14 12:24:44 yS0 conf lib: read: 'voice_log_level 6'
01/14 12:24:44 yS0 conf lib: read: 'voice_dir /var/spool/voice'
01/14 12:24:44 yS0 conf lib: read: 'phone_owner root'
01/14 12:24:44 yS0 conf lib: read: 'phone_group root'
01/14 12:24:44 yS0 conf lib: read: 'phone_mode 0664'
01/14 12:24:44 yS0 conf lib: read: 'message_flag_file .flag'
01/14 12:24:44 yS0 conf lib: read: 'receive_dir incoming'
01/14 12:24:44 yS0 conf lib: read: 'message_dir messages'
01/14 12:24:44 yS0 conf lib: read: 'message_list Index'
01/14 12:24:44 yS0 conf lib: read: 'backup_message standard.rmd'
01/14 12:24:44 yS0 conf lib: read: 'port_speed 115200'
01/14 12:24:44 yS0 conf lib: read: 'voice_shell /bin/sh'
01/14 12:24:44 yS0 conf lib: read: 'port_timeout 20'
01/14 12:24:44 yS0 conf lib: read: 'dial_timeout 90'
01/14 12:24:44 yS0 conf lib: read: 'command_delay 100'
01/14 12:24:44 yS0 conf lib: read: 'dtmf_len 100'
01/14 12:24:44 yS0 conf lib: read: 'dtmf_threshold 40'
01/14 12:24:44 yS0 conf lib: read: 'dtmf_wait 9'
01/14 12:24:44 yS0 conf lib: read: 'ignore_fax_dle false'
01/14 12:24:44 yS0 conf lib: read: 'raw_data false'
01/14 12:24:44 yS0 conf lib: read: 'rec_compression 0'
01/14 12:24:44 yS0 conf lib: read: 'rec_speed 0'
01/14 12:24:44 yS0 conf lib: read: 'rec_silence_len 70'
01/14 12:24:44 yS0 conf lib: read: 'rec_silence_threshold 70'
01/14 12:24:44 yS0 conf lib: read: 'rec_remove_silence false'
01/14 12:24:44 yS0 conf lib: read: 'rec_max_len 300'
01/14 12:24:44 yS0 conf lib: read: 'rec_min_len 0'
01/14 12:24:44 yS0 conf lib: read: 'do_hard_flow true'
01/14 12:24:44 yS0 conf lib: read: 'beep_frequency 999'
01/14 12:24:44 yS0 conf lib: read: 'beep_length 1500'
01/14 12:24:44 yS0 conf lib: read: 'max_tries 3'
01/14 12:24:44 yS0 conf lib: read: 'retry_delay 3'
01/14 12:24:44 yS0 conf lib: read: 'watchdog_timeout 60'
01/14 12:24:44 yS0 conf lib: read: 'receive_gain 100%'
01/14 12:24:44 yS0 conf lib: read: 'transmit_gain 100%'
01/14 12:24:44 yS0 conf lib: read: 'enable_command_echo true'
01/14 12:24:44 yS0 conf lib: read: 'poll_interval 13'
01/14 12:24:44 yS0 conf lib: read: 'program vgetty'
01/14 12:24:44 yS0 section: program vgetty, **found**
01/14 12:24:44 yS0 conf lib: read: 'rings 6'
01/14 12:24:44 yS0 conf lib: read: 'answer_mode voice:fax:data'
01/14 12:24:44 yS0 conf lib: read: 'force_autodetect false'
01/14 12:24:44 yS0 conf lib: read: 'toll_saver_rings 0'
01/14 12:24:44 yS0 conf lib: read: 'rec_always_keep true'
01/14 12:24:44 yS0 conf lib: read: 'button_program '
01/14 12:24:44 yS0 conf lib: read: 'call_program '
01/14 12:24:44 yS0 conf lib: read: 'dtmf_program dtmf.sh'
01/14 12:24:44 yS0 conf lib: read: 'message_program '
01/14 12:24:44 yS0 conf lib: read: 'do_message_light false'
01/14 12:24:44 yS0 conf lib: read: 'program vm'
01/14 12:24:44 yS0 section: program vm, ignore
01/14 12:24:44 yS0 conf lib: read: 'voice_devices ttyS0'
01/14 12:24:44 yS0 conf lib: read: 'dialout_timeout 90'
01/14 12:24:44 yS0 conf lib: read: 'program pvf'
01/14 12:24:44 yS0 section: program pvf, ignore
01/14 12:24:44 yS0 key: 'part', type=6, flags=4, data=(ignored)
01/14 12:24:44 yS0 key: 'program', type=6, flags=4, data=(ignored)
01/14 12:24:44 yS0 key: 'port', type=6, flags=4, data=(ignored)
01/14 12:24:44 yS0 key: 'ring_type', type=6, flags=4, data=(ignored)
01/14 12:24:44 yS0 key: 'voice_log_level', type=0, flags=3, data=6
01/14 12:24:44 yS0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
01/14 12:24:44 yS0 key: 'port_speed', type=0, flags=3, data=115200
01/14 12:24:44 yS0 key: 'port_timeout', type=0, flags=3, data=20
01/14 12:24:44 yS0 key: 'dial_timeout', type=0, flags=3, data=90
01/14 12:24:44 yS0 key: 'command_delay', type=0, flags=3, data=100
01/14 12:24:44 yS0 key: 'dtmf_len', type=0, flags=3, data=100
01/14 12:24:44 yS0 key: 'dtmf_threshold', type=0, flags=3, data=40
01/14 12:24:44 yS0 key: 'dtmf_wait', type=0, flags=3, data=9
01/14 12:24:44 yS0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'raw_data', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'rec_compression', type=0, flags=3, data=0
01/14 12:24:44 yS0 key: 'rec_speed', type=0, flags=3, data=0
01/14 12:24:44 yS0 key: 'rec_silence_len', type=0, flags=3, data=70
01/14 12:24:44 yS0 key: 'rec_silence_threshold', type=0, flags=3, data=70
01/14 12:24:44 yS0 key: 'rec_remove_silence', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'rec_max_len', type=0, flags=3, data=300
01/14 12:24:44 yS0 key: 'rec_min_len', type=0, flags=3, data=0
01/14 12:24:44 yS0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
01/14 12:24:44 yS0 key: 'force_autodetect', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'watchdog_timeout', type=0, flags=3, data=60
01/14 12:24:44 yS0 key: 'receive_gain', type=0, flags=3, data=100
01/14 12:24:44 yS0 key: 'transmit_gain', type=0, flags=3, data=100
01/14 12:24:44 yS0 key: 'enable_command_echo', type=3, flags=3, data=TRUE
01/14 12:24:44 yS0 key: 'poll_interval', type=0, flags=3, data=13
01/14 12:24:44 yS0 key: 'rings', type=1, flags=3, data=6
01/14 12:24:44 yS0 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
01/14 12:24:44 yS0 key: 'toll_saver_rings', type=0, flags=3, data=0
01/14 12:24:44 yS0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
01/14 12:24:44 yS0 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
01/14 12:24:44 yS0 key: 'phone_owner', type=1, flags=3, data=root
01/14 12:24:44 yS0 key: 'phone_group', type=1, flags=3, data=root
01/14 12:24:44 yS0 key: 'phone_mode', type=0, flags=3, data=436
01/14 12:24:44 yS0 key: 'message_flag_file', type=1, flags=3, data=.flag
01/14 12:24:44 yS0 key: 'receive_dir', type=1, flags=3, data=incoming
01/14 12:24:44 yS0 key: 'message_dir', type=1, flags=3, data=messages
01/14 12:24:44 yS0 key: 'message_list', type=1, flags=3, data=Index
01/14 12:24:44 yS0 key: 'backup_message', type=1, flags=3, data=standard.rmd
01/14 12:24:44 yS0 key: 'button_program', type=1, flags=3, data=
01/14 12:24:44 yS0 key: 'call_program', type=1, flags=3, data=
01/14 12:24:44 yS0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
01/14 12:24:44 yS0 key: 'message_program', type=1, flags=3, data=
01/14 12:24:44 yS0 key: 'do_message_light', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'beep_frequency', type=0, flags=3, data=999
01/14 12:24:44 yS0 key: 'beep_length', type=0, flags=3, data=1500
01/14 12:24:44 yS0 key: 'max_tries', type=0, flags=3, data=3
01/14 12:24:44 yS0 key: 'retry_delay', type=0, flags=3, data=3
01/14 12:24:44 yS0 key: 'dialout_timeout', type=0, flags=1, data=90
01/14 12:24:44 yS0 key: 'ringback_goes_away', type=0, flags=1, data=70
01/14 12:24:44 yS0 key: 'ringback_never_came', type=0, flags=1, data=100
01/14 12:24:44 yS0 key: 'voice_devices', type=1, flags=1, data=
01/14 12:24:44 yS0 reading port ttyS0 configuration from config file /etc/mgetty+sendfax/voice.conf
01/14 12:24:44 yS0 reading /etc/mgetty+sendfax/voice.conf...
01/14 12:24:44 yS0 conf lib: read: 'part generic'
01/14 12:24:44 yS0 found CT_KEYWORD part generic
01/14 12:24:44 yS0 conf lib: read: 'voice_log_level 6'
01/14 12:24:44 yS0 conf lib: read: 'voice_dir /var/spool/voice'
01/14 12:24:44 yS0 conf lib: read: 'phone_owner root'
01/14 12:24:44 yS0 conf lib: read: 'phone_group root'
01/14 12:24:44 yS0 conf lib: read: 'phone_mode 0664'
01/14 12:24:44 yS0 conf lib: read: 'message_flag_file .flag'
01/14 12:24:44 yS0 conf lib: read: 'receive_dir incoming'
01/14 12:24:44 yS0 conf lib: read: 'message_dir messages'
01/14 12:24:44 yS0 conf lib: read: 'message_list Index'
01/14 12:24:44 yS0 conf lib: read: 'backup_message standard.rmd'
01/14 12:24:44 yS0 conf lib: read: 'port_speed 115200'
01/14 12:24:44 yS0 conf lib: read: 'voice_shell /bin/sh'
01/14 12:24:44 yS0 conf lib: read: 'port_timeout 20'
01/14 12:24:44 yS0 conf lib: read: 'dial_timeout 90'
01/14 12:24:44 yS0 conf lib: read: 'command_delay 100'
01/14 12:24:44 yS0 conf lib: read: 'dtmf_len 100'
01/14 12:24:44 yS0 conf lib: read: 'dtmf_threshold 40'
01/14 12:24:44 yS0 conf lib: read: 'dtmf_wait 9'
01/14 12:24:44 yS0 conf lib: read: 'ignore_fax_dle false'
01/14 12:24:44 yS0 conf lib: read: 'raw_data false'
01/14 12:24:44 yS0 conf lib: read: 'rec_compression 0'
01/14 12:24:44 yS0 conf lib: read: 'rec_speed 0'
01/14 12:24:44 yS0 conf lib: read: 'rec_silence_len 70'
01/14 12:24:44 yS0 conf lib: read: 'rec_silence_threshold 70'
01/14 12:24:44 yS0 conf lib: read: 'rec_remove_silence false'
01/14 12:24:44 yS0 conf lib: read: 'rec_max_len 300'
01/14 12:24:44 yS0 conf lib: read: 'rec_min_len 0'
01/14 12:24:44 yS0 conf lib: read: 'do_hard_flow true'
01/14 12:24:44 yS0 conf lib: read: 'beep_frequency 999'
01/14 12:24:44 yS0 conf lib: read: 'beep_length 1500'
01/14 12:24:44 yS0 conf lib: read: 'max_tries 3'
01/14 12:24:44 yS0 conf lib: read: 'retry_delay 3'
01/14 12:24:44 yS0 conf lib: read: 'watchdog_timeout 60'
01/14 12:24:44 yS0 conf lib: read: 'receive_gain 100%'
01/14 12:24:44 yS0 conf lib: read: 'transmit_gain 100%'
01/14 12:24:44 yS0 conf lib: read: 'enable_command_echo true'
01/14 12:24:44 yS0 conf lib: read: 'poll_interval 13'
01/14 12:24:44 yS0 conf lib: read: 'program vgetty'
01/14 12:24:44 yS0 conf lib: read: 'rings 6'
01/14 12:24:44 yS0 conf lib: read: 'answer_mode voice:fax:data'
01/14 12:24:44 yS0 conf lib: read: 'force_autodetect false'
01/14 12:24:44 yS0 conf lib: read: 'toll_saver_rings 0'
01/14 12:24:44 yS0 conf lib: read: 'rec_always_keep true'
01/14 12:24:44 yS0 conf lib: read: 'button_program '
01/14 12:24:44 yS0 conf lib: read: 'call_program '
01/14 12:24:44 yS0 conf lib: read: 'dtmf_program dtmf.sh'
01/14 12:24:44 yS0 conf lib: read: 'message_program '
01/14 12:24:44 yS0 conf lib: read: 'do_message_light false'
01/14 12:24:44 yS0 conf lib: read: 'program vm'
01/14 12:24:44 yS0 conf lib: read: 'voice_devices ttyS0'
01/14 12:24:44 yS0 conf lib: read: 'dialout_timeout 90'
01/14 12:24:44 yS0 conf lib: read: 'program pvf'
01/14 12:24:44 yS0 key: 'part', type=6, flags=4, data=(ignored)
01/14 12:24:44 yS0 key: 'program', type=6, flags=4, data=(ignored)
01/14 12:24:44 yS0 key: 'port', type=6, flags=4, data=(ignored)
01/14 12:24:44 yS0 key: 'ring_type', type=6, flags=4, data=(ignored)
01/14 12:24:44 yS0 key: 'voice_log_level', type=0, flags=3, data=6
01/14 12:24:44 yS0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
01/14 12:24:44 yS0 key: 'port_speed', type=0, flags=3, data=115200
01/14 12:24:44 yS0 key: 'port_timeout', type=0, flags=3, data=20
01/14 12:24:44 yS0 key: 'dial_timeout', type=0, flags=3, data=90
01/14 12:24:44 yS0 key: 'command_delay', type=0, flags=3, data=100
01/14 12:24:44 yS0 key: 'dtmf_len', type=0, flags=3, data=100
01/14 12:24:44 yS0 key: 'dtmf_threshold', type=0, flags=3, data=40
01/14 12:24:44 yS0 key: 'dtmf_wait', type=0, flags=3, data=9
01/14 12:24:44 yS0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'raw_data', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'rec_compression', type=0, flags=3, data=0
01/14 12:24:44 yS0 key: 'rec_speed', type=0, flags=3, data=0
01/14 12:24:44 yS0 key: 'rec_silence_len', type=0, flags=3, data=70
01/14 12:24:44 yS0 key: 'rec_silence_threshold', type=0, flags=3, data=70
01/14 12:24:44 yS0 key: 'rec_remove_silence', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'rec_max_len', type=0, flags=3, data=300
01/14 12:24:44 yS0 key: 'rec_min_len', type=0, flags=3, data=0
01/14 12:24:44 yS0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
01/14 12:24:44 yS0 key: 'force_autodetect', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'watchdog_timeout', type=0, flags=3, data=60
01/14 12:24:44 yS0 key: 'receive_gain', type=0, flags=3, data=100
01/14 12:24:44 yS0 key: 'transmit_gain', type=0, flags=3, data=100
01/14 12:24:44 yS0 key: 'enable_command_echo', type=3, flags=3, data=TRUE
01/14 12:24:44 yS0 key: 'poll_interval', type=0, flags=3, data=13
01/14 12:24:44 yS0 key: 'rings', type=1, flags=3, data=6
01/14 12:24:44 yS0 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
01/14 12:24:44 yS0 key: 'toll_saver_rings', type=0, flags=3, data=0
01/14 12:24:44 yS0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
01/14 12:24:44 yS0 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
01/14 12:24:44 yS0 key: 'phone_owner', type=1, flags=3, data=root
01/14 12:24:44 yS0 key: 'phone_group', type=1, flags=3, data=root
01/14 12:24:44 yS0 key: 'phone_mode', type=0, flags=3, data=436
01/14 12:24:44 yS0 key: 'message_flag_file', type=1, flags=3, data=.flag
01/14 12:24:44 yS0 key: 'receive_dir', type=1, flags=3, data=incoming
01/14 12:24:44 yS0 key: 'message_dir', type=1, flags=3, data=messages
01/14 12:24:44 yS0 key: 'message_list', type=1, flags=3, data=Index
01/14 12:24:44 yS0 key: 'backup_message', type=1, flags=3, data=standard.rmd
01/14 12:24:44 yS0 key: 'button_program', type=1, flags=3, data=
01/14 12:24:44 yS0 key: 'call_program', type=1, flags=3, data=
01/14 12:24:44 yS0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
01/14 12:24:44 yS0 key: 'message_program', type=1, flags=3, data=
01/14 12:24:44 yS0 key: 'do_message_light', type=3, flags=3, data=FALSE
01/14 12:24:44 yS0 key: 'beep_frequency', type=0, flags=3, data=999
01/14 12:24:44 yS0 key: 'beep_length', type=0, flags=3, data=1500
01/14 12:24:44 yS0 key: 'max_tries', type=0, flags=3, data=3
01/14 12:24:44 yS0 key: 'retry_delay', type=0, flags=3, data=3
01/14 12:24:44 yS0 key: 'dialout_timeout', type=0, flags=1, data=90
01/14 12:24:44 yS0 key: 'ringback_goes_away', type=0, flags=1, data=70
01/14 12:24:44 yS0 key: 'ringback_never_came', type=0, flags=1, data=100
01/14 12:24:44 yS0 key: 'voice_devices', type=1, flags=1, data=
01/14 12:24:44 yS0 check for lockfiles
01/14 12:24:44 yS0 checklock: stat failed, no file
01/14 12:24:44 yS0 locking the line
01/14 12:24:44 yS0 makelock(ttyS0) called
01/14 12:24:44 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
01/14 12:24:44 yS0 lock made
01/14 12:24:44 yS0 can't get group 'modem': No such file or directory
01/14 12:24:45 yS0 tio_get_rs232_lines: status: RTS CTS DSR DTR
01/14 12:24:45 yS0 lowering DTR to reset Modem
01/14 12:24:45 yS0 tss: set speed to 38400 (017)
01/14 12:24:45 yS0 tio_set_flow_control( HARD )
01/14 12:24:45 yS0 waiting for line to clear (VTIME), read:
01/14 12:24:45 yS0 send: \dATQ0V1H0[0d]
01/14 12:24:46 yS0 waiting for ``OK''
01/14 12:24:46 yS0 got: ATQ0V1H0[0d]
01/14 12:24:46 yS0 CND: ATQ0V1H0[0d][0a]OK ** found **
01/14 12:24:46 yS0 send: ATS0=0Q0&D3&C1[0d]
01/14 12:24:46 yS0 waiting for ``OK''
01/14 12:24:46 yS0 got: [0d]
01/14 12:24:46 yS0 CND: OK[0a]ATS0=0Q0&D3&C1[0d]
01/14 12:24:46 yS0 CND: ATS0=0Q0&D3&C1[0d][0a]OK ** found **
01/14 12:24:46 yS0 mdm_send: 'ATI'
01/14 12:24:46 yS0 got:[0d][0a]ATI[0d]
01/14 12:24:46 yS0 got:[0d][0a]5601[0d]
01/14 12:24:46 yS0 mdm_gis: string 1: '5601'
01/14 12:24:46 yS0 got:[0a][0d][0a]OK[0d]
01/14 12:24:46 yS0 mdm_identify: string '5601'
01/14 12:24:46 yS0 USR Courier/Sportster 56k detected
01/14 12:24:46 yS0 mdm_send: 'ATI3'
01/14 12:24:46 yS0 got:[0a]ATI3[0d]
01/14 12:24:46 yS0 got:[0d][0a]U.S. Robotics 56K Voice EXT Rev. 12.1.0[0d]
01/14 12:24:46 yS0 mdm_gis: string 1: 'U.S. Robotics 56K Voice EXT Rev. 12.1.0'
01/14 12:24:46 yS0 got:[0a][0d][0a]OK[0d]
01/14 12:24:47 yS0 additional info: 'U.S. Robotics 56K Voice EXT Rev. 12.1.0'
01/14 12:24:47 yS0 mdm_send: 'AT+FCLASS=2.0'
01/14 12:24:47 yS0 got:[0a]AT+FCLASS=2.0[0d]
01/14 12:24:47 yS0 mdm_command: string 'AT+FCLASS=2.0'
01/14 12:24:47 yS0 got:[0d][0a]OK[0d]
01/14 12:24:47 yS0 mdm_command: string 'OK' -> OK
01/14 12:24:47 yS0 mdm_send: 'AT+FAA=1;+FCR=1'
01/14 12:24:47 yS0 got:[0a]AT+FAA=1;+FCR=1[0d]
01/14 12:24:47 yS0 mdm_command: string 'AT+FAA=1;+FCR=1'
01/14 12:24:47 yS0 got:[0d][0a]OK[0d]
01/14 12:24:47 yS0 mdm_command: string 'OK' -> OK
01/14 12:24:47 yS0 mdm_send: 'AT+FBO=1'
01/14 12:24:47 yS0 got:[0a]AT+FBO=1[0d]
01/14 12:24:47 yS0 mdm_command: string 'AT+FBO=1'
01/14 12:24:47 yS0 got:[0d][0a]OK[0d]
01/14 12:24:47 yS0 mdm_command: string 'OK' -> OK
01/14 12:24:47 yS0 mdm_send: 'AT+FNR=1,1,1,0'
01/14 12:24:47 yS0 got:[0a]AT+FNR=1,1,1,0[0d]
01/14 12:24:47 yS0 mdm_command: string 'AT+FNR=1,1,1,0'
01/14 12:24:47 yS0 got:[0d][0a]OK[0d]
01/14 12:24:47 yS0 mdm_command: string 'OK' -> OK
01/14 12:24:47 yS0 mdm_send: 'AT+FLI="49 115 xxxxxxxx"'
01/14 12:24:47 yS0 got:[0a]AT+FLI="49 115 xxxxxxxx"[0d]
01/14 12:24:47 yS0 mdm_command: string 'AT+FLI="49 115 xxxxxxxx"'
01/14 12:24:47 yS0 got:[0d][0a]OK[0d]
01/14 12:24:48 yS0 mdm_command: string 'OK' -> OK
01/14 12:24:48 yS0 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
01/14 12:24:48 yS0 got:[0a]AT+FCC=1,5,0,2,0,0,0,0[0d]
01/14 12:24:48 yS0 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
01/14 12:24:48 yS0 got:[0d][0a]OK[0d]
01/14 12:24:48 yS0 mdm_command: string 'OK' -> OK
01/14 12:24:48 yS0 tss: set speed to 115200 (10002)
01/14 12:24:48 yS0 tio_set_flow_control( HARD )
01/14 12:24:48 yS0 detecting voice modem type
01/14 12:24:48 yS0 vgetty: ATE1
01/14 12:24:48 yS0 voice command: '' -> 'OK|ATE1'
01/14 12:24:48 yS0 serial port: ATE1
01/14 12:24:48 yS0 serial port: OK
01/14 12:24:48 yS0 voice command: 'ATI9' -> ''
01/14 12:24:48 yS0 vgetty: ATI9
01/14 12:24:48 yS0 serial port: ATI9
01/14 12:24:48 yS0 serial port: (1.0USR9180\\Modem\PNPC107\U.S. Robotics 56K Voice EXT)FF
01/14 12:24:49 yS0 serial port: OK
01/14 12:24:49 yS0 voice command: 'ATI' -> ''
01/14 12:24:49 yS0 vgetty: ATI
01/14 12:24:49 yS0 serial port: ATI
01/14 12:24:49 yS0 serial port: 5601
01/14 12:24:49 yS0 US Robotics: OK
01/14 12:24:49 yS0 US Robotics: RING
01/14 12:24:49 yS0 US Robotics detected
01/14 12:24:49 yS0 vgetty: entering voice mode
01/14 12:24:49 yS0 vgetty: Installing signal handlers
01/14 12:24:49 yS0 voice command: 'AT#CLS?' -> ''
01/14 12:24:49 yS0 vgetty: AT#CLS?
01/14 12:24:49 yS0 US Robotics: AT#CLS?
01/14 12:24:50 yS0 US Robotics: 2.0
01/14 12:24:50 yS0 voice command: '' -> 'OK'
01/14 12:24:50 yS0 US Robotics: OK
01/14 12:24:50 yS0 voice command: 'AT#CLS=8' -> 'OK'
01/14 12:24:50 yS0 vgetty: AT#CLS=8
01/14 12:24:50 yS0 US Robotics: AT#CLS=8
01/14 12:24:50 yS0 US Robotics: OK
01/14 12:24:50 yS0 voice command: 'AT' -> 'OK'
01/14 12:24:50 yS0 vgetty: AT
01/14 12:24:50 yS0 US Robotics: AT
01/14 12:24:50 yS0 US Robotics: OK
01/14 12:24:50 yS0 voice command: 'AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100' -> 'OK'
01/14 12:24:50 yS0 vgetty: AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/14 12:24:50 yS0 US Robotics: AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/14 12:24:50 yS0 US Robotics: OK
01/14 12:24:50 yS0 vgetty: queued event RESET_WATCHDOG at position 0000
01/14 12:24:50 yS0 US Robotics voice modem
01/14 12:24:50 yS0 This is a driver beta version. V0.4.b3
01/14 12:24:50 yS0 voice command: 'AT&H1&R2&I0' -> 'OK'
01/14 12:24:51 yS0 vgetty: AT&H1&R2&I0
01/14 12:24:51 yS0 vgetty: unqueued event RESET_WATCHDOG at position 0000
01/14 12:24:51 yS0 vgetty: voice_handle_event got event RESET_WATCHDOG with data <NUL>
01/14 12:24:51 yS0 US Robotics: AT&H1&R2&I0
01/14 12:24:51 yS0 US Robotics: OK
01/14 12:24:51 yS0 tio_set_flow_control( HARD )
01/14 12:24:51 yS0 voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
01/14 12:24:51 yS0 vgetty: AT#VTD=3F,3F,3F
01/14 12:24:51 yS0 US Robotics: AT#VTD=3F,3F,3F
01/14 12:24:51 yS0 US Robotics: OK
01/14 12:24:51 yS0 VTD setup successful
01/14 12:24:51 yS0 voice command: 'AT#VSD=1#VSS=2#VSP=70' -> 'OK'
01/14 12:24:51 yS0 vgetty: AT#VSD=1#VSS=2#VSP=70
01/14 12:24:51 yS0 US Robotics: AT#VSD=1#VSS=2#VSP=70
01/14 12:24:51 yS0 US Robotics: OK
01/14 12:24:51 yS0 voice command: 'AT#VRA=70#VRN=100' -> 'OK'
01/14 12:24:51 yS0 vgetty: AT#VRA=70#VRN=100
01/14 12:24:51 yS0 US Robotics: AT#VRA=70#VRN=100
01/14 12:24:51 yS0 US Robotics: OK
01/14 12:24:51 yS0 vgetty: leaving voice mode
01/14 12:24:51 yS0 voice command: 'AT#CLS=2.0' -> 'OK'
01/14 12:24:51 yS0 vgetty: AT#CLS=2.0
01/14 12:24:51 yS0 US Robotics: AT#CLS=2.0
01/14 12:24:52 yS0 US Robotics: OK
01/14 12:24:52 yS0 voice command: 'AT' -> 'OK'
01/14 12:24:52 yS0 vgetty: AT
01/14 12:24:52 yS0 US Robotics: AT
01/14 12:24:52 yS0 US Robotics: OK
01/14 12:24:52 yS0 vgetty: Restoring signal handlers
01/14 12:24:52 yS0 waiting for line to clear (VTIME), read:
01/14 12:24:52 yS0 removing lock file
01/14 12:24:52 yS0 waiting...
01/14 12:24:54 yS0 select returned 1
01/14 12:24:54 yS0 checking lockfiles, locking the line
01/14 12:24:54 yS0 makelock(ttyS0) called
01/14 12:24:54 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
01/14 12:24:54 yS0 lock made
01/14 12:24:54 yS0 vgetty: number of rings (6) was set directly
01/14 12:24:54 yS0 vgetty: decremented number of rings (6) by 0
01/14 12:24:54 yS0 wfr: waiting for ``RING''
01/14 12:24:54 yS0 got: [0a]CS[f3]CS[f3]CS[f3]CS[f3]CS[f3]CS[f3]CS[f3]CS[f3]CS[f3]CS[f3]
--------------2005B621C447DD910AB2EA3A--