vgetty won't do a data dialin connection
"Canary, Robert W." (Robert.Canary@alcoa.com)
Wed, 3 Jun 1998 10:08:58 +0200
Hi,
I have a rockwell voice modem on a Cyclom-Y. I am running vgetty-0.7.3 and
mgetty-1.1.9. I can record messages and all relatively easy, but vgetty
will not do a data connection for a dialin modem. It goes all the way
through its processes and then finally gives up and attempts to do a data
'CONNECT'. The modem will connect using just mgetty. Gert suggested
maybe the calling modems are early models that did not send the correct
signal, and that I might try using some pauses and then sending it digit or
two. I did and still would not do a data connection, although it did go to
a data connect mode quicker. So I am sending you the vgetty.ttyC0 file.
Debug is up at "6". I did not truncate it from the "waiting....", I
thought there might be something in the variables list that you might see
that I have done wrong. Another strange thing, I had to include the "ATH"
in the init-chat because when the caller modem hung up vgetty would not
reset the modem it just it left it in off hook state.
06/02 17:00:16 yC0 vgetty: experimental test release 0.7.3 / 10Aug97
06/02 17:00:16 yC0 mgetty: experimental test release 1.1.9-Aug17
06/02 17:00:16 yC0 increasing modem_check_time to 900 sec.
06/02 17:00:16 yC0 reading generic configuration from config file
/etc/mgetty+sendfax/voice.conf
06/02 17:00:16 yC0 reading /etc/mgetty+sendfax/voice.conf...
06/02 17:00:16 yC0 conf lib: read: 'part generic'
06/02 17:00:16 yC0 section: part generic, **found**
06/02 17:00:16 yC0 conf lib: read: 'voice_log_level 6'
06/02 17:00:16 yC0 conf lib: read: 'voice_dir /var/spool/voice'
06/02 17:00:16 yC0 conf lib: read: 'message_flag_file .flag'
06/02 17:00:16 yC0 conf lib: read: 'receive_dir incoming'
06/02 17:00:16 yC0 conf lib: read: 'message_dir messages'
06/02 17:00:16 yC0 conf lib: read: 'message_list Index'
06/02 17:00:16 yC0 conf lib: read: 'backup_message standard.rmd'
06/02 17:00:16 yC0 conf lib: read: 'port_speed 38400'
06/02 17:00:16 yC0 conf lib: read: 'voice_shell /bin/sh'
06/02 17:00:16 yC0 conf lib: read: 'port_timeout 10'
06/02 17:00:16 yC0 conf lib: read: 'dial_timeout 90'
06/02 17:00:16 yC0 conf lib: read: 'command_delay 100'
06/02 17:00:16 yC0 conf lib: read: 'dtmf_len 30'
06/02 17:00:16 yC0 conf lib: read: 'dtmf_threshold 40'
06/02 17:00:16 yC0 conf lib: read: 'dtmf_wait 7'
06/02 17:00:16 yC0 conf lib: read: 'ignore_fax_dle false'
06/02 17:00:16 yC0 conf lib: read: 'raw_data false'
06/02 17:00:16 yC0 conf lib: read: 'rec_compression 0'
06/02 17:00:16 yC0 conf lib: read: 'rec_speed 0'
06/02 17:00:16 yC0 conf lib: read: 'rec_silence_len 70'
06/02 17:00:16 yC0 conf lib: read: 'rec_silence_threshold 40'
06/02 17:00:16 yC0 conf lib: read: 'rec_remove_silence false'
06/02 17:00:16 yC0 conf lib: read: 'rec_max_len 300'
06/02 17:00:16 yC0 conf lib: read: 'rec_min_len 0'
06/02 17:00:16 yC0 conf lib: read: 'do_hard_flow true'
06/02 17:00:16 yC0 conf lib: read: 'beep_frequency 933'
06/02 17:00:16 yC0 conf lib: read: 'beep_length 150'
06/02 17:00:16 yC0 conf lib: read: 'max_tries 3'
06/02 17:00:16 yC0 conf lib: read: 'retry_delay 5'
06/02 17:00:16 yC0 conf lib: read: 'watchdog_timeout 60'
06/02 17:00:16 yC0 conf lib: read: 'receive_gain -1'
06/02 17:00:16 yC0 conf lib: read: 'transmit_gain 100'
06/02 17:00:16 yC0 conf lib: read: 'enable_command_echo true'
06/02 17:00:16 yC0 conf lib: read: 'program vgetty'
06/02 17:00:16 yC0 found CT_KEYWORD program vgetty
06/02 17:00:16 yC0 conf lib: read: 'rings 2'
06/02 17:00:16 yC0 conf lib: read: 'answer_mode voice:fax:data'
06/02 17:00:16 yC0 conf lib: read: 'force_autodetect false'
06/02 17:00:16 yC0 conf lib: read: 'toll_saver_rings 0'
06/02 17:00:16 yC0 conf lib: read: 'rec_always_keep true'
06/02 17:00:16 yC0 conf lib: read: 'button_program '
06/02 17:00:16 yC0 conf lib: read: 'call_program '
06/02 17:00:16 yC0 conf lib: read: 'dtmf_program dtmf.sh'
06/02 17:00:16 yC0 conf lib: read: 'message_program '
06/02 17:00:16 yC0 conf lib: read: 'do_message_light true'
06/02 17:00:16 yC0 conf lib: read: 'program vm'
06/02 17:00:16 yC0 conf lib: read: 'voice_devices ttyC0'
06/02 17:00:16 yC0 conf lib: read: 'dialout_timeout 90'
06/02 17:00:16 yC0 conf lib: read: 'program pvf'
06/02 17:00:16 yC0 conf lib: read: 'ring_type ring'
06/02 17:00:16 yC0 key: 'part', type=6, flags=4, data=(ignored)
06/02 17:00:16 yC0 key: 'program', type=6, flags=4, data=(ignored)
06/02 17:00:16 yC0 key: 'port', type=6, flags=4, data=(ignored)
06/02 17:00:16 yC0 key: 'ring_type', type=6, flags=4, data=(ignored)
06/02 17:00:16 yC0 key: 'voice_log_level', type=0, flags=3, data=6
06/02 17:00:16 yC0 key: 'voice_devices', type=1, flags=0, data=(empty)
06/02 17:00:16 yC0 key: 'port_speed', type=0, flags=3, data=38400
06/02 17:00:16 yC0 key: 'port_timeout', type=0, flags=3, data=10
06/02 17:00:16 yC0 key: 'dtmf_len', type=0, flags=3, data=30
06/02 17:00:16 yC0 key: 'dtmf_threshold', type=0, flags=3, data=40
06/02 17:00:16 yC0 key: 'dtmf_wait', type=0, flags=3, data=7
06/02 17:00:16 yC0 key: 'rec_compression', type=0, flags=3, data=0
06/02 17:00:16 yC0 key: 'rec_speed', type=0, flags=3, data=0
06/02 17:00:16 yC0 key: 'rec_silence_len', type=0, flags=3, data=70
06/02 17:00:16 yC0 key: 'rec_silence_threshold', type=0, flags=3,
data=40
06/02 17:00:16 yC0 key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
06/02 17:00:16 yC0 key: 'rec_max_len', type=0, flags=3, data=300
06/02 17:00:16 yC0 key: 'receive_gain', type=0, flags=3, data=-1
06/02 17:00:16 yC0 key: 'transmit_gain', type=0, flags=3, data=100
06/02 17:00:16 yC0 key: 'rings', type=1, flags=1, data=3
06/02 17:00:16 yC0 key: 'answer_mode', type=1, flags=1,
data=voice:fax:data
06/02 17:00:16 yC0 key: 'toll_saver_rings', type=0, flags=1, data=0
06/02 17:00:16 yC0 key: 'rec_always_keep', type=3, flags=1, data=TRUE
06/02 17:00:16 yC0 key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
06/02 17:00:16 yC0 key: 'message_flag_file', type=1, flags=3, data=.flag
06/02 17:00:16 yC0 key: 'receive_dir', type=1, flags=3, data=incoming
06/02 17:00:16 yC0 key: 'message_dir', type=1, flags=3, data=messages
06/02 17:00:16 yC0 key: 'message_list', type=1, flags=3, data=Index
06/02 17:00:16 yC0 key: 'backup_message', type=1, flags=3,
data=standard.rmd
06/02 17:00:16 yC0 key: 'dialout_timeout', type=0, flags=1, data=90
06/02 17:00:16 yC0 key: 'beep_frequency', type=0, flags=3, data=933
06/02 17:00:16 yC0 key: 'beep_length', type=0, flags=3, data=150
06/02 17:00:16 yC0 key: 'raw_data', type=3, flags=3, data=FALSE
06/02 17:00:16 yC0 key: 'max_tries', type=0, flags=3, data=3
06/02 17:00:16 yC0 key: 'retry_delay', type=0, flags=3, data=5
06/02 17:00:16 yC0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
06/02 17:00:16 yC0 key: 'button_program', type=1, flags=1, data=
06/02 17:00:16 yC0 key: 'call_program', type=1, flags=1, data=
06/02 17:00:16 yC0 key: 'dtmf_program', type=1, flags=1, data=dtmf.sh
06/02 17:00:16 yC0 key: 'message_program', type=1, flags=1, data=
06/02 17:00:16 yC0 key: 'do_message_light', type=3, flags=1, data=FALSE
06/02 17:00:16 yC0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
06/02 17:00:16 yC0 key: 'force_autodetect', type=3, flags=1, data=FALSE
06/02 17:00:16 yC0 key: 'watchdog_timeout', type=0, flags=3, data=60
06/02 17:00:16 yC0 key: 'rec_min_len', type=0, flags=3, data=0
06/02 17:00:16 yC0 key: 'command_delay', type=0, flags=3, data=100
06/02 17:00:16 yC0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
06/02 17:00:16 yC0 key: 'dial_timeout', type=0, flags=3, data=90
06/02 17:00:16 yC0 key: 'enable_command_echo', type=3, flags=3,
data=TRUE
06/02 17:00:16 yC0 reading program vgetty configuration from config file
/etc/mgetty+sendfax/voice.conf
06/02 17:00:16 yC0 reading port ttyC0 configuration from config file
/etc/mgetty+sendfax/voice.conf
06/02 17:00:16 yC0 reading /etc/mgetty+sendfax/voice.conf...
06/02 17:00:16 yC0 conf lib: read: 'part generic'
06/02 17:00:16 yC0 found CT_KEYWORD part generic
06/02 17:00:16 yC0 conf lib: read: 'voice_log_level 6'
06/02 17:00:16 yC0 conf lib: read: 'voice_dir /var/spool/voice'
06/02 17:00:16 yC0 conf lib: read: 'message_flag_file .flag'
06/02 17:00:16 yC0 conf lib: read: 'receive_dir incoming'
06/02 17:00:16 yC0 conf lib: read: 'message_dir messages'
06/02 17:00:16 yC0 conf lib: read: 'message_list Index'
06/02 17:00:16 yC0 conf lib: read: 'backup_message standard.rmd'
06/02 17:00:16 yC0 conf lib: read: 'port_speed 38400'
06/02 17:00:16 yC0 conf lib: read: 'voice_shell /bin/sh'
06/02 17:00:16 yC0 conf lib: read: 'port_timeout 10'
06/02 17:00:16 yC0 conf lib: read: 'dial_timeout 90'
06/02 17:00:16 yC0 conf lib: read: 'command_delay 100'
06/02 17:00:16 yC0 conf lib: read: 'dtmf_len 30'
06/02 17:00:16 yC0 conf lib: read: 'dtmf_threshold 40'
06/02 17:00:16 yC0 conf lib: read: 'dtmf_wait 7'
06/02 17:00:16 yC0 conf lib: read: 'ignore_fax_dle false'
06/02 17:00:16 yC0 conf lib: read: 'raw_data false'
06/02 17:00:16 yC0 conf lib: read: 'rec_compression 0'
06/02 17:00:16 yC0 conf lib: read: 'rec_speed 0'
06/02 17:00:16 yC0 conf lib: read: 'rec_silence_len 70'
06/02 17:00:16 yC0 conf lib: read: 'rec_silence_threshold 40'
06/02 17:00:16 yC0 conf lib: read: 'rec_remove_silence false'
06/02 17:00:16 yC0 conf lib: read: 'rec_max_len 300'
06/02 17:00:16 yC0 conf lib: read: 'rec_min_len 0'
06/02 17:00:16 yC0 conf lib: read: 'do_hard_flow true'
06/02 17:00:16 yC0 conf lib: read: 'beep_frequency 933'
06/02 17:00:16 yC0 conf lib: read: 'beep_length 150'
06/02 17:00:16 yC0 conf lib: read: 'max_tries 3'
06/02 17:00:16 yC0 conf lib: read: 'retry_delay 5'
06/02 17:00:16 yC0 conf lib: read: 'watchdog_timeout 60'
06/02 17:00:16 yC0 conf lib: read: 'receive_gain -1'
06/02 17:00:16 yC0 conf lib: read: 'transmit_gain 100'
06/02 17:00:16 yC0 conf lib: read: 'enable_command_echo true'
06/02 17:00:16 yC0 conf lib: read: 'program vgetty'
06/02 17:00:16 yC0 conf lib: read: 'rings 2'
06/02 17:00:16 yC0 conf lib: read: 'answer_mode voice:fax:data'
06/02 17:00:16 yC0 conf lib: read: 'force_autodetect false'
06/02 17:00:16 yC0 conf lib: read: 'toll_saver_rings 0'
06/02 17:00:16 yC0 conf lib: read: 'rec_always_keep true'
06/02 17:00:16 yC0 conf lib: read: 'button_program '
06/02 17:00:16 yC0 conf lib: read: 'call_program '
06/02 17:00:16 yC0 conf lib: read: 'dtmf_program dtmf.sh'
06/02 17:00:16 yC0 conf lib: read: 'message_program '
06/02 17:00:16 yC0 conf lib: read: 'do_message_light true'
06/02 17:00:16 yC0 conf lib: read: 'program vm'
06/02 17:00:16 yC0 conf lib: read: 'voice_devices ttyC0'
06/02 17:00:16 yC0 conf lib: read: 'dialout_timeout 90'
06/02 17:00:16 yC0 conf lib: read: 'program pvf'
06/02 17:00:16 yC0 conf lib: read: 'ring_type ring'
06/02 17:00:16 yC0 key: 'part', type=6, flags=4, data=(ignored)
06/02 17:00:16 yC0 key: 'program', type=6, flags=4, data=(ignored)
06/02 17:00:16 yC0 key: 'port', type=6, flags=4, data=(ignored)
06/02 17:00:16 yC0 key: 'ring_type', type=6, flags=4, data=(ignored)
06/02 17:00:16 yC0 key: 'voice_log_level', type=0, flags=3, data=6
06/02 17:00:16 yC0 key: 'voice_devices', type=1, flags=0, data=(empty)
06/02 17:00:16 yC0 key: 'port_speed', type=0, flags=3, data=38400
06/02 17:00:16 yC0 key: 'port_timeout', type=0, flags=3, data=10
06/02 17:00:16 yC0 key: 'dtmf_len', type=0, flags=3, data=30
06/02 17:00:16 yC0 key: 'dtmf_threshold', type=0, flags=3, data=40
06/02 17:00:16 yC0 key: 'dtmf_wait', type=0, flags=3, data=7
06/02 17:00:16 yC0 key: 'rec_compression', type=0, flags=3, data=0
06/02 17:00:16 yC0 key: 'rec_speed', type=0, flags=3, data=0
06/02 17:00:16 yC0 key: 'rec_silence_len', type=0, flags=3, data=70
06/02 17:00:16 yC0 key: 'rec_silence_threshold', type=0, flags=3,
data=40
06/02 17:00:16 yC0 key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
06/02 17:00:16 yC0 key: 'rec_max_len', type=0, flags=3, data=300
06/02 17:00:16 yC0 key: 'receive_gain', type=0, flags=3, data=-1
06/02 17:00:16 yC0 key: 'transmit_gain', type=0, flags=3, data=100
06/02 17:00:16 yC0 key: 'rings', type=1, flags=3, data=2
06/02 17:00:16 yC0 key: 'answer_mode', type=1, flags=3,
data=voice:fax:data
06/02 17:00:16 yC0 key: 'toll_saver_rings', type=0, flags=3, data=0
06/02 17:00:16 yC0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
06/02 17:00:16 yC0 key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
06/02 17:00:16 yC0 key: 'message_flag_file', type=1, flags=3, data=.flag
06/02 17:00:16 yC0 key: 'receive_dir', type=1, flags=3, data=incoming
06/02 17:00:16 yC0 key: 'message_dir', type=1, flags=3, data=messages
06/02 17:00:16 yC0 key: 'message_list', type=1, flags=3, data=Index
06/02 17:00:16 yC0 key: 'backup_message', type=1, flags=3,
data=standard.rmd
06/02 17:00:16 yC0 key: 'dialout_timeout', type=0, flags=1, data=90
06/02 17:00:16 yC0 key: 'beep_frequency', type=0, flags=3, data=933
06/02 17:00:16 yC0 key: 'beep_length', type=0, flags=3, data=150
06/02 17:00:16 yC0 key: 'raw_data', type=3, flags=3, data=FALSE
06/02 17:00:16 yC0 key: 'max_tries', type=0, flags=3, data=3
06/02 17:00:16 yC0 key: 'retry_delay', type=0, flags=3, data=5
06/02 17:00:16 yC0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
06/02 17:00:16 yC0 key: 'button_program', type=1, flags=3, data=
06/02 17:00:16 yC0 key: 'call_program', type=1, flags=3, data=
06/02 17:00:16 yC0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
06/02 17:00:16 yC0 key: 'message_program', type=1, flags=3, data=
06/02 17:00:16 yC0 key: 'do_message_light', type=3, flags=3, data=TRUE
06/02 17:00:16 yC0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
06/02 17:00:16 yC0 key: 'force_autodetect', type=3, flags=3, data=FALSE
06/02 17:00:16 yC0 key: 'watchdog_timeout', type=0, flags=3, data=60
06/02 17:00:16 yC0 key: 'rec_min_len', type=0, flags=3, data=0
06/02 17:00:16 yC0 key: 'command_delay', type=0, flags=3, data=100
06/02 17:00:16 yC0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
06/02 17:00:16 yC0 key: 'dial_timeout', type=0, flags=3, data=90
06/02 17:00:16 yC0 key: 'enable_command_echo', type=3, flags=3,
data=TRUE
06/02 17:00:16 yC0 check for lockfiles
06/02 17:00:16 yC0 checklock: stat failed, no file
06/02 17:00:16 yC0 locking the line
06/02 17:00:16 yC0 makelock(ttyC0) called
06/02 17:00:16 yC0 do_makelock: lock='/var/lock/LCK..ttyC0'
06/02 17:00:16 yC0 lock made
06/02 17:00:17 yC0 tio_get_rs232_lines: status: RTS CTS DSR DTR
06/02 17:00:17 yC0 lowering DTR to reset Modem
06/02 17:00:18 yC0 tss: set speed to 57600 (10001)
06/02 17:00:18 yC0 tio_set_flow_control( HARD )
06/02 17:00:18 yC0 waiting for line to clear (VTIME), read:
06/02 17:00:18 yC0 send: ATH[0d]
06/02 17:00:18 yC0 waiting for ``OK''
06/02 17:00:18 yC0 got: ATH[0d]
06/02 17:00:18 yC0 CND: ATH[0d][0a]OK ** found **
06/02 17:00:18 yC0 send: AT&F0&C1&D2E1Q0X4S0=0M0L3\N3-SSE=1#VLS=0[0d]
06/02 17:00:18 yC0 waiting for ``OK''
06/02 17:00:18 yC0 got: [0d]
06/02 17:00:18 yC0 CND:
OK[0a]AT&F0&C1&D2E1Q0X4S0=0M0L3\N3-SSE=1#VLS=0[0d]
06/02 17:00:18 yC0 CND:
AT&F0&C1&D2E1Q0X4S0=0M0L3\N3-SSE=1#VLS=0[0d][0a]OK ** found **
06/02 17:00:18 yC0 mdm_send: 'ATI'
06/02 17:00:18 yC0 got:[0d][0a]ATI[0d]
06/02 17:00:18 yC0 got:[0d][0a]33600[0d]
06/02 17:00:18 yC0 mdm_gis: string 1: '33600'
06/02 17:00:18 yC0 got:[0a][0d][0a]OK[0d]
06/02 17:00:18 yC0 mdm_identify: string '33600'
06/02 17:00:18 yC0 Generic Rockwell modem (33600)
06/02 17:00:18 yC0 mdm_send: 'ATI3'
06/02 17:00:18 yC0 got:[0a]ATI3[0d]
06/02 17:00:18 yC0 got:[0d][0a]V1.520-V34_DSVD_DS[0d]
06/02 17:00:18 yC0 mdm_gis: string 1: 'V1.520-V34_DSVD_DS'
06/02 17:00:18 yC0 got:[0a][0d][0a]OK[0d]
06/02 17:00:18 yC0 mdm_send: 'ATI4'
06/02 17:00:18 yC0 got:[0a]ATI4[0d]
06/02 17:00:18 yC0 got:[0d][0a]a007840284C6002F[0d]
06/02 17:00:18 yC0 mdm_gis: string 1: 'a007840284C6002F'
06/02 17:00:18 yC0 got:[0a][0d][0a]bC60000000[0d]
06/02 17:00:18 yC0 mdm_gis: string 2: 'bC60000000'
06/02 17:00:18 yC0 got:[0a][0d][0a]r1005111151012000[0d]
06/02 17:00:18 yC0 mdm_gis: string 3: 'r1005111151012000'
06/02 17:00:18 yC0 got:[0a][0d][0a]r3000111170000000[0d]
06/02 17:00:18 yC0 mdm_gis: string 4: 'r3000111170000000'
06/02 17:00:18 yC0 got:[0a][0d][0a]OK[0d]
06/02 17:00:18 yC0 additional info: 'a007840284C6002F'
06/02 17:00:18 yC0 mdm_send: 'AT+FCLASS=2'
06/02 17:00:18 yC0 got:[0a]AT+FCLASS=2[0d]
06/02 17:00:18 yC0 mdm_command: string 'AT+FCLASS=2'
06/02 17:00:18 yC0 got:[0d][0a]OK[0d]
06/02 17:00:18 yC0 mdm_command: string 'OK' -> OK
06/02 17:00:18 yC0 mdm_send: 'AT+FCLASS=0'
06/02 17:00:18 yC0 got:[0a]AT+FCLASS=0[0d]
06/02 17:00:18 yC0 mdm_command: string 'AT+FCLASS=0'
06/02 17:00:18 yC0 got:[0d][0a]OK[0d]
06/02 17:00:18 yC0 mdm_command: string 'OK' -> OK
06/02 17:00:18 yC0 mdm_send: 'AT+FAA=1;+FCR=1'
06/02 17:00:18 yC0 got:[0a]AT+FAA=1;+FCR=1[0d]
06/02 17:00:18 yC0 mdm_command: string 'AT+FAA=1;+FCR=1'
06/02 17:00:18 yC0 got:[0d][0a]OK[0d]
06/02 17:00:18 yC0 mdm_command: string 'OK' -> OK
06/02 17:00:18 yC0 mdm_send: 'AT+FBOR=0'
06/02 17:00:18 yC0 got:[0a]AT+FBOR=0[0d]
06/02 17:00:18 yC0 mdm_command: string 'AT+FBOR=0'
06/02 17:00:18 yC0 got:[0d][0a]OK[0d]
06/02 17:00:18 yC0 mdm_command: string 'OK' -> OK
06/02 17:00:18 yC0 mdm_send: 'AT+FLID="(502)298-7449"'
06/02 17:00:18 yC0 got:[0a]AT+FLID="(502)298-7449"[0d]
06/02 17:00:18 yC0 mdm_command: string 'AT+FLID="(502)298-7449"'
06/02 17:00:18 yC0 got:[0d][0a]OK[0d]
06/02 17:00:18 yC0 mdm_command: string 'OK' -> OK
06/02 17:00:19 yC0 mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0'
06/02 17:00:19 yC0 got:[0a]AT+FDCC=1,5,0,2,0,0,0,0[0d]
06/02 17:00:19 yC0 mdm_command: string 'AT+FDCC=1,5,0,2,0,0,0,0'
06/02 17:00:19 yC0 got:[0d][0a]OK[0d]
06/02 17:00:19 yC0 mdm_command: string 'OK' -> OK
06/02 17:00:19 yC0 tss: set speed to 38400 (017)
06/02 17:00:19 yC0 tio_set_flow_control( HARD )
06/02 17:00:19 yC0 detecting voice modem type
06/02 17:00:19 yC0 vgetty: ATE1
06/02 17:00:19 yC0 voice command: '' -> 'OK|ATE1'
06/02 17:00:19 yC0 serial port: ATE1
06/02 17:00:19 yC0 serial port: OK
06/02 17:00:19 yC0 voice command: 'ATI' -> ''
06/02 17:00:19 yC0 vgetty: ATI
06/02 17:00:19 yC0 serial port: ATI
06/02 17:00:19 yC0 serial port: 33600
06/02 17:00:19 yC0 Rockwell: OK
06/02 17:00:19 yC0 Rockwell detected
06/02 17:00:19 yC0 vgetty: queued event 0x4000 at position 0000
06/02 17:00:19 yC0 initializing ROCKWELL voice modem
06/02 17:00:19 yC0 vgetty: queued event 0x4000 at position 0001
06/02 17:00:19 yC0 voice command: 'AT#CLS?' -> ''
06/02 17:00:19 yC0 vgetty: AT#CLS?
06/02 17:00:19 yC0 Rockwell: AT#CLS?
06/02 17:00:19 yC0 Rockwell: 0
06/02 17:00:19 yC0 voice command: '' -> 'OK'
06/02 17:00:19 yC0 Rockwell: OK
06/02 17:00:19 yC0 voice command: 'AT#CLS=8' -> 'OK'
06/02 17:00:19 yC0 vgetty: AT#CLS=8
06/02 17:00:19 yC0 Rockwell: AT#CLS=8
06/02 17:00:19 yC0 Rockwell: OK
06/02 17:00:19 yC0 voice command: 'AT#VSP=70' -> 'OK'
06/02 17:00:20 yC0 vgetty: AT#VSP=70
06/02 17:00:20 yC0 Rockwell: <DLE> <h>
06/02 17:00:20 yC0 vgetty: queued event 0x2007 at position 0002
06/02 17:00:20 yC0 Rockwell:
06/02 17:00:20 yC0 Rockwell: AT#VSP=70
06/02 17:00:20 yC0 Rockwell: OK
06/02 17:00:20 yC0 voice command: 'AT#VSD=0' -> 'OK'
06/02 17:00:20 yC0 vgetty: AT#VSD=0
06/02 17:00:20 yC0 Rockwell: AT#VSD=0
06/02 17:00:20 yC0 Rockwell: OK
06/02 17:00:20 yC0 voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
06/02 17:00:20 yC0 vgetty: AT#VTD=3F,3F,3F
06/02 17:00:20 yC0 Rockwell: AT#VTD=3F,3F,3F
06/02 17:00:20 yC0 Rockwell: OK
06/02 17:00:20 yC0 voice command: 'AT#VSS=1' -> 'OK'
06/02 17:00:20 yC0 vgetty: AT#VSS=1
06/02 17:00:20 yC0 Rockwell: AT#VSS=1
06/02 17:00:20 yC0 Rockwell: OK
06/02 17:00:20 yC0 vgetty: queued event 0x4000 at position 0003
06/02 17:00:20 yC0 voice command: 'AT#CLS=0' -> 'OK'
06/02 17:00:20 yC0 vgetty: AT#CLS=0
06/02 17:00:20 yC0 Rockwell: AT#CLS=0
06/02 17:00:20 yC0 Rockwell: OK
06/02 17:00:20 yC0 voice command: 'AT&K3' -> 'OK'
06/02 17:00:20 yC0 vgetty: AT&K3
06/02 17:00:20 yC0 Rockwell: AT&K3
06/02 17:00:20 yC0 Rockwell: OK
06/02 17:00:20 yC0 tio_set_flow_control( HARD )
06/02 17:00:20 yC0 vgetty: checking for message flag file
/var/spool/voice/.flag
06/02 17:00:20 yC0 vgetty: queued event 0x4000 at position 0004
06/02 17:00:20 yC0 voice command: 'ATS0=254' -> 'OK'
06/02 17:00:20 yC0 vgetty: ATS0=254
06/02 17:00:20 yC0 Rockwell: ATS0=254
06/02 17:00:20 yC0 Rockwell: OK
06/02 17:00:20 yC0 waiting for line to clear (VTIME), read:
06/02 17:00:21 yC0 removing lock file
06/02 17:00:21 yC0 waiting...
06/02 17:07:44 yC0 select returned 1
06/02 17:07:44 yC0 checking lockfiles, locking the line
06/02 17:07:44 yC0 makelock(ttyC0) called
06/02 17:07:44 yC0 do_makelock: lock='/var/lock/LCK..ttyC0'
06/02 17:07:44 yC0 lock made
06/02 17:07:44 yC0 vgetty: number of rings (2) was set directly
06/02 17:07:44 yC0 vgetty: decremented number of rings (2) by 0
06/02 17:07:44 yC0 waiting for ``RING_''
06/02 17:07:44 yC0 got: [0d]
06/02 17:07:44 yC0 CND: OK[0a]RING[0d]
06/02 17:07:44 yC0 CND: RING ** found **
06/02 17:07:44 yC0 waiting for ``RING_''
06/02 17:07:44 yC0 got: [0a][0d][0a]RING[0d]
06/02 17:07:50 yC0 CND: RING ** found **
06/02 17:07:50 yC0 CND: check no: 'none'
06/02 17:07:50 yC0 reading ring_type ring configuration from config file
/etc/mgetty+sendfax/voice.conf
06/02 17:07:50 yC0 reading /etc/mgetty+sendfax/voice.conf...
06/02 17:07:50 yC0 conf lib: read: 'part generic'
06/02 17:07:50 yC0 found CT_KEYWORD part generic
06/02 17:07:50 yC0 conf lib: read: 'voice_log_level 6'
06/02 17:07:50 yC0 conf lib: read: 'voice_dir /var/spool/voice'
06/02 17:07:50 yC0 conf lib: read: 'message_flag_file .flag'
06/02 17:07:50 yC0 conf lib: read: 'receive_dir incoming'
06/02 17:07:50 yC0 conf lib: read: 'message_dir messages'
06/02 17:07:50 yC0 conf lib: read: 'message_list Index'
06/02 17:07:50 yC0 conf lib: read: 'backup_message standard.rmd'
06/02 17:07:50 yC0 conf lib: read: 'port_speed 38400'
06/02 17:07:50 yC0 conf lib: read: 'voice_shell /bin/sh'
06/02 17:07:50 yC0 conf lib: read: 'port_timeout 10'
06/02 17:07:50 yC0 conf lib: read: 'dial_timeout 90'
06/02 17:07:50 yC0 conf lib: read: 'command_delay 100'
06/02 17:07:50 yC0 conf lib: read: 'dtmf_len 30'
06/02 17:07:50 yC0 conf lib: read: 'dtmf_threshold 40'
06/02 17:07:50 yC0 conf lib: read: 'dtmf_wait 7'
06/02 17:07:50 yC0 conf lib: read: 'ignore_fax_dle false'
06/02 17:07:50 yC0 conf lib: read: 'raw_data false'
06/02 17:07:50 yC0 conf lib: read: 'rec_compression 0'
06/02 17:07:50 yC0 conf lib: read: 'rec_speed 0'
06/02 17:07:50 yC0 conf lib: read: 'rec_silence_len 70'
06/02 17:07:50 yC0 conf lib: read: 'rec_silence_threshold 40'
06/02 17:07:50 yC0 conf lib: read: 'rec_remove_silence false'
06/02 17:07:50 yC0 conf lib: read: 'rec_max_len 300'
06/02 17:07:50 yC0 conf lib: read: 'rec_min_len 0'
06/02 17:07:50 yC0 conf lib: read: 'do_hard_flow true'
06/02 17:07:50 yC0 conf lib: read: 'beep_frequency 933'
06/02 17:07:50 yC0 conf lib: read: 'beep_length 150'
06/02 17:07:50 yC0 conf lib: read: 'max_tries 3'
06/02 17:07:50 yC0 conf lib: read: 'retry_delay 5'
06/02 17:07:50 yC0 conf lib: read: 'watchdog_timeout 60'
06/02 17:07:50 yC0 conf lib: read: 'receive_gain -1'
06/02 17:07:50 yC0 conf lib: read: 'transmit_gain 100'
06/02 17:07:50 yC0 conf lib: read: 'enable_command_echo true'
06/02 17:07:50 yC0 conf lib: read: 'program vgetty'
06/02 17:07:50 yC0 conf lib: read: 'rings 2'
06/02 17:07:50 yC0 conf lib: read: 'answer_mode voice:fax:data'
06/02 17:07:50 yC0 conf lib: read: 'force_autodetect false'
06/02 17:07:50 yC0 conf lib: read: 'toll_saver_rings 0'
06/02 17:07:50 yC0 conf lib: read: 'rec_always_keep true'
06/02 17:07:50 yC0 conf lib: read: 'button_program '
06/02 17:07:50 yC0 conf lib: read: 'call_program '
06/02 17:07:50 yC0 conf lib: read: 'dtmf_program dtmf.sh'
06/02 17:07:50 yC0 conf lib: read: 'message_program '
06/02 17:07:50 yC0 conf lib: read: 'do_message_light true'
06/02 17:07:50 yC0 conf lib: read: 'program vm'
06/02 17:07:50 yC0 conf lib: read: 'voice_devices ttyC0'
06/02 17:07:50 yC0 conf lib: read: 'dialout_timeout 90'
06/02 17:07:50 yC0 conf lib: read: 'program pvf'
06/02 17:07:50 yC0 conf lib: read: 'ring_type ring'
06/02 17:07:50 yC0 section: ring_type ring, **found**
06/02 17:07:50 yC0 key: 'part', type=6, flags=4, data=(ignored)
06/02 17:07:50 yC0 key: 'program', type=6, flags=4, data=(ignored)
06/02 17:07:50 yC0 key: 'port', type=6, flags=4, data=(ignored)
06/02 17:07:50 yC0 key: 'ring_type', type=6, flags=4, data=(ignored)
06/02 17:07:50 yC0 key: 'voice_log_level', type=0, flags=3, data=6
06/02 17:07:50 yC0 key: 'voice_devices', type=1, flags=0, data=(empty)
06/02 17:07:50 yC0 key: 'port_speed', type=0, flags=3, data=38400
06/02 17:07:50 yC0 key: 'port_timeout', type=0, flags=3, data=10
06/02 17:07:50 yC0 key: 'dtmf_len', type=0, flags=3, data=30
06/02 17:07:50 yC0 key: 'dtmf_threshold', type=0, flags=3, data=40
06/02 17:07:50 yC0 key: 'dtmf_wait', type=0, flags=3, data=7
06/02 17:07:50 yC0 key: 'rec_compression', type=0, flags=3, data=0
06/02 17:07:50 yC0 key: 'rec_speed', type=0, flags=3, data=0
06/02 17:07:50 yC0 key: 'rec_silence_len', type=0, flags=3, data=70
06/02 17:07:50 yC0 key: 'rec_silence_threshold', type=0, flags=3,
data=40
06/02 17:07:50 yC0 key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
06/02 17:07:50 yC0 key: 'rec_max_len', type=0, flags=3, data=300
06/02 17:07:50 yC0 key: 'receive_gain', type=0, flags=3, data=-1
06/02 17:07:50 yC0 key: 'transmit_gain', type=0, flags=3, data=100
06/02 17:07:50 yC0 key: 'rings', type=1, flags=3, data=2
06/02 17:07:50 yC0 key: 'answer_mode', type=1, flags=3,
data=voice:fax:data
06/02 17:07:50 yC0 key: 'toll_saver_rings', type=0, flags=3, data=0
06/02 17:07:50 yC0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
06/02 17:07:50 yC0 key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
06/02 17:07:50 yC0 key: 'message_flag_file', type=1, flags=3, data=.flag
06/02 17:07:50 yC0 key: 'receive_dir', type=1, flags=3, data=incoming
06/02 17:07:50 yC0 key: 'message_dir', type=1, flags=3, data=messages
06/02 17:07:50 yC0 key: 'message_list', type=1, flags=3, data=Index
06/02 17:07:50 yC0 key: 'backup_message', type=1, flags=3,
data=standard.rmd
06/02 17:07:50 yC0 key: 'dialout_timeout', type=0, flags=1, data=90
06/02 17:07:50 yC0 key: 'beep_frequency', type=0, flags=3, data=933
06/02 17:07:50 yC0 key: 'beep_length', type=0, flags=3, data=150
06/02 17:07:50 yC0 key: 'raw_data', type=3, flags=3, data=FALSE
06/02 17:07:50 yC0 key: 'max_tries', type=0, flags=3, data=3
06/02 17:07:50 yC0 key: 'retry_delay', type=0, flags=3, data=5
06/02 17:07:50 yC0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
06/02 17:07:50 yC0 key: 'button_program', type=1, flags=3, data=
06/02 17:07:50 yC0 key: 'call_program', type=1, flags=3, data=
06/02 17:07:50 yC0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
06/02 17:07:50 yC0 key: 'message_program', type=1, flags=3, data=
06/02 17:07:50 yC0 key: 'do_message_light', type=3, flags=3, data=TRUE
06/02 17:07:50 yC0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
06/02 17:07:50 yC0 key: 'force_autodetect', type=3, flags=3, data=FALSE
06/02 17:07:50 yC0 key: 'watchdog_timeout', type=0, flags=3, data=60
06/02 17:07:50 yC0 key: 'rec_min_len', type=0, flags=3, data=0
06/02 17:07:50 yC0 key: 'command_delay', type=0, flags=3, data=100
06/02 17:07:50 yC0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
06/02 17:07:50 yC0 key: 'dial_timeout', type=0, flags=3, data=90
06/02 17:07:50 yC0 key: 'enable_command_echo', type=3, flags=3,
data=TRUE
06/02 17:07:50 yC0 vgetty: answer mode was set directly
06/02 17:07:50 yC0 vgetty: answer mode is [data|fax|voice]
06/02 17:07:50 yC0 vgetty: opening list file
/var/spool/voice/messages/Index
06/02 17:07:50 yC0 vgetty: found 1 messages, picked message number 1
06/02 17:07:50 yC0 vgetty: message name is
/var/spool/voice/messages/greetings/ogm.rmd
06/02 17:07:50 yC0 vgetty: entering voice mode
06/02 17:07:50 yC0 vgetty: Installing signal handlers
06/02 17:07:50 yC0 vgetty: queued event 0x4000 at position 0005
06/02 17:07:50 yC0 voice command: 'AT#CLS?' -> ''
06/02 17:07:50 yC0 vgetty: AT#CLS?
06/02 17:07:50 yC0 Rockwell: AT#CLS?
06/02 17:07:50 yC0 Rockwell: 0
06/02 17:07:50 yC0 voice command: '' -> 'OK'
06/02 17:07:50 yC0 Rockwell: OK
06/02 17:07:50 yC0 voice command: 'AT#CLS=8' -> 'OK'
06/02 17:07:51 yC0 vgetty: AT#CLS=8
06/02 17:07:51 yC0 Rockwell: AT#CLS=8
06/02 17:07:51 yC0 Rockwell: OK
06/02 17:07:51 yC0 vgetty: queued event 0x4000 at position 0006
06/02 17:07:51 yC0 voice command: 'ATA' -> 'VCON'
06/02 17:07:51 yC0 vgetty: ATA
06/02 17:07:51 yC0 Rockwell: <DLE> <h>
06/02 17:07:51 yC0 vgetty: queued event 0x2007 at position 0007
06/02 17:07:51 yC0 Rockwell:
06/02 17:07:51 yC0 Rockwell: ATA
06/02 17:07:51 yC0 Rockwell: VCON
06/02 17:07:51 yC0 playing voice file
/var/spool/voice/messages/greetings/ogm.rmd
06/02 17:07:51 yC0 vgetty: raw modem data header found
06/02 17:07:51 yC0 vgetty: modem type Rockwell found
06/02 17:07:51 yC0 vgetty: compression method 0x0002, speed 7200, bits 2
06/02 17:07:51 yC0 vgetty: queued event 0x4000 at position 0008
06/02 17:07:51 yC0 voice command: 'AT#VBS=2' -> 'OK'
06/02 17:07:51 yC0 vgetty: AT#VBS=2
06/02 17:07:51 yC0 Rockwell: AT#VBS=2
06/02 17:07:51 yC0 Rockwell: OK
06/02 17:07:51 yC0 vgetty: queued event 0x4000 at position 0009
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0000
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0001
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<
06/02 17:07:51 yC0 vgetty: unqueued event 0x2007 at position 0002
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x2007 with data
<
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0003
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0004
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0005
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0006
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<
06/02 17:07:51 yC0 vgetty: unqueued event 0x2007 at position 0007
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x2007 with data
<
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0008
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<h>
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0009
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<
06/02 17:07:51 yC0 voice command: 'AT&K3' -> 'OK'
06/02 17:07:51 yC0 vgetty: AT&K3
06/02 17:07:51 yC0 Rockwell: AT&K3
06/02 17:07:51 yC0 Rockwell: OK
06/02 17:07:51 yC0 tio_set_flow_control( HARD XON_OUT )
06/02 17:07:51 yC0 voice command: 'AT#VTX' -> 'CONNECT'
06/02 17:07:51 yC0 vgetty: AT#VTX
06/02 17:07:51 yC0 Rockwell: AT#VTX
06/02 17:07:51 yC0 Rockwell: CONNECT
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 182 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 183 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 182 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:51 yC0 vgetty: queued event 0x4000 at position 0010
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0010
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: queued event 0x4000 at position 0011
06/02 17:07:51 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:51 yC0 vgetty: unqueued event 0x4000 at position 0011
06/02 17:07:51 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:07:51 yC0 vgetty: <DATA 182 bytes>
06/02 17:07:51 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:52 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:52 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:52 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:52 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:52 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:52 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:52 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:52 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:52 yC0 vgetty: queued event 0x4000 at position 0012
06/02 17:07:52 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:53 yC0 vgetty: unqueued event 0x4000 at position 0012
06/02 17:07:53 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:07:53 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:53 yC0 vgetty: queued event 0x4000 at position 0013
06/02 17:07:53 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:53 yC0 vgetty: unqueued event 0x4000 at position 0013
06/02 17:07:53 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:07:53 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:53 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:54 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:54 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:54 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:54 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:54 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:54 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:54 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:54 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:54 yC0 vgetty: queued event 0x4000 at position 0014
06/02 17:07:54 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:55 yC0 vgetty: unqueued event 0x4000 at position 0014
06/02 17:07:55 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:07:55 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:55 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:55 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:55 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:55 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:55 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:55 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:55 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:55 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:56 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:56 yC0 vgetty: queued event 0x4000 at position 0015
06/02 17:07:56 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:56 yC0 vgetty: unqueued event 0x4000 at position 0015
06/02 17:07:56 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:07:56 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:56 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:56 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:56 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:56 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:56 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:56 yC0 vgetty: <DATA 182 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 182 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 183 bytes>
06/02 17:07:57 yC0 vgetty: queued event 0x4000 at position 0016
06/02 17:07:57 yC0 vgetty: <DATA 182 bytes>
06/02 17:07:57 yC0 vgetty: unqueued event 0x4000 at position 0016
06/02 17:07:57 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:07:57 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 183 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:57 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:58 yC0 vgetty: queued event 0x4000 at position 0017
06/02 17:07:58 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:58 yC0 vgetty: unqueued event 0x4000 at position 0017
06/02 17:07:58 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:07:58 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:58 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:58 yC0 vgetty: <DATA 181 bytes>
06/02 17:07:58 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:58 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:58 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:58 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:58 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:59 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:59 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:59 yC0 vgetty: queued event 0x4000 at position 0018
06/02 17:07:59 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:59 yC0 vgetty: unqueued event 0x4000 at position 0018
06/02 17:07:59 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:07:59 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:59 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:59 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:59 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:59 yC0 vgetty: <DATA 180 bytes>
06/02 17:07:59 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:00 yC0 vgetty: <DATA 182 bytes>
06/02 17:08:00 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:00 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:00 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:00 yC0 vgetty: queued event 0x4000 at position 0019
06/02 17:08:00 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:00 yC0 vgetty: unqueued event 0x4000 at position 0019
06/02 17:08:00 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:08:00 yC0 vgetty: <DATA 181 bytes>
06/02 17:08:00 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:00 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:00 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:01 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:01 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:01 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:01 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:01 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:01 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:01 yC0 vgetty: queued event 0x4000 at position 0020
06/02 17:08:01 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:01 yC0 vgetty: unqueued event 0x4000 at position 0020
06/02 17:08:01 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:08:01 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:01 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:02 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:02 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:02 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:02 yC0 vgetty: <DATA 180 bytes>
06/02 17:08:02 yC0 vgetty: <DATA 145 bytes>
06/02 17:08:02 yC0 vgetty: <DLE> <ETX>
06/02 17:08:02 yC0 voice command: '' -> 'OK|VCON'
06/02 17:08:02 yC0 Rockwell: VCON
06/02 17:08:04 yC0 voice command: 'AT' -> 'OK'
06/02 17:08:04 yC0 vgetty: AT
06/02 17:08:04 yC0 Rockwell: AT
06/02 17:08:04 yC0 Rockwell: OK
06/02 17:08:04 yC0 vgetty: queued event 0x4000 at position 0021
06/02 17:08:04 yC0 voice command: 'AT#VTS=[933,0,15]' -> 'OK'
06/02 17:08:05 yC0 vgetty: AT#VTS=[933,0,15]
06/02 17:08:05 yC0 Rockwell: AT#VTS=[933,0,15]
06/02 17:08:06 yC0 Rockwell: OK
06/02 17:08:06 yC0 recording voice file
/var/spool/voice/incoming/va18762.rmd
06/02 17:08:06 yC0 vgetty: queued event 0x4000 at position 0022
06/02 17:08:06 yC0 voice command: 'AT#VBS=2' -> 'OK'
06/02 17:08:06 yC0 vgetty: AT#VBS=2
06/02 17:08:06 yC0 Rockwell: AT#VBS=2
06/02 17:08:06 yC0 Rockwell: OK
06/02 17:08:06 yC0 vgetty: queued event 0x4000 at position 0023
06/02 17:08:06 yC0 vgetty: unqueued event 0x4000 at position 0021
06/02 17:08:06 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:08:06 yC0 vgetty: unqueued event 0x4000 at position 0022
06/02 17:08:06 yC0 vgetty: voice_handle_event got event 0x4000 with data
<
06/02 17:08:06 yC0 vgetty: unqueued event 0x4000 at position 0023
06/02 17:08:06 yC0 vgetty: voice_handle_event got event 0x4000 with data
<
06/02 17:08:06 yC0 voice command: 'AT&K3' -> 'OK'
06/02 17:08:06 yC0 vgetty: AT&K3
06/02 17:08:06 yC0 Rockwell: AT&K3
06/02 17:08:06 yC0 Rockwell: OK
06/02 17:08:06 yC0 tio_set_flow_control( HARD XON_OUT )
06/02 17:08:06 yC0 voice command: 'AT#VRX' -> 'CONNECT'
06/02 17:08:07 yC0 vgetty: AT#VRX
06/02 17:08:07 yC0 Rockwell: AT#VRX
06/02 17:08:07 yC0 Rockwell: CONNECT
06/02 17:08:07 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:07 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:07 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:07 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:07 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:07 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:07 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 vgetty: queued event 0x4000 at position 0024
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 vgetty: unqueued event 0x4000 at position 0024
06/02 17:08:08 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:08 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 vgetty: queued event 0x4000 at position 0025
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 vgetty: unqueued event 0x4000 at position 0025
06/02 17:08:09 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:09 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 vgetty: queued event 0x4000 at position 0026
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 vgetty: unqueued event 0x4000 at position 0026
06/02 17:08:10 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:10 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 vgetty: queued event 0x4000 at position 0027
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 vgetty: unqueued event 0x4000 at position 0027
06/02 17:08:11 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:11 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 vgetty: queued event 0x4000 at position 0028
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 vgetty: unqueued event 0x4000 at position 0028
06/02 17:08:12 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:12 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 vgetty: queued event 0x4000 at position 0029
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:13 yC0 vgetty: unqueued event 0x4000 at position 0029
06/02 17:08:13 yC0 vgetty: voice_handle_event got event 0x4000 with data
<_>
06/02 17:08:13 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:14 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:14 yC0 Rockwell: <DATA 180 bytes>
06/02 17:08:14 yC0 Rockwell: <DLE> <s>
06/02 17:08:14 yC0 vgetty: queued event 0x200e at position 0030
06/02 17:08:14 yC0 Rockwell: <DATA 178 bytes>
06/02 17:08:14 yC0 vgetty: unqueued event 0x200e at position 0030
06/02 17:08:14 yC0 vgetty: voice_handle_event got event 0x200e with data
<_>
06/02 17:08:14 yC0 vgetty: !
06/02 17:08:14 yC0 Rockwell: <DATA 21 bytes>
06/02 17:08:14 yC0 Rockwell: <DLE> <ETX>
06/02 17:08:14 yC0 Rockwell: OK|VCON
06/02 17:08:14 yC0 voice command: 'AT' -> 'OK'
06/02 17:08:14 yC0 vgetty: AT
06/02 17:08:14 yC0 Rockwell: AT
06/02 17:08:14 yC0 Rockwell: OK
06/02 17:08:14 yC0 vgetty: trying data and fax connection
06/02 17:08:14 yC0 vgetty: queued event 0x4000 at position 0031
06/02 17:08:14 yC0 voice command: 'AT#CLS=2' -> 'OK'
06/02 17:08:14 yC0 vgetty: AT#CLS=2
06/02 17:08:14 yC0 Rockwell: AT#CLS=2
06/02 17:08:15 yC0 Rockwell: OK
06/02 17:08:15 yC0 voice command: 'AT+FAA=1' -> 'OK'
06/02 17:08:15 yC0 vgetty: AT+FAA=1
06/02 17:08:15 yC0 Rockwell: AT+FAA=1
06/02 17:08:15 yC0 Rockwell: OK
06/02 17:08:15 yC0 mdm_send: 'AT+FBOR=0'
06/02 17:08:15 yC0 got:[0a]AT+FBOR=0[0d]
06/02 17:08:15 yC0 mdm_command: string 'AT+FBOR=0'
06/02 17:08:15 yC0 got:[0d][0a]OK[0d]
06/02 17:08:15 yC0 mdm_command: string 'OK' -> OK
06/02 17:08:15 yC0 vgetty: Restoring signal handlers
06/02 17:08:15 yC0 send: ATA[0d]
06/02 17:08:15 yC0 waiting for ``CONNECT''
06/02 17:08:15 yC0 got: ATA[0d]
06/02 17:08:15 yC0 CND: ATA[0d][0a]+FHNG:
06/02 17:09:03 yC0 found action string: ``+FHNG:''
06/02 17:09:03 ##### failed A_FAIL dev=ttyC0, pid=18762, caller=none,
conn='', name=''
06/02 17:09:03 yC0 removing lock file
--