Vgetty reports: Huh? Junk in the line?
Giovanni Biscuolo (giovanni.biscuolo@libero.it)
Sat, 22 Jan 2000 09:02:38 +0100
[due to limitation in the max number of lines I can send (2000) by
mgetty mail server,
I had to cut the log file and post this message again]
Marc SCHAEFER wrote:
> (omissis)...
>
> Ok, try to add the following to your configuration (mgetty.config),
> for the right port.
>
> init-chat "" ATZ OK \d\d\dATQ0V1H0S0=255 OK
>
> just to see if it makes anything better.
OK, I tried: things are better now, but still problematic.
The modem now alwais answers incoming calls (I tried 7 voice calls) but
with strange behavior:
first in voice mode, then fax, then voice ... and so on alternatively.
I got this situation:
1. 12:59 modem answered and recorded as aspected;
2. 13:00 modem answered in fax mode to a voice call;
3. 13:06 OK.
4. 13:07 fax answered
5. 13:09 OK
6. 13:10 fax answered
7. 13:11 OK
As you can see in log file (attached) it seems the problem is my modem
reporting a
strange (to me) return code instead of ring: +FC0 - look at 13:00:33,
13:07:40, 13:10:03
logs - and vgetty stops (?) managing the call. The strange thing is that
*something* answers
the phone in fax mode, but there is no trace in vgetty log.
Is there something wrong in my voice.config file?
Why the modem reports +FC0 while I'm sure it is a ring?
Sorry for the attached log file is very long :-( ... thank you very
much.
Ciao.
--
____________________________________
Giovanni Biscuolo
mailto: giovanni.biscuolo@libero.it
--------------3CD7632F4C99E207CE9B0C63
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/16 12:38:57 yS0 waiting...
01/16 12:58:54 yS0 select returned 1
01/16 12:58:54 yS0 checking lockfiles, locking the line
01/16 12:58:54 yS0 makelock(ttyS0) called
01/16 12:58:54 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
01/16 12:58:54 yS0 lock made
01/16 12:58:54 yS0 vgetty: number of rings (6) was set directly
01/16 12:58:54 yS0 vgetty: decremented number of rings (6) by 0
01/16 12:58:54 yS0 wfr: waiting for ``RING''
01/16 12:58:54 yS0 got: [0a][0d][0a]RING[0d]
01/16 12:58:54 yS0 CND: RING
01/16 12:58:54 yS0 wfr: rc=0, drn=0
01/16 12:58:54 yS0 wfr: waiting for ``RING''
01/16 12:58:54 yS0 got: [0a][0d][0a]RING[0d]
01/16 12:58:59 yS0 CND: RING
01/16 12:58:59 yS0 wfr: rc=0, drn=0
01/16 12:58:59 yS0 wfr: waiting for ``RING''
01/16 12:58:59 yS0 got: [0a][0d][0a]RING[0d]
01/16 12:59:04 yS0 CND: RING
01/16 12:59:04 yS0 wfr: rc=0, drn=0
01/16 12:59:04 yS0 wfr: waiting for ``RING''
01/16 12:59:04 yS0 got: [0a][0d][0a]RING[0d]
01/16 12:59:09 yS0 CND: RING
01/16 12:59:09 yS0 wfr: rc=0, drn=0
01/16 12:59:09 yS0 wfr: waiting for ``RING''
01/16 12:59:09 yS0 got: [0a][0d][0a]RING[0d]
01/16 12:59:14 yS0 CND: RING
01/16 12:59:14 yS0 wfr: rc=0, drn=0
01/16 12:59:14 yS0 wfr: waiting for ``RING''
01/16 12:59:14 yS0 got: [0a][0d][0a]RING[0d]
01/16 12:59:19 yS0 CND: RING
01/16 12:59:19 yS0 wfr: rc=0, drn=0
01/16 12:59:19 yS0 setenv: 'CALLER_ID=none'
01/16 12:59:19 yS0 setenv: 'CALLED_ID='
01/16 12:59:19 yS0 setenv: 'CONNECT='
01/16 12:59:19 yS0 reading ring_type ring configuration from config
file /etc/mgetty+sendfax/voice.conf
01/16 12:59:19 yS0 reading /etc/mgetty+sendfax/voice.conf...
01/16 12:59:19 yS0 conf lib: read: 'part generic'
01/16 12:59:19 yS0 found CT_KEYWORD part generic
01/16 12:59:19 yS0 conf lib: read: 'voice_log_level 6'
01/16 12:59:19 yS0 conf lib: read: 'voice_dir /var/spool/voice'
01/16 12:59:19 yS0 conf lib: read: 'phone_owner root'
01/16 12:59:19 yS0 conf lib: read: 'phone_group root'
01/16 12:59:19 yS0 conf lib: read: 'phone_mode 0664'
01/16 12:59:19 yS0 conf lib: read: 'message_flag_file .flag'
01/16 12:59:19 yS0 conf lib: read: 'receive_dir incoming'
01/16 12:59:19 yS0 conf lib: read: 'message_dir messages'
01/16 12:59:19 yS0 conf lib: read: 'message_list Index'
01/16 12:59:19 yS0 conf lib: read: 'backup_message standard.rmd'
01/16 12:59:19 yS0 conf lib: read: 'port_speed 115200'
01/16 12:59:19 yS0 conf lib: read: 'voice_shell /bin/sh'
01/16 12:59:19 yS0 conf lib: read: 'port_timeout 20'
01/16 12:59:19 yS0 conf lib: read: 'dial_timeout 90'
01/16 12:59:19 yS0 conf lib: read: 'command_delay 100'
01/16 12:59:19 yS0 conf lib: read: 'dtmf_len 100'
01/16 12:59:19 yS0 conf lib: read: 'dtmf_threshold 40'
01/16 12:59:19 yS0 conf lib: read: 'dtmf_wait 9'
01/16 12:59:19 yS0 conf lib: read: 'ignore_fax_dle false'
01/16 12:59:19 yS0 conf lib: read: 'raw_data false'
01/16 12:59:19 yS0 conf lib: read: 'rec_compression 0'
01/16 12:59:19 yS0 conf lib: read: 'rec_speed 0'
01/16 12:59:19 yS0 conf lib: read: 'rec_silence_len 70'
01/16 12:59:19 yS0 conf lib: read: 'rec_silence_threshold 70'
01/16 12:59:19 yS0 conf lib: read: 'rec_remove_silence false'
01/16 12:59:19 yS0 conf lib: read: 'rec_max_len 300'
01/16 12:59:19 yS0 conf lib: read: 'rec_min_len 0'
01/16 12:59:19 yS0 conf lib: read: 'do_hard_flow true'
01/16 12:59:19 yS0 conf lib: read: 'beep_frequency 999'
01/16 12:59:19 yS0 conf lib: read: 'beep_length 1500'
01/16 12:59:19 yS0 conf lib: read: 'max_tries 3'
01/16 12:59:19 yS0 conf lib: read: 'retry_delay 3'
01/16 12:59:19 yS0 conf lib: read: 'watchdog_timeout 60'
01/16 12:59:19 yS0 conf lib: read: 'receive_gain 100%'
01/16 12:59:19 yS0 conf lib: read: 'transmit_gain 100%'
01/16 12:59:19 yS0 conf lib: read: 'enable_command_echo true'
01/16 12:59:19 yS0 conf lib: read: 'poll_interval 13'
01/16 12:59:19 yS0 conf lib: read: 'program vgetty'
01/16 12:59:19 yS0 conf lib: read: 'rings 6'
01/16 12:59:19 yS0 conf lib: read: 'answer_mode voice:fax:data'
01/16 12:59:19 yS0 conf lib: read: 'force_autodetect false'
01/16 12:59:19 yS0 conf lib: read: 'toll_saver_rings 0'
01/16 12:59:19 yS0 conf lib: read: 'rec_always_keep true'
01/16 12:59:19 yS0 conf lib: read: 'button_program '
01/16 12:59:19 yS0 conf lib: read: 'call_program '
01/16 12:59:19 yS0 conf lib: read: 'dtmf_program dtmf.sh'
01/16 12:59:19 yS0 conf lib: read: 'message_program '
01/16 12:59:19 yS0 conf lib: read: 'do_message_light false'
01/16 12:59:19 yS0 conf lib: read: 'program vm'
01/16 12:59:19 yS0 conf lib: read: 'voice_devices ttyS0'
01/16 12:59:19 yS0 conf lib: read: 'dialout_timeout 90'
01/16 12:59:19 yS0 conf lib: read: 'program pvf'
01/16 12:59:19 yS0 key: 'part', type=6, flags=4, data=(ignored)
01/16 12:59:19 yS0 key: 'program', type=6, flags=4, data=(ignored)
01/16 12:59:19 yS0 key: 'port', type=6, flags=4, data=(ignored)
01/16 12:59:19 yS0 key: 'ring_type', type=6, flags=4, data=(ignored)
01/16 12:59:19 yS0 key: 'voice_log_level', type=0, flags=3, data=6
01/16 12:59:19 yS0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
01/16 12:59:19 yS0 key: 'port_speed', type=0, flags=3, data=115200
01/16 12:59:19 yS0 key: 'port_timeout', type=0, flags=3, data=20
01/16 12:59:19 yS0 key: 'dial_timeout', type=0, flags=3, data=90
01/16 12:59:19 yS0 key: 'command_delay', type=0, flags=3, data=100
01/16 12:59:19 yS0 key: 'dtmf_len', type=0, flags=3, data=100
01/16 12:59:19 yS0 key: 'dtmf_threshold', type=0, flags=3, data=40
01/16 12:59:19 yS0 key: 'dtmf_wait', type=0, flags=3, data=9
01/16 12:59:19 yS0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
01/16 12:59:19 yS0 key: 'raw_data', type=3, flags=3, data=FALSE
01/16 12:59:19 yS0 key: 'rec_compression', type=0, flags=3, data=0
01/16 12:59:19 yS0 key: 'rec_speed', type=0, flags=3, data=0
01/16 12:59:19 yS0 key: 'rec_silence_len', type=0, flags=3, data=70
01/16 12:59:19 yS0 key: 'rec_silence_threshold', type=0, flags=3,
data=70
01/16 12:59:19 yS0 key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
01/16 12:59:19 yS0 key: 'rec_max_len', type=0, flags=3, data=300
01/16 12:59:19 yS0 key: 'rec_min_len', type=0, flags=3, data=0
01/16 12:59:19 yS0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
01/16 12:59:19 yS0 key: 'force_autodetect', type=3, flags=3,
data=FALSE
01/16 12:59:19 yS0 key: 'watchdog_timeout', type=0, flags=3, data=60
01/16 12:59:19 yS0 key: 'receive_gain', type=0, flags=3, data=100
01/16 12:59:19 yS0 key: 'transmit_gain', type=0, flags=3, data=100
01/16 12:59:19 yS0 key: 'enable_command_echo', type=3, flags=3,
data=TRUE
01/16 12:59:19 yS0 key: 'poll_interval', type=0, flags=3, data=13
01/16 12:59:19 yS0 key: 'rings', type=1, flags=3, data=6
01/16 12:59:19 yS0 key: 'answer_mode', type=1, flags=3,
data=voice:fax:data
01/16 12:59:19 yS0 key: 'toll_saver_rings', type=0, flags=3, data=0
01/16 12:59:19 yS0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
01/16 12:59:19 yS0 key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
01/16 12:59:19 yS0 key: 'phone_owner', type=1, flags=3, data=root
01/16 12:59:19 yS0 key: 'phone_group', type=1, flags=3, data=root
01/16 12:59:19 yS0 key: 'phone_mode', type=0, flags=3, data=436
01/16 12:59:19 yS0 key: 'message_flag_file', type=1, flags=3,
data=.flag
01/16 12:59:19 yS0 key: 'receive_dir', type=1, flags=3, data=incoming
01/16 12:59:19 yS0 key: 'message_dir', type=1, flags=3, data=messages
01/16 12:59:19 yS0 key: 'message_list', type=1, flags=3, data=Index
01/16 12:59:19 yS0 key: 'backup_message', type=1, flags=3,
data=standard.rmd
01/16 12:59:19 yS0 key: 'button_program', type=1, flags=3, data=
01/16 12:59:19 yS0 key: 'call_program', type=1, flags=3, data=
01/16 12:59:19 yS0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
01/16 12:59:19 yS0 key: 'message_program', type=1, flags=3, data=
01/16 12:59:19 yS0 key: 'do_message_light', type=3, flags=3,
data=FALSE
01/16 12:59:19 yS0 key: 'beep_frequency', type=0, flags=3, data=999
01/16 12:59:19 yS0 key: 'beep_length', type=0, flags=3, data=1500
01/16 12:59:19 yS0 key: 'max_tries', type=0, flags=3, data=3
01/16 12:59:19 yS0 key: 'retry_delay', type=0, flags=3, data=3
01/16 12:59:19 yS0 key: 'dialout_timeout', type=0, flags=1, data=90
01/16 12:59:19 yS0 key: 'ringback_goes_away', type=0, flags=1, data=70
01/16 12:59:19 yS0 key: 'ringback_never_came', type=0, flags=1,
data=100
01/16 12:59:19 yS0 key: 'voice_devices', type=1, flags=1, data=
01/16 12:59:19 yS0 vgetty: answer mode was set directly
01/16 12:59:19 yS0 vgetty: answer mode is [data|fax|voice]
01/16 12:59:19 yS0 vgetty: opening list file
/var/spool/voice/messages/Index
01/16 12:59:19 yS0 vgetty: found 1 messages, picked message number 1
01/16 12:59:19 yS0 vgetty: message name is
/var/spool/voice/messages/primo.rmd
01/16 12:59:19 yS0 vgetty: entering voice mode
01/16 12:59:19 yS0 vgetty: Installing signal handlers
01/16 12:59:19 yS0 voice command: 'AT#CLS?' -> ''
01/16 12:59:20 yS0 vgetty: AT#CLS?
01/16 12:59:20 yS0 US Robotics: AT#CLS?
01/16 12:59:20 yS0 US Robotics: 2.0
01/16 12:59:20 yS0 voice command: '' -> 'OK'
01/16 12:59:20 yS0 US Robotics: OK
01/16 12:59:20 yS0 voice command: 'AT#CLS=8' -> 'OK'
01/16 12:59:20 yS0 vgetty: AT#CLS=8
01/16 12:59:20 yS0 US Robotics: AT#CLS=8
01/16 12:59:20 yS0 US Robotics: OK
01/16 12:59:20 yS0 voice command: 'AT' -> 'OK'
01/16 12:59:20 yS0 vgetty: AT
01/16 12:59:20 yS0 US Robotics: AT
01/16 12:59:20 yS0 US Robotics: OK
01/16 12:59:20 yS0 voice command:
'AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100' -> 'OK'
01/16 12:59:20 yS0 vgetty:
AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/16 12:59:20 yS0 US Robotics:
AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/16 12:59:21 yS0 US Robotics: OK
01/16 12:59:21 yS0 voice command: 'AT#VLS=0A' -> 'VCON'
01/16 12:59:21 yS0 vgetty: AT#VLS=0A
01/16 12:59:21 yS0 US Robotics: AT#VLS=0A
01/16 12:59:21 yS0 US Robotics: VCON
01/16 12:59:21 yS0 vgetty: queued event RESET_WATCHDOG at position
0001
01/16 12:59:21 yS0 voice command: 'AT#VLS=0' -> 'OK|VCON'
01/16 12:59:21 yS0 vgetty: AT#VLS=0
01/16 12:59:21 yS0 vgetty: unqueued event RESET_WATCHDOG at position
0001
01/16 12:59:21 yS0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <`>
01/16 12:59:21 yS0 US Robotics: AT#VLS=0
01/16 12:59:21 yS0 US Robotics: OK
01/16 12:59:21 yS0 playing voice file
/var/spool/voice/messages/primo.rmd
01/16 12:59:21 yS0 vgetty: raw modem data header found
01/16 12:59:21 yS0 vgetty: modem type US Robotics found
01/16 12:59:21 yS0 vgetty: compression method 0x0001, speed 8000, bits
1
01/16 12:59:21 yS0 vgetty: queued event RESET_WATCHDOG at position
0002
01/16 12:59:21 yS0 vgetty: unqueued event RESET_WATCHDOG at position
0002
01/16 12:59:21 yS0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <`>
01/16 12:59:21 yS0 voice command: 'AT' -> 'OK'
01/16 12:59:21 yS0 vgetty: AT
01/16 12:59:21 yS0 US Robotics: AT
01/16 12:59:21 yS0 US Robotics: OK
01/16 12:59:21 yS0 tio_set_flow_control( HARD XON_OUT )
01/16 12:59:21 yS0 voice command: 'ATE0#VTX' -> 'CONNECT'
01/16 12:59:21 yS0 vgetty: ATE0#VTX
01/16 12:59:21 yS0 US Robotics: ATE0#VTX
01/16 12:59:21 yS0 US Robotics: CONNECT
01/16 12:59:28 yS0 vgetty: <VOICE DATA 16404 bytes>
01/16 12:59:28 yS0 vgetty: queued event RESET_WATCHDOG at position
0003
01/16 12:59:28 yS0 vgetty: unqueued event RESET_WATCHDOG at position
0003
01/16 12:59:28 yS0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <H>
01/16 12:59:28 yS0 vgetty: <STOP PLAY>
01/16 12:59:28 yS0 voice command: '' -> 'OK|VCON'
01/16 12:59:28 yS0 US Robotics: VCON
01/16 12:59:30 yS0 voice command: '' -> 'OK|VCON'
01/16 12:59:30 yS0 US Robotics: OK
01/16 12:59:30 yS0 voice command: 'AT' -> 'OK'
01/16 12:59:30 yS0 vgetty: AT
01/16 12:59:30 yS0 US Robotics: AT
01/16 12:59:30 yS0 US Robotics: OK
01/16 12:59:30 yS0 vgetty: queued event RESET_WATCHDOG at position
0004
01/16 12:59:30 yS0 voice command: 'AT#VTS=[999,0,15]' -> ''
01/16 12:59:30 yS0 vgetty: AT#VTS=[999,0,15]
01/16 12:59:30 yS0 vgetty: unqueued event RESET_WATCHDOG at position
0004
01/16 12:59:30 yS0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <H>
01/16 12:59:30 yS0 US Robotics: AT#VTS=[999,0,15]
01/16 12:59:32 yS0 voice command: '' -> 'OK'
01/16 12:59:33 yS0 US Robotics: OK
01/16 12:59:33 yS0 voice command: 'AT#VLS=0A' -> 'VCON'
01/16 12:59:33 yS0 vgetty: AT#VLS=0A
01/16 12:59:33 yS0 US Robotics: AT#VLS=0A
01/16 12:59:33 yS0 US Robotics: VCON
01/16 12:59:33 yS0 recording voice file
/var/spool/voice/incoming/vQhq8S3.rmd
01/16 12:59:33 yS0 vgetty: queued event RESET_WATCHDOG at position
0005
01/16 12:59:33 yS0 vgetty: unqueued event RESET_WATCHDOG at position
0005
01/16 12:59:33 yS0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <`>
01/16 12:59:33 yS0 voice command: 'AT' -> 'OK'
01/16 12:59:33 yS0 vgetty: AT
01/16 12:59:33 yS0 US Robotics: AT
01/16 12:59:33 yS0 US Robotics: OK
01/16 12:59:33 yS0 tio_set_flow_control( HARD XON_IN )
01/16 12:59:33 yS0 voice command: 'AT#VRX' -> 'CONNECT'
01/16 12:59:33 yS0 vgetty: AT#VRX
01/16 12:59:33 yS0 US Robotics: AT#VRX
01/16 12:59:33 yS0 US Robotics: CONNECT
01/16 12:59:35 yS0 US Robotics: <DLE> <i>
01/16 12:59:35 yS0 USR_handle_dle: Ignoring <DLE> <i> because the
modem is RECORDING.
01/16 12:59:36 yS0 US Robotics: <DLE> <i>
01/16 12:59:36 yS0 USR_handle_dle: Ignoring <DLE> <i> because the
modem is RECORDING.
01/16 12:59:52 yS0 US Robotics: <DLE> <b>
01/16 12:59:52 yS0 vgetty: queued event BUSY_TONE at position 0006
01/16 12:59:52 yS0 vgetty: unqueued event BUSY_TONE at position 0006
01/16 12:59:52 yS0 vgetty: voice_handle_event got event BUSY_TONE
with data <H>
01/16 12:59:52 yS0 vgetty: <STOP RECORDING>
01/16 12:59:52 yS0 US Robotics: <VOICE DATA 30553 bytes>
01/16 12:59:52 yS0 US Robotics: <DLE> <ETX>
01/16 12:59:52 yS0 voice command: '' -> 'OK|VCON'
01/16 12:59:52 yS0 US Robotics: VCON
01/16 12:59:52 yS0 vgetty: queued event RESET_WATCHDOG at position
0007
01/16 12:59:52 yS0 voice command: 'AT#VLS=0H0' -> 'OK|VCON'
01/16 12:59:52 yS0 vgetty: AT#VLS=0H0
01/16 12:59:52 yS0 vgetty: unqueued event RESET_WATCHDOG at position
0007
01/16 12:59:52 yS0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <H>
01/16 12:59:52 yS0 US Robotics: AT#VLS=0H0
01/16 12:59:52 yS0 US Robotics: OK
01/16 12:59:52 yS0 vgetty: leaving voice mode
01/16 12:59:52 yS0 voice command: 'AT#CLS=2.0' -> 'OK'
01/16 12:59:52 yS0 vgetty: AT#CLS=2.0
01/16 12:59:52 yS0 US Robotics: AT#CLS=2.0
01/16 12:59:52 yS0 US Robotics: OK
01/16 12:59:52 yS0 voice command: 'AT' -> 'OK'
01/16 12:59:52 yS0 vgetty: AT
01/16 12:59:52 yS0 US Robotics: AT
01/16 12:59:53 yS0 US Robotics: OK
01/16 12:59:53 yS0 vgetty: Restoring signal handlers
01/16 12:59:53 yS0 closing voice modem device
01/16 12:59:53 yS0 removing lock file
01/16 12:59:53 ##### message keep, length=00:00:19, name='',
caller=none, dev=ttyS0, pid=485
01/16 12:59:53 yS0 vgetty: creating message flag file
/var/spool/voice/.flag
--
01/16 12:59:53 yS0 vgetty: experimental test release 0.9.9 / 13Nov99
01/16 12:59:53 yS0 mgetty: experimental test release 1.1.21-Jul24
01/16 12:59:53 yS0 reading generic configuration from config file
/etc/mgetty+sendfax/voice.conf
01/16 12:59:53 yS0 reading /etc/mgetty+sendfax/voice.conf...
01/16 12:59:53 yS0 conf lib: read: 'part generic'
01/16 12:59:53 yS0 section: part generic, **found**
01/16 12:59:53 yS0 conf lib: read: 'voice_log_level 6'
01/16 12:59:53 yS0 conf lib: read: 'voice_dir /var/spool/voice'
01/16 12:59:53 yS0 conf lib: read: 'phone_owner root'
01/16 12:59:53 yS0 conf lib: read: 'phone_group root'
01/16 12:59:53 yS0 conf lib: read: 'phone_mode 0664'
01/16 12:59:53 yS0 conf lib: read: 'message_flag_file .flag'
01/16 12:59:53 yS0 conf lib: read: 'receive_dir incoming'
01/16 12:59:53 yS0 conf lib: read: 'message_dir messages'
01/16 12:59:53 yS0 conf lib: read: 'message_list Index'
01/16 12:59:53 yS0 conf lib: read: 'backup_message standard.rmd'
01/16 12:59:53 yS0 conf lib: read: 'port_speed 115200'
01/16 12:59:53 yS0 conf lib: read: 'voice_shell /bin/sh'
01/16 12:59:53 yS0 conf lib: read: 'port_timeout 20'
01/16 12:59:53 yS0 conf lib: read: 'dial_timeout 90'
01/16 12:59:53 yS0 conf lib: read: 'command_delay 100'
01/16 12:59:53 yS0 conf lib: read: 'dtmf_len 100'
01/16 12:59:53 yS0 conf lib: read: 'dtmf_threshold 40'
01/16 12:59:53 yS0 conf lib: read: 'dtmf_wait 9'
01/16 12:59:53 yS0 conf lib: read: 'ignore_fax_dle false'
01/16 12:59:53 yS0 conf lib: read: 'raw_data false'
01/16 12:59:53 yS0 conf lib: read: 'rec_compression 0'
01/16 12:59:53 yS0 conf lib: read: 'rec_speed 0'
01/16 12:59:53 yS0 conf lib: read: 'rec_silence_len 70'
01/16 12:59:53 yS0 conf lib: read: 'rec_silence_threshold 70'
01/16 12:59:53 yS0 conf lib: read: 'rec_remove_silence false'
01/16 12:59:53 yS0 conf lib: read: 'rec_max_len 300'
01/16 12:59:53 yS0 conf lib: read: 'rec_min_len 0'
01/16 12:59:53 yS0 conf lib: read: 'do_hard_flow true'
01/16 12:59:53 yS0 conf lib: read: 'beep_frequency 999'
01/16 12:59:53 yS0 conf lib: read: 'beep_length 1500'
01/16 12:59:53 yS0 conf lib: read: 'max_tries 3'
01/16 12:59:53 yS0 conf lib: read: 'retry_delay 3'
01/16 12:59:53 yS0 conf lib: read: 'watchdog_timeout 60'
01/16 12:59:53 yS0 conf lib: read: 'receive_gain 100%'
01/16 12:59:53 yS0 conf lib: read: 'transmit_gain 100%'
01/16 12:59:53 yS0 conf lib: read: 'enable_command_echo true'
01/16 12:59:53 yS0 conf lib: read: 'poll_interval 13'
01/16 12:59:53 yS0 conf lib: read: 'program vgetty'
01/16 12:59:53 yS0 found CT_KEYWORD program vgetty
01/16 12:59:53 yS0 conf lib: read: 'rings 6'
01/16 12:59:53 yS0 conf lib: read: 'answer_mode voice:fax:data'
01/16 12:59:53 yS0 conf lib: read: 'force_autodetect false'
01/16 12:59:53 yS0 conf lib: read: 'toll_saver_rings 0'
01/16 12:59:53 yS0 conf lib: read: 'rec_always_keep true'
01/16 12:59:53 yS0 conf lib: read: 'button_program '
01/16 12:59:53 yS0 conf lib: read: 'call_program '
01/16 12:59:53 yS0 conf lib: read: 'dtmf_program dtmf.sh'
01/16 12:59:53 yS0 conf lib: read: 'message_program '
01/16 12:59:53 yS0 conf lib: read: 'do_message_light false'
01/16 12:59:53 yS0 conf lib: read: 'program vm'
01/16 12:59:53 yS0 conf lib: read: 'voice_devices ttyS0'
01/16 12:59:53 yS0 conf lib: read: 'dialout_timeout 90'
01/16 12:59:53 yS0 conf lib: read: 'program pvf'
01/16 12:59:53 yS0 key: 'part', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'program', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'port', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'ring_type', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'voice_log_level', type=0, flags=3, data=6
01/16 12:59:53 yS0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
01/16 12:59:53 yS0 key: 'port_speed', type=0, flags=3, data=115200
01/16 12:59:53 yS0 key: 'port_timeout', type=0, flags=3, data=20
01/16 12:59:53 yS0 key: 'dial_timeout', type=0, flags=3, data=90
01/16 12:59:53 yS0 key: 'command_delay', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'dtmf_len', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'dtmf_threshold', type=0, flags=3, data=40
01/16 12:59:53 yS0 key: 'dtmf_wait', type=0, flags=3, data=9
01/16 12:59:53 yS0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
01/16 12:59:53 yS0 key: 'raw_data', type=3, flags=3, data=FALSE
01/16 12:59:53 yS0 key: 'rec_compression', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'rec_speed', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'rec_silence_len', type=0, flags=3, data=70
01/16 12:59:53 yS0 key: 'rec_silence_threshold', type=0, flags=3,
data=70
01/16 12:59:53 yS0 key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
01/16 12:59:53 yS0 key: 'rec_max_len', type=0, flags=3, data=300
01/16 12:59:53 yS0 key: 'rec_min_len', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
01/16 12:59:53 yS0 key: 'force_autodetect', type=3, flags=1,
data=FALSE
01/16 12:59:53 yS0 key: 'watchdog_timeout', type=0, flags=3, data=60
01/16 12:59:53 yS0 key: 'receive_gain', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'transmit_gain', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'enable_command_echo', type=3, flags=3,
data=TRUE
01/16 12:59:53 yS0 key: 'poll_interval', type=0, flags=3, data=13
01/16 12:59:53 yS0 key: 'rings', type=1, flags=1, data=3
01/16 12:59:53 yS0 key: 'answer_mode', type=1, flags=1,
data=voice:fax:data
01/16 12:59:53 yS0 key: 'toll_saver_rings', type=0, flags=1, data=0
01/16 12:59:53 yS0 key: 'rec_always_keep', type=3, flags=1, data=TRUE
01/16 12:59:53 yS0 key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
01/16 12:59:53 yS0 key: 'phone_owner', type=1, flags=3, data=root
01/16 12:59:53 yS0 key: 'phone_group', type=1, flags=3, data=root
01/16 12:59:53 yS0 key: 'phone_mode', type=0, flags=3, data=436
01/16 12:59:53 yS0 key: 'message_flag_file', type=1, flags=3,
data=.flag
01/16 12:59:53 yS0 key: 'receive_dir', type=1, flags=3, data=incoming
01/16 12:59:53 yS0 key: 'message_dir', type=1, flags=3, data=messages
01/16 12:59:53 yS0 key: 'message_list', type=1, flags=3, data=Index
01/16 12:59:53 yS0 key: 'backup_message', type=1, flags=3,
data=standard.rmd
01/16 12:59:53 yS0 key: 'button_program', type=1, flags=1, data=
01/16 12:59:53 yS0 key: 'call_program', type=1, flags=1, data=
01/16 12:59:53 yS0 key: 'dtmf_program', type=1, flags=1, data=dtmf.sh
01/16 12:59:53 yS0 key: 'message_program', type=1, flags=1, data=
01/16 12:59:53 yS0 key: 'do_message_light', type=3, flags=1,
data=FALSE
01/16 12:59:53 yS0 key: 'beep_frequency', type=0, flags=3, data=999
01/16 12:59:53 yS0 key: 'beep_length', type=0, flags=3, data=1500
01/16 12:59:53 yS0 key: 'max_tries', type=0, flags=3, data=3
01/16 12:59:53 yS0 key: 'retry_delay', type=0, flags=3, data=3
01/16 12:59:53 yS0 key: 'dialout_timeout', type=0, flags=1, data=90
01/16 12:59:53 yS0 key: 'ringback_goes_away', type=0, flags=1, data=70
01/16 12:59:53 yS0 key: 'ringback_never_came', type=0, flags=1,
data=100
01/16 12:59:53 yS0 key: 'voice_devices', type=1, flags=1, data=
01/16 12:59:53 yS0 reading program vgetty configuration from config
file /etc/mgetty+sendfax/voice.conf
01/16 12:59:53 yS0 reading /etc/mgetty+sendfax/voice.conf...
01/16 12:59:53 yS0 conf lib: read: 'part generic'
01/16 12:59:53 yS0 found CT_KEYWORD part generic
01/16 12:59:53 yS0 conf lib: read: 'voice_log_level 6'
01/16 12:59:53 yS0 conf lib: read: 'voice_dir /var/spool/voice'
01/16 12:59:53 yS0 conf lib: read: 'phone_owner root'
01/16 12:59:53 yS0 conf lib: read: 'phone_group root'
01/16 12:59:53 yS0 conf lib: read: 'phone_mode 0664'
01/16 12:59:53 yS0 conf lib: read: 'message_flag_file .flag'
01/16 12:59:53 yS0 conf lib: read: 'receive_dir incoming'
01/16 12:59:53 yS0 conf lib: read: 'message_dir messages'
01/16 12:59:53 yS0 conf lib: read: 'message_list Index'
01/16 12:59:53 yS0 conf lib: read: 'backup_message standard.rmd'
01/16 12:59:53 yS0 conf lib: read: 'port_speed 115200'
01/16 12:59:53 yS0 conf lib: read: 'voice_shell /bin/sh'
01/16 12:59:53 yS0 conf lib: read: 'port_timeout 20'
01/16 12:59:53 yS0 conf lib: read: 'dial_timeout 90'
01/16 12:59:53 yS0 conf lib: read: 'command_delay 100'
01/16 12:59:53 yS0 conf lib: read: 'dtmf_len 100'
01/16 12:59:53 yS0 conf lib: read: 'dtmf_threshold 40'
01/16 12:59:53 yS0 conf lib: read: 'dtmf_wait 9'
01/16 12:59:53 yS0 conf lib: read: 'ignore_fax_dle false'
01/16 12:59:53 yS0 conf lib: read: 'raw_data false'
01/16 12:59:53 yS0 conf lib: read: 'rec_compression 0'
01/16 12:59:53 yS0 conf lib: read: 'rec_speed 0'
01/16 12:59:53 yS0 conf lib: read: 'rec_silence_len 70'
01/16 12:59:53 yS0 conf lib: read: 'rec_silence_threshold 70'
01/16 12:59:53 yS0 conf lib: read: 'rec_remove_silence false'
01/16 12:59:53 yS0 conf lib: read: 'rec_max_len 300'
01/16 12:59:53 yS0 conf lib: read: 'rec_min_len 0'
01/16 12:59:53 yS0 conf lib: read: 'do_hard_flow true'
01/16 12:59:53 yS0 conf lib: read: 'beep_frequency 999'
01/16 12:59:53 yS0 conf lib: read: 'beep_length 1500'
01/16 12:59:53 yS0 conf lib: read: 'max_tries 3'
01/16 12:59:53 yS0 conf lib: read: 'retry_delay 3'
01/16 12:59:53 yS0 conf lib: read: 'watchdog_timeout 60'
01/16 12:59:53 yS0 conf lib: read: 'receive_gain 100%'
01/16 12:59:53 yS0 conf lib: read: 'transmit_gain 100%'
01/16 12:59:53 yS0 conf lib: read: 'enable_command_echo true'
01/16 12:59:53 yS0 conf lib: read: 'poll_interval 13'
01/16 12:59:53 yS0 conf lib: read: 'program vgetty'
01/16 12:59:53 yS0 section: program vgetty, **found**
01/16 12:59:53 yS0 conf lib: read: 'rings 6'
01/16 12:59:53 yS0 conf lib: read: 'answer_mode voice:fax:data'
01/16 12:59:53 yS0 conf lib: read: 'force_autodetect false'
01/16 12:59:53 yS0 conf lib: read: 'toll_saver_rings 0'
01/16 12:59:53 yS0 conf lib: read: 'rec_always_keep true'
01/16 12:59:53 yS0 conf lib: read: 'button_program '
01/16 12:59:53 yS0 conf lib: read: 'call_program '
01/16 12:59:53 yS0 conf lib: read: 'dtmf_program dtmf.sh'
01/16 12:59:53 yS0 conf lib: read: 'message_program '
01/16 12:59:53 yS0 conf lib: read: 'do_message_light false'
01/16 12:59:53 yS0 conf lib: read: 'program vm'
01/16 12:59:53 yS0 section: program vm, ignore
01/16 12:59:53 yS0 conf lib: read: 'voice_devices ttyS0'
01/16 12:59:53 yS0 conf lib: read: 'dialout_timeout 90'
01/16 12:59:53 yS0 conf lib: read: 'program pvf'
01/16 12:59:53 yS0 section: program pvf, ignore
01/16 12:59:53 yS0 key: 'part', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'program', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'port', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'ring_type', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'voice_log_level', type=0, flags=3, data=6
01/16 12:59:53 yS0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
01/16 12:59:53 yS0 key: 'port_speed', type=0, flags=3, data=115200
01/16 12:59:53 yS0 key: 'port_timeout', type=0, flags=3, data=20
01/16 12:59:53 yS0 key: 'dial_timeout', type=0, flags=3, data=90
01/16 12:59:53 yS0 key: 'command_delay', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'dtmf_len', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'dtmf_threshold', type=0, flags=3, data=40
01/16 12:59:53 yS0 key: 'dtmf_wait', type=0, flags=3, data=9
01/16 12:59:53 yS0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
01/16 12:59:53 yS0 key: 'raw_data', type=3, flags=3, data=FALSE
01/16 12:59:53 yS0 key: 'rec_compression', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'rec_speed', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'rec_silence_len', type=0, flags=3, data=70
01/16 12:59:53 yS0 key: 'rec_silence_threshold', type=0, flags=3,
data=70
01/16 12:59:53 yS0 key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
01/16 12:59:53 yS0 key: 'rec_max_len', type=0, flags=3, data=300
01/16 12:59:53 yS0 key: 'rec_min_len', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
01/16 12:59:53 yS0 key: 'force_autodetect', type=3, flags=3,
data=FALSE
01/16 12:59:53 yS0 key: 'watchdog_timeout', type=0, flags=3, data=60
01/16 12:59:53 yS0 key: 'receive_gain', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'transmit_gain', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'enable_command_echo', type=3, flags=3,
data=TRUE
01/16 12:59:53 yS0 key: 'poll_interval', type=0, flags=3, data=13
01/16 12:59:53 yS0 key: 'rings', type=1, flags=3, data=6
01/16 12:59:53 yS0 key: 'answer_mode', type=1, flags=3,
data=voice:fax:data
01/16 12:59:53 yS0 key: 'toll_saver_rings', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
01/16 12:59:53 yS0 key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
01/16 12:59:53 yS0 key: 'phone_owner', type=1, flags=3, data=root
01/16 12:59:53 yS0 key: 'phone_group', type=1, flags=3, data=root
01/16 12:59:53 yS0 key: 'phone_mode', type=0, flags=3, data=436
01/16 12:59:53 yS0 key: 'message_flag_file', type=1, flags=3,
data=.flag
01/16 12:59:53 yS0 key: 'receive_dir', type=1, flags=3, data=incoming
01/16 12:59:53 yS0 key: 'message_dir', type=1, flags=3, data=messages
01/16 12:59:53 yS0 key: 'message_list', type=1, flags=3, data=Index
01/16 12:59:53 yS0 key: 'backup_message', type=1, flags=3,
data=standard.rmd
01/16 12:59:53 yS0 key: 'button_program', type=1, flags=3, data=
01/16 12:59:53 yS0 key: 'call_program', type=1, flags=3, data=
01/16 12:59:53 yS0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
01/16 12:59:53 yS0 key: 'message_program', type=1, flags=3, data=
01/16 12:59:53 yS0 key: 'do_message_light', type=3, flags=3,
data=FALSE
01/16 12:59:53 yS0 key: 'beep_frequency', type=0, flags=3, data=999
01/16 12:59:53 yS0 key: 'beep_length', type=0, flags=3, data=1500
01/16 12:59:53 yS0 key: 'max_tries', type=0, flags=3, data=3
01/16 12:59:53 yS0 key: 'retry_delay', type=0, flags=3, data=3
01/16 12:59:53 yS0 key: 'dialout_timeout', type=0, flags=1, data=90
01/16 12:59:53 yS0 key: 'ringback_goes_away', type=0, flags=1, data=70
01/16 12:59:53 yS0 key: 'ringback_never_came', type=0, flags=1,
data=100
01/16 12:59:53 yS0 key: 'voice_devices', type=1, flags=1, data=
01/16 12:59:53 yS0 reading port ttyS0 configuration from config file
/etc/mgetty+sendfax/voice.conf
01/16 12:59:53 yS0 reading /etc/mgetty+sendfax/voice.conf...
01/16 12:59:53 yS0 conf lib: read: 'part generic'
01/16 12:59:53 yS0 found CT_KEYWORD part generic
01/16 12:59:53 yS0 conf lib: read: 'voice_log_level 6'
01/16 12:59:53 yS0 conf lib: read: 'voice_dir /var/spool/voice'
01/16 12:59:53 yS0 conf lib: read: 'phone_owner root'
01/16 12:59:53 yS0 conf lib: read: 'phone_group root'
01/16 12:59:53 yS0 conf lib: read: 'phone_mode 0664'
01/16 12:59:53 yS0 conf lib: read: 'message_flag_file .flag'
01/16 12:59:53 yS0 conf lib: read: 'receive_dir incoming'
01/16 12:59:53 yS0 conf lib: read: 'message_dir messages'
01/16 12:59:53 yS0 conf lib: read: 'message_list Index'
01/16 12:59:53 yS0 conf lib: read: 'backup_message standard.rmd'
01/16 12:59:53 yS0 conf lib: read: 'port_speed 115200'
01/16 12:59:53 yS0 conf lib: read: 'voice_shell /bin/sh'
01/16 12:59:53 yS0 conf lib: read: 'port_timeout 20'
01/16 12:59:53 yS0 conf lib: read: 'dial_timeout 90'
01/16 12:59:53 yS0 conf lib: read: 'command_delay 100'
01/16 12:59:53 yS0 conf lib: read: 'dtmf_len 100'
01/16 12:59:53 yS0 conf lib: read: 'dtmf_threshold 40'
01/16 12:59:53 yS0 conf lib: read: 'dtmf_wait 9'
01/16 12:59:53 yS0 conf lib: read: 'ignore_fax_dle false'
01/16 12:59:53 yS0 conf lib: read: 'raw_data false'
01/16 12:59:53 yS0 conf lib: read: 'rec_compression 0'
01/16 12:59:53 yS0 conf lib: read: 'rec_speed 0'
01/16 12:59:53 yS0 conf lib: read: 'rec_silence_len 70'
01/16 12:59:53 yS0 conf lib: read: 'rec_silence_threshold 70'
01/16 12:59:53 yS0 conf lib: read: 'rec_remove_silence false'
01/16 12:59:53 yS0 conf lib: read: 'rec_max_len 300'
01/16 12:59:53 yS0 conf lib: read: 'rec_min_len 0'
01/16 12:59:53 yS0 conf lib: read: 'do_hard_flow true'
01/16 12:59:53 yS0 conf lib: read: 'beep_frequency 999'
01/16 12:59:53 yS0 conf lib: read: 'beep_length 1500'
01/16 12:59:53 yS0 conf lib: read: 'max_tries 3'
01/16 12:59:53 yS0 conf lib: read: 'retry_delay 3'
01/16 12:59:53 yS0 conf lib: read: 'watchdog_timeout 60'
01/16 12:59:53 yS0 conf lib: read: 'receive_gain 100%'
01/16 12:59:53 yS0 conf lib: read: 'transmit_gain 100%'
01/16 12:59:53 yS0 conf lib: read: 'enable_command_echo true'
01/16 12:59:53 yS0 conf lib: read: 'poll_interval 13'
01/16 12:59:53 yS0 conf lib: read: 'program vgetty'
01/16 12:59:53 yS0 conf lib: read: 'rings 6'
01/16 12:59:53 yS0 conf lib: read: 'answer_mode voice:fax:data'
01/16 12:59:53 yS0 conf lib: read: 'force_autodetect false'
01/16 12:59:53 yS0 conf lib: read: 'toll_saver_rings 0'
01/16 12:59:53 yS0 conf lib: read: 'rec_always_keep true'
01/16 12:59:53 yS0 conf lib: read: 'button_program '
01/16 12:59:53 yS0 conf lib: read: 'call_program '
01/16 12:59:53 yS0 conf lib: read: 'dtmf_program dtmf.sh'
01/16 12:59:53 yS0 conf lib: read: 'message_program '
01/16 12:59:53 yS0 conf lib: read: 'do_message_light false'
01/16 12:59:53 yS0 conf lib: read: 'program vm'
01/16 12:59:53 yS0 conf lib: read: 'voice_devices ttyS0'
01/16 12:59:53 yS0 conf lib: read: 'dialout_timeout 90'
01/16 12:59:53 yS0 conf lib: read: 'program pvf'
01/16 12:59:53 yS0 key: 'part', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'program', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'port', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'ring_type', type=6, flags=4, data=(ignored)
01/16 12:59:53 yS0 key: 'voice_log_level', type=0, flags=3, data=6
01/16 12:59:53 yS0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
01/16 12:59:53 yS0 key: 'port_speed', type=0, flags=3, data=115200
01/16 12:59:53 yS0 key: 'port_timeout', type=0, flags=3, data=20
01/16 12:59:53 yS0 key: 'dial_timeout', type=0, flags=3, data=90
01/16 12:59:53 yS0 key: 'command_delay', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'dtmf_len', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'dtmf_threshold', type=0, flags=3, data=40
01/16 12:59:53 yS0 key: 'dtmf_wait', type=0, flags=3, data=9
01/16 12:59:53 yS0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
01/16 12:59:53 yS0 key: 'raw_data', type=3, flags=3, data=FALSE
01/16 12:59:53 yS0 key: 'rec_compression', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'rec_speed', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'rec_silence_len', type=0, flags=3, data=70
01/16 12:59:53 yS0 key: 'rec_silence_threshold', type=0, flags=3,
data=70
01/16 12:59:53 yS0 key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
01/16 12:59:53 yS0 key: 'rec_max_len', type=0, flags=3, data=300
01/16 12:59:53 yS0 key: 'rec_min_len', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
01/16 12:59:53 yS0 key: 'force_autodetect', type=3, flags=3,
data=FALSE
01/16 12:59:53 yS0 key: 'watchdog_timeout', type=0, flags=3, data=60
01/16 12:59:53 yS0 key: 'receive_gain', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'transmit_gain', type=0, flags=3, data=100
01/16 12:59:53 yS0 key: 'enable_command_echo', type=3, flags=3,
data=TRUE
01/16 12:59:53 yS0 key: 'poll_interval', type=0, flags=3, data=13
01/16 12:59:53 yS0 key: 'rings', type=1, flags=3, data=6
01/16 12:59:53 yS0 key: 'answer_mode', type=1, flags=3,
data=voice:fax:data
01/16 12:59:53 yS0 key: 'toll_saver_rings', type=0, flags=3, data=0
01/16 12:59:53 yS0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
01/16 12:59:53 yS0 key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
01/16 12:59:53 yS0 key: 'phone_owner', type=1, flags=3, data=root
01/16 12:59:53 yS0 key: 'phone_group', type=1, flags=3, data=root
01/16 12:59:53 yS0 key: 'phone_mode', type=0, flags=3, data=436
01/16 12:59:53 yS0 key: 'message_flag_file', type=1, flags=3,
data=.flag
01/16 12:59:53 yS0 key: 'receive_dir', type=1, flags=3, data=incoming
01/16 12:59:53 yS0 key: 'message_dir', type=1, flags=3, data=messages
01/16 12:59:53 yS0 key: 'message_list', type=1, flags=3, data=Index
01/16 12:59:53 yS0 key: 'backup_message', type=1, flags=3,
data=standard.rmd
01/16 12:59:53 yS0 key: 'button_program', type=1, flags=3, data=
01/16 12:59:53 yS0 key: 'call_program', type=1, flags=3, data=
01/16 12:59:53 yS0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
01/16 12:59:53 yS0 key: 'message_program', type=1, flags=3, data=
01/16 12:59:53 yS0 key: 'do_message_light', type=3, flags=3,
data=FALSE
01/16 12:59:53 yS0 key: 'beep_frequency', type=0, flags=3, data=999
01/16 12:59:53 yS0 key: 'beep_length', type=0, flags=3, data=1500
01/16 12:59:53 yS0 key: 'max_tries', type=0, flags=3, data=3
01/16 12:59:53 yS0 key: 'retry_delay', type=0, flags=3, data=3
01/16 12:59:53 yS0 key: 'dialout_timeout', type=0, flags=1, data=90
01/16 12:59:53 yS0 key: 'ringback_goes_away', type=0, flags=1, data=70
01/16 12:59:53 yS0 key: 'ringback_never_came', type=0, flags=1,
data=100
01/16 12:59:53 yS0 key: 'voice_devices', type=1, flags=1, data=
01/16 12:59:53 yS0 check for lockfiles
01/16 12:59:53 yS0 checklock: stat failed, no file
01/16 12:59:53 yS0 locking the line
01/16 12:59:53 yS0 makelock(ttyS0) called
01/16 12:59:53 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
01/16 12:59:53 yS0 lock made
01/16 12:59:53 yS0 can't get group 'modem': No such file or directory
01/16 12:59:53 yS0 tio_get_rs232_lines: status: RTS CTS DSR DTR
01/16 12:59:53 yS0 lowering DTR to reset Modem
01/16 12:59:54 yS0 tss: set speed to 115200 (10002)
01/16 12:59:54 yS0 tio_set_flow_control( HARD )
01/16 12:59:54 yS0 waiting for line to clear (VTIME), read:
01/16 12:59:54 yS0 send: ATZ[0d]
01/16 12:59:54 yS0 waiting for ``OK''
01/16 12:59:54 yS0 got: ATZ[0d]
01/16 12:59:54 yS0 CND: ATZ[0d][0a]OK ** found **
01/16 12:59:54 yS0 send: \d\d\dATQ0V1H0S0=255[0d]
01/16 12:59:56 yS0 waiting for ``OK''
01/16 12:59:56 yS0 got: [0d]
01/16 12:59:56 yS0 CND: OK[0a]ATQ0V1H0S0=255[0d]
01/16 12:59:56 yS0 CND: ATQ0V1H0S0=255[0d][0a]OK ** found **
01/16 12:59:56 yS0 mdm_send: 'ATI'
01/16 12:59:56 yS0 got:[0d][0a]ATI[0d]
01/16 12:59:56 yS0 got:[0d][0a]5601[0d]
01/16 12:59:56 yS0 mdm_gis: string 1: '5601'
01/16 12:59:56 yS0 got:[0a][0d][0a]OK[0d]
01/16 12:59:56 yS0 mdm_identify: string '5601'
01/16 12:59:56 yS0 USR Courier/Sportster 56k detected
01/16 12:59:56 yS0 mdm_send: 'ATI3'
01/16 12:59:56 yS0 got:[0a]ATI3[0d]
01/16 12:59:56 yS0 got:[0d][0a]U.S. Robotics 56K Voice EXT Rev.
12.1.0[0d]
01/16 12:59:56 yS0 mdm_gis: string 1: 'U.S. Robotics 56K Voice EXT
Rev. 12.1.0'
01/16 12:59:56 yS0 got:[0a][0d][0a]OK[0d]
01/16 12:59:56 yS0 additional info: 'U.S. Robotics 56K Voice EXT Rev.
12.1.0'
01/16 12:59:56 yS0 mdm_send: 'AT+FCLASS=2.0'
01/16 12:59:56 yS0 got:[0a]AT+FCLASS=2.0[0d]
01/16 12:59:56 yS0 mdm_command: string 'AT+FCLASS=2.0'
01/16 12:59:56 yS0 got:[0d][0a]OK[0d]
01/16 12:59:56 yS0 mdm_command: string 'OK' -> OK
01/16 12:59:56 yS0 mdm_send: 'AT+FAA=1;+FCR=1'
01/16 12:59:56 yS0 got:[0a]AT+FAA=1;+FCR=1[0d]
01/16 12:59:56 yS0 mdm_command: string 'AT+FAA=1;+FCR=1'
01/16 12:59:56 yS0 got:[0d][0a]OK[0d]
01/16 12:59:57 yS0 mdm_command: string 'OK' -> OK
01/16 12:59:57 yS0 mdm_send: 'AT+FBO=1'
01/16 12:59:57 yS0 got:[0a]AT+FBO=1[0d]
01/16 12:59:57 yS0 mdm_command: string 'AT+FBO=1'
01/16 12:59:57 yS0 got:[0d][0a]OK[0d]
01/16 12:59:57 yS0 mdm_command: string 'OK' -> OK
01/16 12:59:57 yS0 mdm_send: 'AT+FNR=1,1,1,0'
01/16 12:59:57 yS0 got:[0a]AT+FNR=1,1,1,0[0d]
01/16 12:59:57 yS0 mdm_command: string 'AT+FNR=1,1,1,0'
01/16 12:59:57 yS0 got:[0d][0a]OK[0d]
01/16 12:59:57 yS0 mdm_command: string 'OK' -> OK
01/16 12:59:57 yS0 mdm_send: 'AT+FLI="+39 039 2782777"'
01/16 12:59:57 yS0 got:[0a]AT+FLI="+39 039 2782777"[0d]
01/16 12:59:57 yS0 mdm_command: string 'AT+FLI="+39 039 2782777"'
01/16 12:59:57 yS0 got:[0d][0a]OK[0d]
01/16 12:59:57 yS0 mdm_command: string 'OK' -> OK
01/16 12:59:57 yS0 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
01/16 12:59:57 yS0 got:[0a]AT+FCC=1,5,0,2,0,0,0,0[0d]
01/16 12:59:57 yS0 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
01/16 12:59:57 yS0 got:[0d][0a]OK[0d]
01/16 12:59:57 yS0 mdm_command: string 'OK' -> OK
01/16 12:59:57 yS0 tss: set speed to 115200 (10002)
01/16 12:59:57 yS0 tio_set_flow_control( HARD )
01/16 12:59:57 yS0 detecting voice modem type
01/16 12:59:57 yS0 vgetty: ATE1
01/16 12:59:57 yS0 voice command: '' -> 'OK|ATE1'
01/16 12:59:57 yS0 serial port: ATE1
01/16 12:59:57 yS0 serial port: OK
01/16 12:59:58 yS0 voice command: 'ATI9' -> ''
01/16 12:59:58 yS0 vgetty: ATI9
01/16 12:59:58 yS0 serial port: ATI9
01/16 12:59:58 yS0 serial port: (1.0USR9180\\Modem\PNPC107\U.S.
Robotics 56K Voice EXT)FF
01/16 12:59:58 yS0 serial port: OK
01/16 12:59:58 yS0 voice command: 'ATI' -> ''
01/16 12:59:58 yS0 vgetty: ATI
01/16 12:59:58 yS0 serial port: ATI
01/16 12:59:58 yS0 serial port: 5601
01/16 12:59:59 yS0 US Robotics: OK
01/16 12:59:59 yS0 US Robotics detected
01/16 12:59:59 yS0 vgetty: entering voice mode
01/16 12:59:59 yS0 vgetty: Installing signal handlers
01/16 12:59:59 yS0 voice command: 'AT#CLS?' -> ''
01/16 12:59:59 yS0 vgetty: AT#CLS?
01/16 12:59:59 yS0 US Robotics: AT#CLS?
01/16 12:59:59 yS0 US Robotics: 2.0
01/16 12:59:59 yS0 voice command: '' -> 'OK'
01/16 12:59:59 yS0 US Robotics: OK
01/16 12:59:59 yS0 voice command: 'AT#CLS=8' -> 'OK'
01/16 12:59:59 yS0 vgetty: AT#CLS=8
01/16 12:59:59 yS0 US Robotics: AT#CLS=8
01/16 12:59:59 yS0 US Robotics: OK
01/16 12:59:59 yS0 voice command: 'AT' -> 'OK'
01/16 13:00:00 yS0 vgetty: AT
01/16 13:00:00 yS0 US Robotics: AT
01/16 13:00:00 yS0 US Robotics: OK
01/16 13:00:00 yS0 voice command:
'AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100' -> 'OK'
01/16 13:00:00 yS0 vgetty:
AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/16 13:00:00 yS0 US Robotics:
AT#VTD=3F,3F,3F#VSD=1#VSS=2#VSP=70#VRA=70#VRN=100
01/16 13:00:00 yS0 US Robotics: OK
01/16 13:00:00 yS0 vgetty: queued event RESET_WATCHDOG at position
0000
01/16 13:00:00 yS0 US Robotics voice modem
01/16 13:00:00 yS0 This is a driver beta version. V0.4.b3
01/16 13:00:00 yS0 voice command: 'AT&H1&R2&I0' -> 'OK'
01/16 13:00:00 yS0 vgetty: AT&H1&R2&I0
01/16 13:00:00 yS0 vgetty: unqueued event RESET_WATCHDOG at position
0000
01/16 13:00:00 yS0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <NUL>
01/16 13:00:00 yS0 US Robotics: AT&H1&R2&I0
01/16 13:00:00 yS0 US Robotics: OK
01/16 13:00:00 yS0 tio_set_flow_control( HARD )
01/16 13:00:00 yS0 voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
01/16 13:00:00 yS0 vgetty: AT#VTD=3F,3F,3F
01/16 13:00:00 yS0 US Robotics: AT#VTD=3F,3F,3F
01/16 13:00:00 yS0 US Robotics: OK
01/16 13:00:00 yS0 VTD setup successful
01/16 13:00:00 yS0 voice command: 'AT#VSD=1#VSS=2#VSP=70' -> 'OK'
01/16 13:00:00 yS0 vgetty: AT#VSD=1#VSS=2#VSP=70
01/16 13:00:00 yS0 US Robotics: AT#VSD=1#VSS=2#VSP=70
01/16 13:00:01 yS0 US Robotics: OK
01/16 13:00:01 yS0 voice command: 'AT#VRA=70#VRN=100' -> 'OK'
01/16 13:00:01 yS0 vgetty: AT#VRA=70#VRN=100
01/16 13:00:01 yS0 US Robotics: AT#VRA=70#VRN=100
01/16 13:00:01 yS0 US Robotics: OK
01/16 13:00:01 yS0 vgetty: leaving voice mode
01/16 13:00:01 yS0 voice command: 'AT#CLS=2.0' -> 'OK'
01/16 13:00:01 yS0 vgetty: AT#CLS=2.0
01/16 13:00:01 yS0 US Robotics: AT#CLS=2.0
01/16 13:00:01 yS0 US Robotics: OK
01/16 13:00:01 yS0 voice command: 'AT' -> 'OK'
01/16 13:00:01 yS0 vgetty: AT
01/16 13:00:01 yS0 US Robotics: AT
01/16 13:00:01 yS0 US Robotics: OK
01/16 13:00:01 yS0 vgetty: Restoring signal handlers
01/16 13:00:01 yS0 waiting for line to clear (VTIME), read:
01/16 13:00:02 yS0 removing lock file
01/16 13:00:02 yS0 waiting...
01/16 13:00:05 yS0 select returned 1
01/16 13:00:05 yS0 checking lockfiles, locking the line
01/16 13:00:05 yS0 makelock(ttyS0) called
01/16 13:00:05 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
01/16 13:00:05 yS0 lock made
01/16 13:00:05 yS0 vgetty: number of rings (6) was set directly
01/16 13:00:05 yS0 vgetty: decremented number of rings (6) by 0
01/16 13:00:05 yS0 wfr: waiting for ``RING''
01/16 13:00:05 yS0 got: [0a][0d][0a]RING[0d]
01/16 13:00:05 yS0 CND: RING
01/16 13:00:05 yS0 wfr: rc=0, drn=0
01/16 13:00:05 yS0 wfr: waiting for ``RING''
01/16 13:00:05 yS0 got: [0a][0d][0a]RING[0d]
01/16 13:00:10 yS0 CND: RING
01/16 13:00:10 yS0 wfr: rc=0, drn=0
01/16 13:00:10 yS0 wfr: waiting for ``RING''
01/16 13:00:10 yS0 got: [0a][0d][0a]RING[0d]
01/16 13:00:15 yS0 CND: RING
01/16 13:00:15 yS0 wfr: rc=0, drn=0
01/16 13:00:15 yS0 wfr: waiting for ``RING''
01/16 13:00:15 yS0 got: [0a][0d][0a]RING[0d]
01/16 13:00:20 yS0 CND: RING
01/16 13:00:20 yS0 wfr: rc=0, drn=0
01/16 13:00:20 yS0 wfr: waiting for ``RING''
01/16 13:00:20 yS0 got: [0a][0d][0a]RING[0d]
01/16 13:00:25 yS0 CND: RING
01/16 13:00:25 yS0 wfr: rc=0, drn=0
01/16 13:00:25 yS0 wfr: waiting for ``RING''
01/16 13:00:25 yS0 got: [0a][0d][0a]+FCO[0d]
01/16 13:00:33 yS0 CND: +FCO
01/16 13:00:33 yS0 got: [0a]
01/16 13:00:35 yS0 mdm_read_byte: read returned -1: Interrupted system
call
01/16 13:00:35 yS0 wfr: timeout waiting for RING
01/16 13:00:35 yS0 wfr: rc=-1, drn=0
01/16 13:00:35 ##### phone stopped ringing (rings=5, dev=ttyS0,
pid=1327, caller='none')
01/16 13:00:35 yS0 waiting for line to clear (VTIME), read:
01/16 13:00:35 yS0 removing lock file
01/16 13:00:35 yS0 waiting...
01/16 13:00:40 yS0 select returned 1
01/16 13:00:40 yS0 checking lockfiles, locking the line
01/16 13:00:40 yS0 makelock(ttyS0) called
01/16 13:00:40 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
01/16 13:00:40 yS0 lock made
01/16 13:00:40 yS0 vgetty: number of rings (6) was set directly
01/16 13:00:40 yS0 vgetty: decremented number of rings (6) by 0
01/16 13:00:40 yS0 wfr: waiting for ``RING''
01/16 13:00:40 yS0 got: [0d][0a]+FCO[0d]
01/16 13:00:40 yS0 CND: +FCO
01/16 13:00:40 yS0 got: [0a][0d][0a]+FCO[0d]
01/16 13:00:47 yS0 CND: +FCO
01/16 13:00:47 yS0 got: [0a]
01/16 13:00:50 yS0 mdm_read_byte: read returned -1: Interrupted system
call
01/16 13:00:50 yS0 wfr: timeout waiting for RING
01/16 13:00:50 yS0 wfr: rc=-1, drn=0
01/16 13:00:50 yS0 huh? Junk on the line?
01/16 13:00:50 yS0 removing lock file
--
--
____________________________________
Giovanni Biscuolo
mailto: giovanni.biscuolo@libero.it