Vgetty data login problem

Sebastian Fritsch (s.fritsch@usa.net)
Tue, 9 Jun 1998 22:30:56 +0200


Hi,

I asked you about a problem with vgetty. I've got problems getting
data logins.

So I logged a complete call, from the init, so you can look at it
yourself.

I think the interesting part is at 16:29:14 when it's tried to get the
logon name.

06/09 16:28:18 yS0  vgetty: experimental test release 0.8.1 / 25Mar98
06/09 16:28:18 yS0  mgetty: experimental test release 1.1.14-Apr02
06/09 16:28:18 yS0  reading generic configuration from config file
/usr/local/etc/mgetty+sendfax/voice.conf
06/09 16:28:18 yS0  reading program vgetty configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
06/09 16:28:18 yS0   reading
/usr/local/etc/mgetty+sendfax/voice.conf...
06/09 16:28:18 yS0   conf lib: read: 'part generic'
06/09 16:28:18 yS0   found CT_KEYWORD part generic
06/09 16:28:18 yS0   conf lib: read: 'voice_log_level 6'
06/09 16:28:18 yS0   conf lib: read: 'voice_dir /var/spool/voice'
06/09 16:28:18 yS0   conf lib: read: 'message_flag_file .flag'
06/09 16:28:18 yS0   conf lib: read: 'receive_dir incoming'
06/09 16:28:18 yS0   conf lib: read: 'message_dir messages'
06/09 16:28:18 yS0   conf lib: read: 'message_list Index'
06/09 16:28:18 yS0   conf lib: read: 'backup_message standard.rmd'
06/09 16:28:18 yS0   conf lib: read: 'port_speed 38400'
06/09 16:28:18 yS0   conf lib: read: 'voice_shell /bin/sh'
06/09 16:28:18 yS0   conf lib: read: 'port_timeout 10'
06/09 16:28:18 yS0   conf lib: read: 'dial_timeout 90'
06/09 16:28:18 yS0   conf lib: read: 'command_delay 100'
06/09 16:28:18 yS0   conf lib: read: 'dtmf_len 30'
06/09 16:28:18 yS0   conf lib: read: 'dtmf_threshold 40'
06/09 16:28:18 yS0   conf lib: read: 'dtmf_wait 7'
06/09 16:28:18 yS0   conf lib: read: 'ignore_fax_dle false'
06/09 16:28:18 yS0   conf lib: read: 'raw_data false'
06/09 16:28:18 yS0   conf lib: read: 'rec_compression 0'
06/09 16:28:18 yS0   conf lib: read: 'rec_speed 0'
06/09 16:28:18 yS0   conf lib: read: 'rec_silence_len 70'
06/09 16:28:18 yS0   conf lib: read: 'rec_silence_threshold 40'
06/09 16:28:18 yS0   conf lib: read: 'rec_remove_silence false'
06/09 16:28:18 yS0   conf lib: read: 'rec_max_len 300'
06/09 16:28:18 yS0   conf lib: read: 'rec_min_len 0'
06/09 16:28:18 yS0   conf lib: read: 'do_hard_flow true'
06/09 16:28:18 yS0   conf lib: read: 'beep_frequency 933'
06/09 16:28:18 yS0   conf lib: read: 'beep_length 150'
06/09 16:28:18 yS0   conf lib: read: 'max_tries 3'
06/09 16:28:18 yS0   conf lib: read: 'retry_delay 5'
06/09 16:28:18 yS0   conf lib: read: 'watchdog_timeout 60'
06/09 16:28:18 yS0   conf lib: read: 'receive_gain -1'
06/09 16:28:18 yS0   conf lib: read: 'transmit_gain -1'
06/09 16:28:18 yS0   conf lib: read: 'enable_command_echo true'
06/09 16:28:18 yS0   conf lib: read: 'program vgetty'
06/09 16:28:18 yS0   section: program vgetty, **found**
06/09 16:28:19 yS0   conf lib: read: 'rings 2'
06/09 16:28:19 yS0   conf lib: read: 'answer_mode voice:fax:data'
06/09 16:28:19 yS0   conf lib: read: 'force_autodetect false'
06/09 16:28:19 yS0   conf lib: read: 'toll_saver_rings 0'
06/09 16:28:19 yS0   conf lib: read: 'rec_always_keep true'
06/09 16:28:19 yS0   conf lib: read: 'button_program '
06/09 16:28:19 yS0   conf lib: read: 'call_program '
06/09 16:28:19 yS0   conf lib: read: 'dtmf_program dtmf.sh'
06/09 16:28:19 yS0   conf lib: read: 'message_program '
06/09 16:28:19 yS0   conf lib: read: 'do_message_light false'
06/09 16:28:19 yS0   conf lib: read: 'program vm'
06/09 16:28:19 yS0   section: program vm, ignore
06/09 16:28:19 yS0   conf lib: read: 'voice_devices '
06/09 16:28:19 yS0   conf lib: read: 'dialout_timeout 90'
06/09 16:28:19 yS0   conf lib: read: 'program pvf'
06/09 16:28:19 yS0   section: program pvf, ignore
06/09 16:28:19 yS0   conf lib: read: 'port ttyS0'
06/09 16:28:19 yS0   conf lib: read: 'port ttyS5'
06/09 16:28:19 yS0   conf lib: read: 'ring_type virtual'
06/09 16:28:19 yS0   conf lib: read: 'answer_mode fax:data'
06/09 16:28:19 yS0   conf lib: read: 'ring_type ring'
06/09 16:28:19 yS0   conf lib: read: 'ring_type ring1'
06/09 16:28:19 yS0   key: 'part', type=6, flags=4, data=(ignored)
06/09 16:28:19 yS0   key: 'program', type=6, flags=4, data=(ignored)
06/09 16:28:19 yS0   key: 'port', type=6, flags=4, data=(ignored)
06/09 16:28:19 yS0   key: 'ring_type', type=6, flags=4, data=(ignored)
06/09 16:28:19 yS0   key: 'voice_log_level', type=0, flags=3, data=6
06/09 16:28:19 yS0   key: 'voice_shell', type=1, flags=3, data=/bin/sh
06/09 16:28:19 yS0   key: 'port_speed', type=0, flags=3, data=38400
06/09 16:28:19 yS0   key: 'port_timeout', type=0, flags=3, data=10
06/09 16:28:19 yS0   key: 'dial_timeout', type=0, flags=3, data=90
06/09 16:28:19 yS0   key: 'command_delay', type=0, flags=3, data=100
06/09 16:28:19 yS0   key: 'dtmf_len', type=0, flags=3, data=30
06/09 16:28:19 yS0   key: 'dtmf_threshold', type=0, flags=3, data=40
06/09 16:28:19 yS0   key: 'dtmf_wait', type=0, flags=3, data=7
06/09 16:28:19 yS0   key: 'ignore_fax_dle', type=3, flags=3,
data=FALSE
06/09 16:28:19 yS0   key: 'raw_data', type=3, flags=3, data=FALSE
06/09 16:28:19 yS0   key: 'rec_compression', type=0, flags=3, data=0
06/09 16:28:19 yS0   key: 'rec_speed', type=0, flags=3, data=0
06/09 16:28:19 yS0   key: 'rec_silence_len', type=0, flags=3, data=70
06/09 16:28:19 yS0   key: 'rec_silence_threshold', type=0, flags=3,
data=40
06/09 16:28:19 yS0   key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
06/09 16:28:19 yS0   key: 'rec_max_len', type=0, flags=3, data=300
06/09 16:28:19 yS0   key: 'rec_min_len', type=0, flags=3, data=0
06/09 16:28:19 yS0   key: 'do_hard_flow', type=3, flags=3, data=TRUE
06/09 16:28:19 yS0   key: 'force_autodetect', type=3, flags=3,
data=FALSE
06/09 16:28:19 yS0   key: 'watchdog_timeout', type=0, flags=3, data=60
06/09 16:28:19 yS0   key: 'receive_gain', type=0, flags=3, data=-1
06/09 16:28:19 yS0   key: 'transmit_gain', type=0, flags=3, data=-1
06/09 16:28:19 yS0   key: 'enable_command_echo', type=3, flags=3,
data=TRUE
06/09 16:28:19 yS0   key: 'poll_interval', type=0, flags=1, data=10
06/09 16:28:19 yS0   key: 'rings', type=1, flags=3, data=2
06/09 16:28:19 yS0   key: 'answer_mode', type=1, flags=3,
data=voice:fax:data
06/09 16:28:19 yS0   key: 'toll_saver_rings', type=0, flags=3, data=0
06/09 16:28:19 yS0   key: 'rec_always_keep', type=3, flags=3,
data=TRUE
06/09 16:28:19 yS0   key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
06/09 16:28:19 yS0   key: 'phone_owner', type=1, flags=1, data=root
06/09 16:28:19 yS0   key: 'phone_group', type=1, flags=1, data=phone
06/09 16:28:19 yS0   key: 'phone_mode', type=0, flags=1, data=432
06/09 16:28:19 yS0   key: 'message_flag_file', type=1, flags=3,
data=.flag
06/09 16:28:19 yS0   key: 'receive_dir', type=1, flags=3,
data=incoming
06/09 16:28:19 yS0   key: 'message_dir', type=1, flags=3,
data=messages
06/09 16:28:19 yS0   key: 'message_list', type=1, flags=3, data=Index
06/09 16:28:19 yS0   key: 'backup_message', type=1, flags=3,
data=standard.rmd
06/09 16:28:19 yS0   key: 'button_program', type=1, flags=3, data=
06/09 16:28:19 yS0   key: 'call_program', type=1, flags=3, data=
06/09 16:28:19 yS0   key: 'dtmf_program', type=1, flags=3,
data=dtmf.sh
06/09 16:28:19 yS0   key: 'message_program', type=1, flags=3, data=
06/09 16:28:19 yS0   key: 'do_message_light', type=3, flags=3,
data=FALSE
06/09 16:28:19 yS0   key: 'beep_frequency', type=0, flags=3, data=933
06/09 16:28:19 yS0   key: 'beep_length', type=0, flags=3, data=150
06/09 16:28:19 yS0   key: 'max_tries', type=0, flags=3, data=3
06/09 16:28:19 yS0   key: 'retry_delay', type=0, flags=3, data=5
06/09 16:28:19 yS0   key: 'dialout_timeout', type=0, flags=1, data=90
06/09 16:28:19 yS0   key: 'voice_devices', type=1, flags=1, data=
06/09 16:28:19 yS0  reading port ttyS0 configuration from config file
/usr/local/etc/mgetty+sendfax/voice.conf
06/09 16:28:19 yS0   reading
/usr/local/etc/mgetty+sendfax/voice.conf...
06/09 16:28:19 yS0   conf lib: read: 'part generic'
06/09 16:28:19 yS0   found CT_KEYWORD part generic
06/09 16:28:19 yS0   conf lib: read: 'voice_log_level 6'
06/09 16:28:19 yS0   conf lib: read: 'voice_dir /var/spool/voice'
06/09 16:28:19 yS0   conf lib: read: 'message_flag_file .flag'
06/09 16:28:19 yS0   conf lib: read: 'receive_dir incoming'
06/09 16:28:19 yS0   conf lib: read: 'message_dir messages'
06/09 16:28:19 yS0   conf lib: read: 'message_list Index'
06/09 16:28:19 yS0   conf lib: read: 'backup_message standard.rmd'
06/09 16:28:19 yS0   conf lib: read: 'port_speed 38400'
06/09 16:28:19 yS0   conf lib: read: 'voice_shell /bin/sh'
06/09 16:28:19 yS0   conf lib: read: 'port_timeout 10'
06/09 16:28:19 yS0   conf lib: read: 'dial_timeout 90'
06/09 16:28:19 yS0   conf lib: read: 'command_delay 100'
06/09 16:28:19 yS0   conf lib: read: 'dtmf_len 30'
06/09 16:28:19 yS0   conf lib: read: 'dtmf_threshold 40'
06/09 16:28:19 yS0   conf lib: read: 'dtmf_wait 7'
06/09 16:28:19 yS0   conf lib: read: 'ignore_fax_dle false'
06/09 16:28:19 yS0   conf lib: read: 'raw_data false'
06/09 16:28:19 yS0   conf lib: read: 'rec_compression 0'
06/09 16:28:19 yS0   conf lib: read: 'rec_speed 0'
06/09 16:28:19 yS0   conf lib: read: 'rec_silence_len 70'
06/09 16:28:19 yS0   conf lib: read: 'rec_silence_threshold 40'
06/09 16:28:19 yS0   conf lib: read: 'rec_remove_silence false'
06/09 16:28:19 yS0   conf lib: read: 'rec_max_len 300'
06/09 16:28:19 yS0   conf lib: read: 'rec_min_len 0'
06/09 16:28:19 yS0   conf lib: read: 'do_hard_flow true'
06/09 16:28:19 yS0   conf lib: read: 'beep_frequency 933'
06/09 16:28:19 yS0   conf lib: read: 'beep_length 150'
06/09 16:28:19 yS0   conf lib: read: 'max_tries 3'
06/09 16:28:19 yS0   conf lib: read: 'retry_delay 5'
06/09 16:28:19 yS0   conf lib: read: 'watchdog_timeout 60'
06/09 16:28:19 yS0   conf lib: read: 'receive_gain -1'
06/09 16:28:19 yS0   conf lib: read: 'transmit_gain -1'
06/09 16:28:19 yS0   conf lib: read: 'enable_command_echo true'
06/09 16:28:19 yS0   conf lib: read: 'program vgetty'
06/09 16:28:19 yS0   conf lib: read: 'rings 2'
06/09 16:28:19 yS0   conf lib: read: 'answer_mode voice:fax:data'
06/09 16:28:19 yS0   conf lib: read: 'force_autodetect false'
06/09 16:28:19 yS0   conf lib: read: 'toll_saver_rings 0'
06/09 16:28:19 yS0   conf lib: read: 'rec_always_keep true'
06/09 16:28:19 yS0   conf lib: read: 'button_program '
06/09 16:28:19 yS0   conf lib: read: 'call_program '
06/09 16:28:19 yS0   conf lib: read: 'dtmf_program dtmf.sh'
06/09 16:28:19 yS0   conf lib: read: 'message_program '
06/09 16:28:19 yS0   conf lib: read: 'do_message_light false'
06/09 16:28:19 yS0   conf lib: read: 'program vm'
06/09 16:28:19 yS0   conf lib: read: 'voice_devices '
06/09 16:28:19 yS0   conf lib: read: 'dialout_timeout 90'
06/09 16:28:19 yS0   conf lib: read: 'program pvf'
06/09 16:28:19 yS0   conf lib: read: 'port ttyS0'
06/09 16:28:19 yS0   section: port ttyS0, **found**
06/09 16:28:19 yS0   conf lib: read: 'port ttyS5'
06/09 16:28:19 yS0   section: port ttyS5, ignore
06/09 16:28:19 yS0   conf lib: read: 'ring_type virtual'
06/09 16:28:19 yS0   conf lib: read: 'answer_mode fax:data'
06/09 16:28:19 yS0   conf lib: read: 'ring_type ring'
06/09 16:28:19 yS0   conf lib: read: 'ring_type ring1'
06/09 16:28:19 yS0   key: 'part', type=6, flags=4, data=(ignored)
06/09 16:28:19 yS0   key: 'program', type=6, flags=4, data=(ignored)
06/09 16:28:19 yS0   key: 'port', type=6, flags=4, data=(ignored)
06/09 16:28:19 yS0   key: 'ring_type', type=6, flags=4, data=(ignored)
06/09 16:28:19 yS0   key: 'voice_log_level', type=0, flags=3, data=6
06/09 16:28:19 yS0   key: 'voice_shell', type=1, flags=3, data=/bin/sh
06/09 16:28:19 yS0   key: 'port_speed', type=0, flags=3, data=38400
06/09 16:28:19 yS0   key: 'port_timeout', type=0, flags=3, data=10
06/09 16:28:19 yS0   key: 'dial_timeout', type=0, flags=3, data=90
06/09 16:28:19 yS0   key: 'command_delay', type=0, flags=3, data=100
06/09 16:28:19 yS0   key: 'dtmf_len', type=0, flags=3, data=30
06/09 16:28:19 yS0   key: 'dtmf_threshold', type=0, flags=3, data=40
06/09 16:28:19 yS0   key: 'dtmf_wait', type=0, flags=3, data=7
06/09 16:28:19 yS0   key: 'ignore_fax_dle', type=3, flags=3,
data=FALSE
06/09 16:28:19 yS0   key: 'raw_data', type=3, flags=3, data=FALSE
06/09 16:28:19 yS0   key: 'rec_compression', type=0, flags=3, data=0
06/09 16:28:19 yS0   key: 'rec_speed', type=0, flags=3, data=0
06/09 16:28:19 yS0   key: 'rec_silence_len', type=0, flags=3, data=70
06/09 16:28:19 yS0   key: 'rec_silence_threshold', type=0, flags=3,
data=40
06/09 16:28:19 yS0   key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
06/09 16:28:19 yS0   key: 'rec_max_len', type=0, flags=3, data=300
06/09 16:28:19 yS0   key: 'rec_min_len', type=0, flags=3, data=0
06/09 16:28:19 yS0   key: 'do_hard_flow', type=3, flags=3, data=TRUE
06/09 16:28:19 yS0   key: 'force_autodetect', type=3, flags=3,
data=FALSE
06/09 16:28:19 yS0   key: 'watchdog_timeout', type=0, flags=3, data=60
06/09 16:28:19 yS0   key: 'receive_gain', type=0, flags=3, data=-1
06/09 16:28:19 yS0   key: 'transmit_gain', type=0, flags=3, data=-1
06/09 16:28:19 yS0   key: 'enable_command_echo', type=3, flags=3,
data=TRUE
06/09 16:28:19 yS0   key: 'poll_interval', type=0, flags=1, data=10
06/09 16:28:19 yS0   key: 'rings', type=1, flags=3, data=2
06/09 16:28:19 yS0   key: 'answer_mode', type=1, flags=3,
data=voice:fax:data
06/09 16:28:19 yS0   key: 'toll_saver_rings', type=0, flags=3, data=0
06/09 16:28:19 yS0   key: 'rec_always_keep', type=3, flags=3,
data=TRUE
06/09 16:28:19 yS0   key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
06/09 16:28:19 yS0   key: 'phone_owner', type=1, flags=1, data=root
06/09 16:28:19 yS0   key: 'phone_group', type=1, flags=1, data=phone
06/09 16:28:19 yS0   key: 'phone_mode', type=0, flags=1, data=432
06/09 16:28:19 yS0   key: 'message_flag_file', type=1, flags=3,
data=.flag
06/09 16:28:19 yS0   key: 'receive_dir', type=1, flags=3,
data=incoming
06/09 16:28:19 yS0   key: 'message_dir', type=1, flags=3,
data=messages
06/09 16:28:19 yS0   key: 'message_list', type=1, flags=3, data=Index
06/09 16:28:19 yS0   key: 'backup_message', type=1, flags=3,
data=standard.rmd
06/09 16:28:19 yS0   key: 'button_program', type=1, flags=3, data=
06/09 16:28:19 yS0   key: 'call_program', type=1, flags=3, data=
06/09 16:28:19 yS0   key: 'dtmf_program', type=1, flags=3,
data=dtmf.sh
06/09 16:28:19 yS0   key: 'message_program', type=1, flags=3, data=
06/09 16:28:19 yS0   key: 'do_message_light', type=3, flags=3,
data=FALSE
06/09 16:28:19 yS0   key: 'beep_frequency', type=0, flags=3, data=933
06/09 16:28:19 yS0   key: 'beep_length', type=0, flags=3, data=150
06/09 16:28:19 yS0   key: 'max_tries', type=0, flags=3, data=3
06/09 16:28:19 yS0   key: 'retry_delay', type=0, flags=3, data=5
06/09 16:28:19 yS0   key: 'dialout_timeout', type=0, flags=1, data=90
06/09 16:28:19 yS0   key: 'voice_devices', type=1, flags=1, data=
06/09 16:28:19 yS0  check for lockfiles
06/09 16:28:20 yS0   checklock: no active process has lock, will
remove
06/09 16:28:20 yS0  locking the line
06/09 16:28:20 yS0   makelock(ttyS0) called
06/09 16:28:20 yS0   do_makelock: lock='/var/lock/LCK..ttyS0'
06/09 16:28:20 yS0   lock made
06/09 16:28:20 yS0   tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
06/09 16:28:20 yS0  WARNING: DCD line still active, check modem
settings (AT&Dx)
06/09 16:28:20 yS0  lowering DTR to reset Modem
06/09 16:28:21 yS0  TIOCMBIS failed: I/O error
06/09 16:28:21 yS0  cannot turn off soft carrier: I/O error
06/09 16:28:21 yS0  tcgetattr failed: I/O error
06/09 16:28:21 yS0  cannot get TIO: I/O error
06/09 16:28:21 yS0  mg_init_device failed, trying again
06/09 16:28:21 yS0   tio_get_rs232_lines: status: RTS CTS DSR DTR
06/09 16:28:21 yS0  lowering DTR to reset Modem
06/09 16:28:21 yS0   tss: set speed to 38400 (017)
06/09 16:28:21 yS0   tio_set_flow_control( HARD )
06/09 16:28:21 yS0   waiting for line to clear (VTIME), read: 
06/09 16:28:21 yS0  send: \dATQ0V1H0[0d]
06/09 16:28:22 yS0  waiting for ``OK''
06/09 16:28:22 yS0   got: [0d][0a]OK ** found **
06/09 16:28:23 yS0  send: ATS0=0Q0&D3&C1[0d]
06/09 16:28:23 yS0  waiting for ``OK''
06/09 16:28:23 yS0   got: [0d]
06/09 16:28:23 yS0    CND: OK[0a]ATS0=0Q0&D3&C1[0d]
06/09 16:28:23 yS0    CND: ATS0=0Q0&D3&C1[0d][0a]OK ** found **
06/09 16:28:23 yS0  mdm_send: 'ATI'
06/09 16:28:23 yS0    got:[0d][0a]ATI[0d]
06/09 16:28:23 yS0    got:[0d][0a]282[0d]
06/09 16:28:23 yS0   mdm_gis: string 1: '282'
06/09 16:28:23 yS0    got:[0a][0d][0a]OK[0d]
06/09 16:28:23 yS0   mdm_identify: string '282'
06/09 16:28:23 yS0  ELSA MicroLink 28.8 TQV detected
06/09 16:28:23 yS0  mdm_send: 'ATI3'
06/09 16:28:23 yS0    got:[0a]ATI3[0d]
06/09 16:28:23 yS0    got:[0d][0a]Ver. 1.08 vom 05.05.95[0d]
06/09 16:28:23 yS0   mdm_gis: string 1: 'Ver. 1.08 vom 05.05.95'
06/09 16:28:23 yS0    got:[0a][0d][0a]OK[0d]
06/09 16:28:23 yS0  additional info: 'Ver. 1.08 vom 05.05.95'
06/09 16:28:23 yS0  mdm_send: 'AT+FCLASS=2.0'
06/09 16:28:23 yS0    got:[0a]AT+FCLASS=2.0[0d]
06/09 16:28:23 yS0   mdm_command: string 'AT+FCLASS=2.0'
06/09 16:28:23 yS0    got:[0d][0a]OK[0d]
06/09 16:28:23 yS0   mdm_command: string 'OK' -> OK
06/09 16:28:23 yS0  mdm_send: 'AT+FAA=1;+FCR=1'
06/09 16:28:23 yS0    got:[0a]AT+FAA=1;+FCR=1[0d]
06/09 16:28:23 yS0   mdm_command: string 'AT+FAA=1;+FCR=1'
06/09 16:28:23 yS0    got:[0d][0a]OK[0d]
06/09 16:28:23 yS0   mdm_command: string 'OK' -> OK
06/09 16:28:23 yS0  mdm_send: 'AT+FBO=1'
06/09 16:28:23 yS0    got:[0a]AT+FBO=1[0d]
06/09 16:28:23 yS0   mdm_command: string 'AT+FBO=1'
06/09 16:28:23 yS0    got:[0d][0a]OK[0d]
06/09 16:28:23 yS0   mdm_command: string 'OK' -> OK
06/09 16:28:24 yS0  mdm_send: 'AT+FNR=1,1,1,0'
06/09 16:28:24 yS0    got:[0a]AT+FNR=1,1,1,0[0d]
06/09 16:28:24 yS0   mdm_command: string 'AT+FNR=1,1,1,0'
06/09 16:28:24 yS0    got:[0d][0a]OK[0d]
06/09 16:28:24 yS0   mdm_command: string 'OK' -> OK
06/09 16:28:24 yS0  mdm_send: 'AT+FLI="+49-2843-860072"'
06/09 16:28:24 yS0    got:[0a]AT+FLI="+49-2843-860072"[0d]
06/09 16:28:24 yS0   mdm_command: string 'AT+FLI="+49-2843-860072"'
06/09 16:28:24 yS0    got:[0d][0a]OK[0d]
06/09 16:28:24 yS0   mdm_command: string 'OK' -> OK
06/09 16:28:24 yS0  mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
06/09 16:28:24 yS0    got:[0a]AT+FCC=1,5,0,2,0,0,0,0[0d]
06/09 16:28:24 yS0   mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
06/09 16:28:24 yS0    got:[0d][0a]OK[0d]
06/09 16:28:24 yS0   mdm_command: string 'OK' -> OK
06/09 16:28:24 yS0   tss: set speed to 38400 (017)
06/09 16:28:24 yS0   tio_set_flow_control( HARD )
06/09 16:28:24 yS0  detecting voice modem type
06/09 16:28:24 yS0    vgetty: ATE1
06/09 16:28:24 yS0   voice command: '' -> 'OK|ATE1'
06/09 16:28:24 yS0    serial port: ATE1
06/09 16:28:24 yS0    serial port: OK
06/09 16:28:24 yS0   voice command: 'ATI' -> ''
06/09 16:28:24 yS0    vgetty: ATI
06/09 16:28:24 yS0    serial port: ATI
06/09 16:28:24 yS0    serial port: 282
06/09 16:28:24 yS0    Elsa MicroLink: OK
06/09 16:28:24 yS0  Elsa MicroLink detected
06/09 16:28:24 yS0   vgetty: entering voice mode
06/09 16:28:24 yS0   vgetty: Installing signal handlers
06/09 16:28:24 yS0   voice command: 'AT#CLS?' -> ''
06/09 16:28:25 yS0    vgetty: AT#CLS?
06/09 16:28:25 yS0    Elsa MicroLink: AT#CLS?
06/09 16:28:25 yS0    Elsa MicroLink: 2
06/09 16:28:25 yS0   voice command: '' -> 'OK'
06/09 16:28:25 yS0    Elsa MicroLink: OK
06/09 16:28:25 yS0   voice command: 'AT#CLS=8' -> 'OK'
06/09 16:28:25 yS0    vgetty: AT#CLS=8
06/09 16:28:25 yS0    Elsa MicroLink: AT#CLS=8
06/09 16:28:25 yS0    Elsa MicroLink: OK
06/09 16:28:25 yS0    vgetty: queued event RESET_WATCHDOG at position
0000
06/09 16:28:25 yS0  initializing Elsa voice modem
06/09 16:28:25 yS0   voice command: 'AT#VSP=70' -> 'OK'
06/09 16:28:25 yS0    vgetty: AT#VSP=70
06/09 16:28:25 yS0    vgetty: unqueued event RESET_WATCHDOG at
position 0000
06/09 16:28:25 yS0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
06/09 16:28:25 yS0    Elsa MicroLink: AT#VSP=70
06/09 16:28:25 yS0    Elsa MicroLink: OK
06/09 16:28:25 yS0   voice command: 'AT#VSD=0' -> 'OK'
06/09 16:28:25 yS0    vgetty: AT#VSD=0
06/09 16:28:25 yS0    Elsa MicroLink: AT#VSD=0
06/09 16:28:25 yS0    Elsa MicroLink: OK
06/09 16:28:25 yS0   voice command: 'AT#VBS=4' -> 'OK'
06/09 16:28:25 yS0    vgetty: AT#VBS=4
06/09 16:28:25 yS0    Elsa MicroLink: AT#VBS=4
06/09 16:28:25 yS0    Elsa MicroLink: OK
06/09 16:28:25 yS0   voice command: 'AT#BDR=16' -> 'OK'
06/09 16:28:25 yS0    vgetty: AT#BDR=16
06/09 16:28:25 yS0    Elsa MicroLink: AT#BDR=16
06/09 16:28:25 yS0    Elsa MicroLink: OK
06/09 16:28:25 yS0   voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
06/09 16:28:25 yS0    vgetty: AT#VTD=3F,3F,3F
06/09 16:28:25 yS0    Elsa MicroLink: AT#VTD=3F,3F,3F
06/09 16:28:25 yS0    Elsa MicroLink: OK
06/09 16:28:25 yS0   voice command: 'AT#VSS=1' -> 'OK'
06/09 16:28:26 yS0    vgetty: AT#VSS=1
06/09 16:28:26 yS0    Elsa MicroLink: AT#VSS=1
06/09 16:28:26 yS0    Elsa MicroLink: OK
06/09 16:28:26 yS0   voice command: 'ATS30=60' -> 'OK'
06/09 16:28:26 yS0    vgetty: ATS30=60
06/09 16:28:26 yS0    Elsa MicroLink: ATS30=60
06/09 16:28:26 yS0    Elsa MicroLink: OK
06/09 16:28:26 yS0   voice command: 'AT#VGT=191' -> 'OK'
06/09 16:28:26 yS0    vgetty: AT#VGT=191
06/09 16:28:26 yS0    Elsa MicroLink: AT#VGT=191
06/09 16:28:26 yS0    Elsa MicroLink: OK
06/09 16:28:26 yS0   voice command: 'AT#VGR=191' -> 'OK'
06/09 16:28:26 yS0    vgetty: AT#VGR=191
06/09 16:28:26 yS0    Elsa MicroLink: AT#VGR=191
06/09 16:28:26 yS0    Elsa MicroLink: OK
06/09 16:28:26 yS0    vgetty: queued event RESET_WATCHDOG at position
0001
06/09 16:28:26 yS0    Elsa MicroLink: _DIALUP: (2)
06/09 16:28:26 yS0   voice command: 'AT#VLS=4' -> 'OK'
06/09 16:28:26 yS0    vgetty: AT#VLS=4
06/09 16:28:26 yS0    vgetty: unqueued event RESET_WATCHDOG at
position 0001
06/09 16:28:26 yS0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
06/09 16:28:26 yS0    Elsa MicroLink: AT#VLS=4
06/09 16:28:26 yS0    Elsa MicroLink: OK
06/09 16:28:26 yS0   voice command: 'AT\Q3' -> 'OK'
06/09 16:28:26 yS0    vgetty: AT\Q3
06/09 16:28:26 yS0    Elsa MicroLink: AT\Q3
06/09 16:28:26 yS0    Elsa MicroLink: OK
06/09 16:28:26 yS0   tio_set_flow_control( HARD )
06/09 16:28:26 yS0   vgetty: leaving voice mode
06/09 16:28:26 yS0   voice command: 'AT#CLS=2' -> 'OK'
06/09 16:28:26 yS0    vgetty: AT#CLS=2
06/09 16:28:26 yS0    Elsa MicroLink: AT#CLS=2
06/09 16:28:26 yS0    Elsa MicroLink: OK
06/09 16:28:26 yS0   vgetty: Restoring signal handlers
06/09 16:28:26 yS0   waiting for line to clear (VTIME), read: 
06/09 16:28:27 yS0   removing lock file
06/09 16:28:27 yS0  waiting...
06/09 16:28:48 yS0    select returned 1
06/09 16:28:48 yS0   checking lockfiles, locking the line
06/09 16:28:48 yS0   makelock(ttyS0) called
06/09 16:28:48 yS0   do_makelock: lock='/var/lock/LCK..ttyS0'
06/09 16:28:48 yS0   lock made
06/09 16:28:48 yS0    vgetty: number of rings (2) was set directly
06/09 16:28:48 yS0    vgetty: decremented number of rings (2) by 0
06/09 16:28:48 yS0  waiting for ``RING_''
06/09 16:28:48 yS0   got: [0d]
06/09 16:28:48 yS0    CND: OK[0a]RING[0d]
06/09 16:28:48 yS0    CND: RING ** found **
06/09 16:28:48 yS0  waiting for ``RING_''
06/09 16:28:48 yS0   got: [0a][0d][0a]RING[0d]
06/09 16:28:54 yS0    CND: RING ** found **
06/09 16:28:54 yS0  reading ring_type ring configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
06/09 16:28:54 yS0   reading
/usr/local/etc/mgetty+sendfax/voice.conf...
06/09 16:28:54 yS0   conf lib: read: 'part generic'
06/09 16:28:54 yS0   found CT_KEYWORD part generic
06/09 16:28:54 yS0   conf lib: read: 'voice_log_level 6'
06/09 16:28:54 yS0   conf lib: read: 'voice_dir /var/spool/voice'
06/09 16:28:54 yS0   conf lib: read: 'message_flag_file .flag'
06/09 16:28:54 yS0   conf lib: read: 'receive_dir incoming'
06/09 16:28:54 yS0   conf lib: read: 'message_dir messages'
06/09 16:28:54 yS0   conf lib: read: 'message_list Index'
06/09 16:28:54 yS0   conf lib: read: 'backup_message standard.rmd'
06/09 16:28:54 yS0   conf lib: read: 'port_speed 38400'
06/09 16:28:54 yS0   conf lib: read: 'voice_shell /bin/sh'
06/09 16:28:54 yS0   conf lib: read: 'port_timeout 10'
06/09 16:28:54 yS0   conf lib: read: 'dial_timeout 90'
06/09 16:28:54 yS0   conf lib: read: 'command_delay 100'
06/09 16:28:54 yS0   conf lib: read: 'dtmf_len 30'
06/09 16:28:54 yS0   conf lib: read: 'dtmf_threshold 40'
06/09 16:28:54 yS0   conf lib: read: 'dtmf_wait 7'
06/09 16:28:54 yS0   conf lib: read: 'ignore_fax_dle false'
06/09 16:28:54 yS0   conf lib: read: 'raw_data false'
06/09 16:28:54 yS0   conf lib: read: 'rec_compression 0'
06/09 16:28:54 yS0   conf lib: read: 'rec_speed 0'
06/09 16:28:54 yS0   conf lib: read: 'rec_silence_len 70'
06/09 16:28:54 yS0   conf lib: read: 'rec_silence_threshold 40'
06/09 16:28:54 yS0   conf lib: read: 'rec_remove_silence false'
06/09 16:28:54 yS0   conf lib: read: 'rec_max_len 300'
06/09 16:28:54 yS0   conf lib: read: 'rec_min_len 0'
06/09 16:28:54 yS0   conf lib: read: 'do_hard_flow true'
06/09 16:28:54 yS0   conf lib: read: 'beep_frequency 933'
06/09 16:28:54 yS0   conf lib: read: 'beep_length 150'
06/09 16:28:54 yS0   conf lib: read: 'max_tries 3'
06/09 16:28:54 yS0   conf lib: read: 'retry_delay 5'
06/09 16:28:54 yS0   conf lib: read: 'watchdog_timeout 60'
06/09 16:28:54 yS0   conf lib: read: 'receive_gain -1'
06/09 16:28:54 yS0   conf lib: read: 'transmit_gain -1'
06/09 16:28:54 yS0   conf lib: read: 'enable_command_echo true'
06/09 16:28:54 yS0   conf lib: read: 'program vgetty'
06/09 16:28:54 yS0   conf lib: read: 'rings 2'
06/09 16:28:54 yS0   conf lib: read: 'answer_mode voice:fax:data'
06/09 16:28:54 yS0   conf lib: read: 'force_autodetect false'
06/09 16:28:54 yS0   conf lib: read: 'toll_saver_rings 0'
06/09 16:28:54 yS0   conf lib: read: 'rec_always_keep true'
06/09 16:28:54 yS0   conf lib: read: 'button_program '
06/09 16:28:54 yS0   conf lib: read: 'call_program '
06/09 16:28:54 yS0   conf lib: read: 'dtmf_program dtmf.sh'
06/09 16:28:54 yS0   conf lib: read: 'message_program '
06/09 16:28:54 yS0   conf lib: read: 'do_message_light false'
06/09 16:28:54 yS0   conf lib: read: 'program vm'
06/09 16:28:54 yS0   conf lib: read: 'voice_devices '
06/09 16:28:54 yS0   conf lib: read: 'dialout_timeout 90'
06/09 16:28:54 yS0   conf lib: read: 'program pvf'
06/09 16:28:54 yS0   conf lib: read: 'port ttyS0'
06/09 16:28:54 yS0   conf lib: read: 'port ttyS5'
06/09 16:28:54 yS0   conf lib: read: 'ring_type virtual'
06/09 16:28:54 yS0   section: ring_type virtual, ignore
06/09 16:28:54 yS0   conf lib: read: 'answer_mode fax:data'
06/09 16:28:54 yS0   conf lib: read: 'ring_type ring'
06/09 16:28:54 yS0   section: ring_type ring, **found**
06/09 16:28:54 yS0   conf lib: read: 'ring_type ring1'
06/09 16:28:54 yS0   section: ring_type ring1, ignore
06/09 16:28:54 yS0   key: 'part', type=6, flags=4, data=(ignored)
06/09 16:28:54 yS0   key: 'program', type=6, flags=4, data=(ignored)
06/09 16:28:54 yS0   key: 'port', type=6, flags=4, data=(ignored)
06/09 16:28:54 yS0   key: 'ring_type', type=6, flags=4, data=(ignored)
06/09 16:28:54 yS0   key: 'voice_log_level', type=0, flags=3, data=6
06/09 16:28:54 yS0   key: 'voice_shell', type=1, flags=3, data=/bin/sh
06/09 16:28:54 yS0   key: 'port_speed', type=0, flags=3, data=38400
06/09 16:28:54 yS0   key: 'port_timeout', type=0, flags=3, data=10
06/09 16:28:54 yS0   key: 'dial_timeout', type=0, flags=3, data=90
06/09 16:28:54 yS0   key: 'command_delay', type=0, flags=3, data=100
06/09 16:28:54 yS0   key: 'dtmf_len', type=0, flags=3, data=30
06/09 16:28:54 yS0   key: 'dtmf_threshold', type=0, flags=3, data=40
06/09 16:28:54 yS0   key: 'dtmf_wait', type=0, flags=3, data=7
06/09 16:28:54 yS0   key: 'ignore_fax_dle', type=3, flags=3,
data=FALSE
06/09 16:28:54 yS0   key: 'raw_data', type=3, flags=3, data=FALSE
06/09 16:28:54 yS0   key: 'rec_compression', type=0, flags=3, data=0
06/09 16:28:54 yS0   key: 'rec_speed', type=0, flags=3, data=0
06/09 16:28:54 yS0   key: 'rec_silence_len', type=0, flags=3, data=70
06/09 16:28:54 yS0   key: 'rec_silence_threshold', type=0, flags=3,
data=40
06/09 16:28:54 yS0   key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
06/09 16:28:54 yS0   key: 'rec_max_len', type=0, flags=3, data=300
06/09 16:28:54 yS0   key: 'rec_min_len', type=0, flags=3, data=0
06/09 16:28:54 yS0   key: 'do_hard_flow', type=3, flags=3, data=TRUE
06/09 16:28:54 yS0   key: 'force_autodetect', type=3, flags=3,
data=FALSE
06/09 16:28:54 yS0   key: 'watchdog_timeout', type=0, flags=3, data=60
06/09 16:28:54 yS0   key: 'receive_gain', type=0, flags=3, data=50
06/09 16:28:54 yS0   key: 'transmit_gain', type=0, flags=3, data=50
06/09 16:28:54 yS0   key: 'enable_command_echo', type=3, flags=3,
data=TRUE
06/09 16:28:54 yS0   key: 'poll_interval', type=0, flags=1, data=10
06/09 16:28:54 yS0   key: 'rings', type=1, flags=3, data=2
06/09 16:28:54 yS0   key: 'answer_mode', type=1, flags=3,
data=voice:fax:data
06/09 16:28:54 yS0   key: 'toll_saver_rings', type=0, flags=3, data=0
06/09 16:28:54 yS0   key: 'rec_always_keep', type=3, flags=3,
data=TRUE
06/09 16:28:54 yS0   key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
06/09 16:28:54 yS0   key: 'phone_owner', type=1, flags=1, data=root
06/09 16:28:54 yS0   key: 'phone_group', type=1, flags=1, data=phone
06/09 16:28:54 yS0   key: 'phone_mode', type=0, flags=1, data=432
06/09 16:28:54 yS0   key: 'message_flag_file', type=1, flags=3,
data=.flag
06/09 16:28:54 yS0   key: 'receive_dir', type=1, flags=3,
data=incoming
06/09 16:28:54 yS0   key: 'message_dir', type=1, flags=3,
data=messages
06/09 16:28:54 yS0   key: 'message_list', type=1, flags=3, data=Index
06/09 16:28:54 yS0   key: 'backup_message', type=1, flags=3,
data=standard.rmd
06/09 16:28:54 yS0   key: 'button_program', type=1, flags=3, data=
06/09 16:28:54 yS0   key: 'call_program', type=1, flags=3, data=
06/09 16:28:54 yS0   key: 'dtmf_program', type=1, flags=3,
data=dtmf.sh
06/09 16:28:54 yS0   key: 'message_program', type=1, flags=3, data=
06/09 16:28:54 yS0   key: 'do_message_light', type=3, flags=3,
data=FALSE
06/09 16:28:54 yS0   key: 'beep_frequency', type=0, flags=3, data=933
06/09 16:28:54 yS0   key: 'beep_length', type=0, flags=3, data=150
06/09 16:28:54 yS0   key: 'max_tries', type=0, flags=3, data=3
06/09 16:28:54 yS0   key: 'retry_delay', type=0, flags=3, data=5
06/09 16:28:54 yS0   key: 'dialout_timeout', type=0, flags=1, data=90
06/09 16:28:54 yS0   key: 'voice_devices', type=1, flags=1, data=
06/09 16:28:54 yS0    vgetty: answer mode was set directly
06/09 16:28:54 yS0    vgetty: answer mode is [data|fax|voice]
06/09 16:28:54 yS0    vgetty: opening list file
/var/spool/voice/messages/Index
06/09 16:28:54 yS0    vgetty: found 1 messages, picked message number
1
06/09 16:28:54 yS0    vgetty: message name is
/var/spool/voice/messages/ansage.rmd
06/09 16:28:54 yS0   vgetty: entering voice mode
06/09 16:28:54 yS0   vgetty: Installing signal handlers
06/09 16:28:54 yS0   voice command: 'AT#CLS?' -> ''
06/09 16:28:54 yS0    vgetty: AT#CLS?
06/09 16:28:54 yS0    Elsa MicroLink: AT#CLS?
06/09 16:28:54 yS0    Elsa MicroLink: 2
06/09 16:28:54 yS0   voice command: '' -> 'OK'
06/09 16:28:54 yS0    Elsa MicroLink: OK
06/09 16:28:54 yS0   voice command: 'AT#CLS=8' -> 'OK'
06/09 16:28:55 yS0    vgetty: AT#CLS=8
06/09 16:28:55 yS0    Elsa MicroLink: AT#CLS=8
06/09 16:28:55 yS0    Elsa MicroLink: OK
06/09 16:28:55 yS0    vgetty: queued event RESET_WATCHDOG at position
0002
06/09 16:28:55 yS0    Elsa MicroLink: _DIALUP: (2)
06/09 16:28:55 yS0   voice command: 'AT#VLS=4' -> 'OK'
06/09 16:28:55 yS0    vgetty: AT#VLS=4
06/09 16:28:55 yS0    vgetty: unqueued event RESET_WATCHDOG at
position 0002
06/09 16:28:55 yS0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
06/09 16:28:55 yS0    Elsa MicroLink: AT#VLS=4
06/09 16:28:55 yS0    Elsa MicroLink: OK
06/09 16:28:55 yS0   voice command: 'ATA' -> 'VCON|+VCON'
06/09 16:28:55 yS0    vgetty: ATA
06/09 16:28:55 yS0    Elsa MicroLink: ATA
06/09 16:28:55 yS0    Elsa MicroLink: VCON
06/09 16:28:55 yS0  playing voice file
/var/spool/voice/messages/ansage.rmd
06/09 16:28:55 yS0   vgetty: raw modem data header found
06/09 16:28:55 yS0   vgetty: modem type Elsa found
06/09 16:28:55 yS0   vgetty: compression method 0x0004, speed 7200,
bits 4
06/09 16:28:55 yS0    vgetty: queued event RESET_WATCHDOG at position
0003
06/09 16:28:55 yS0   voice command: 'AT#VBS=4' -> 'OK'
06/09 16:28:56 yS0    vgetty: AT#VBS=4
06/09 16:28:56 yS0    vgetty: unqueued event RESET_WATCHDOG at
position 0003
06/09 16:28:56 yS0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
06/09 16:28:56 yS0    Elsa MicroLink: AT#VBS=4
06/09 16:28:56 yS0    Elsa MicroLink: OK
06/09 16:28:56 yS0    vgetty: queued event RESET_WATCHDOG at position
0004
06/09 16:28:56 yS0    vgetty: unqueued event RESET_WATCHDOG at
position 0004
06/09 16:28:56 yS0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
06/09 16:28:56 yS0   voice command: 'AT\Q3' -> 'OK'
06/09 16:28:56 yS0    vgetty: AT\Q3
06/09 16:28:56 yS0    Elsa MicroLink: AT\Q3
06/09 16:28:56 yS0    Elsa MicroLink: OK
06/09 16:28:56 yS0   tio_set_flow_control( HARD XON_OUT )
06/09 16:28:56 yS0   voice command: 'AT#VTX' -> 'CONNECT'
06/09 16:28:56 yS0    vgetty: AT#VTX
06/09 16:28:56 yS0    Elsa MicroLink: AT#VTX
06/09 16:28:56 yS0    Elsa MicroLink: CONNECT
06/09 16:28:59 yS0    Elsa MicroLink: <DLE> <e>
06/09 16:28:59 yS0    vgetty: queued event DATA_CALLING_TONE at
position 0005
06/09 16:28:59 yS0    vgetty: unqueued event DATA_CALLING_TONE at
position 0005
06/09 16:28:59 yS0    vgetty: voice_handle_event got event
DATA_CALLING_TONE with data <_>
06/09 16:28:59 yS0    vgetty: <VOICE DATA 16200 bytes>
06/09 16:28:59 yS0    vgetty: queued event RESET_WATCHDOG at position
0006
06/09 16:28:59 yS0    vgetty: unqueued event RESET_WATCHDOG at
position 0006
06/09 16:28:59 yS0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
06/09 16:28:59 yS0    vgetty: <INTERRUPT PLAY>
06/09 16:28:59 yS0   voice command: '' -> 'OK|VCON'
06/09 16:28:59 yS0    Elsa MicroLink: VCON
06/09 16:28:59 yS0    vgetty: trying data connection
06/09 16:28:59 yS0   voice command: 'AT#CLS=0' -> 'OK'
06/09 16:29:00 yS0    vgetty: AT#CLS=0
06/09 16:29:00 yS0    Elsa MicroLink: AT#CLS=0
06/09 16:29:00 yS0    Elsa MicroLink: OK
06/09 16:29:00 yS0   voice command: 'AT+FAA=0' -> 'OK'
06/09 16:29:00 yS0    vgetty: AT+FAA=0
06/09 16:29:00 yS0    Elsa MicroLink: AT+FAA=0
06/09 16:29:00 yS0    Elsa MicroLink: OK
06/09 16:29:00 yS0   vgetty: Restoring signal handlers
06/09 16:29:00 yS0  send: ATA[0d]
06/09 16:29:00 yS0  waiting for ``CONNECT''
06/09 16:29:00 yS0   got: ATA[0d]
06/09 16:29:00 yS0    CND: ATA[0d][0a]CONNECT ** found **
06/09 16:29:14 yS0  send: 
06/09 16:29:14 yS0  waiting for ``_''
06/09 16:29:14 yS0   got:  28800/LAPM/V42BIS[0d]
06/09 16:29:14 yS0    CND: CONNECT 28800/LAPM/V42BIS
06/09 16:29:14 yS0    CND: found: 28800/LAPM/V42BIS[0a] ** found **
06/09 16:29:14 yS0   waiting for line to clear (VTIME), read: 
06/09 16:29:14 yS0    looking for utmp entry... (my PID: 6667)
06/09 16:29:14 yS0   utmp + wtmp entry made
06/09 16:29:14 yS0   tio_set_flow_control( HARD )
06/09 16:29:14 yS0   print welcome banner (/etc/issue)
06/09 16:29:14 yS0   getlogname (no opts), read:~[ff]}#[c0]!}!}!}
}7}"}&} }*} } }%}&} %<[ac]}'}"}(}"}-}#}&[ba][a0]~~[ff]}#[c0]!}!}"}
}7}"}&} }*} } }%}&} %<[ac]}'}"}(}"}-}#}&LS~~[ff]}#[c0]!}!}#} }7}"}&}
}*} } }%}&} %<[ac]}'}"}(}"}-}#}&}1[fa]~~[ff]}#[c0]!}!}$} }7}"}&} }*} }
}%}&} %<[ac]}'}"}(}"}-}#}&[b1][bc]~~[ff]}#[c0]!}!}%} }4}"}&} }*} }
}%}&} %<[ac]}'}"}(}"[eb][92]~~[ff]}#[c0]!}!}&} }4}"}&} }*} } }%}&}
%<[ac]}'}"}(}"!/~~[ff]}#[c0]!}!}'} }4}"}&} }*} } }%}&}
%<[ac]}'}"}(}"h[bc]~~[ff]}#[c0]!}!}(} }4}"}&} }*} } }%}&}
%<[ac]}'}"}(}"[a8][e6]~~[ff]}#[c0]!}!})} }4}"}&} }*} } }%}&}
%<[ac]}'}"}(}"[e1]u~~[ff]}#[c0]!}!}*} }4}"}&} }*} } }%}&}
%<[ac]}'}"}(}"+[c8]~[16][01][00]/[f0][f0][03]
06/09 16:29:46 yS0   getlogname (no opts), read:,[00][ff][ef][0a]
06/09 16:29:46 yS0   tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
06/09 16:29:46 yS0    login: use login config file
/usr/local/etc/mgetty+sendfax/login.config
06/09 16:29:46 yS0   match: user='o', key=''
06/09 16:29:46 yS0   match: user='o', key='/FIDO/'
06/09 16:29:46 yS0   match: user='o', key=''
06/09 16:29:46 yS0   match: user='o', key='/AutoPPP/'
06/09 16:29:46 yS0   match: user='o', key=''
06/09 16:29:46 yS0   match: user='o', key='*'*** hit!
06/09 16:29:46 yS0   calling login: cmd='/bin/login', argv[]='login o'
06/09 16:29:46 ##### data dev=ttyS0, pid=6667, caller='none',
conn='28800/LAPM/V42BIS', name='', cmd='/bin/login', user='o'

Thanks
Sebastian