SupraExpress v/f/d transition probs
Bjoern Giesler (Bjoern.Giesler@stud.uni-karlsruhe.de)
6 May 1999 1915:48:04 GMT
Hi,
I've finally decided to nail down the ?!)$' voice/fax/data transition
problem on my SupraExpress 56e Pro. The symptom is (when being called by
a modem, haven't tried with fax):
o vgetty plays greeting message, receives modem calling tone
o vgetty aborts greeting message, tries to set answering type to fax
("AT#CLS=2, AT+FAA=1, AT+FBOR=0") (no idea what these mean
individually) and tries to answer in that mode sending "ATA"
o my modem hangs up immediately
I've appended the log file; no idea why the modem does that -- perhaps
it thinks that for picking up ("ATA"), it has to be on-hook, so it hangs
up!?
Thanks for any and all help,
--Bjoern
Here's the log:
[snip]
05/06 17:34:47 dem vgetty: experimental test release 0.9.1 / 17Nov98
05/06 17:34:47 dem mgetty: experimental test release 1.1.20-Jan17
05/06 17:34:47 dem reading generic configuration from config file
/usr/local/etc/mgetty+sendfax/voice.conf
05/06 17:34:47 dem reading program vgetty configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
05/06 17:34:47 dem reading /usr/local/etc/mgetty+sendfax/voice.conf...
05/06 17:34:47 dem conf lib: read: 'part generic'
05/06 17:34:47 dem found CT_KEYWORD part generic
05/06 17:34:47 dem conf lib: read: 'voice_log_level 6'
05/06 17:34:47 dem conf lib: read: 'voice_dir /var/spool/voice'
05/06 17:34:47 dem conf lib: read: 'message_flag_file .flag'
05/06 17:34:47 dem conf lib: read: 'receive_dir incoming'
05/06 17:34:47 dem conf lib: read: 'message_dir messages'
05/06 17:34:47 dem conf lib: read: 'message_list Index'
05/06 17:34:47 dem conf lib: read: 'backup_message standard.adpcm'
05/06 17:34:47 dem conf lib: read: 'voice_shell /bin/sh'
05/06 17:34:47 dem conf lib: read: 'port_timeout 120'
05/06 17:34:47 dem conf lib: read: 'dial_timeout 90'
05/06 17:34:47 dem conf lib: read: 'command_delay 100'
05/06 17:34:47 dem conf lib: read: 'dtmf_len 30'
05/06 17:34:47 dem conf lib: read: 'dtmf_threshold 40'
05/06 17:34:47 dem conf lib: read: 'dtmf_wait 7'
05/06 17:34:47 dem conf lib: read: 'ignore_fax_dle false'
05/06 17:34:47 dem conf lib: read: 'raw_data false'
05/06 17:34:47 dem conf lib: read: 'rec_compression 0'
05/06 17:34:47 dem conf lib: read: 'rec_speed 0'
05/06 17:34:47 dem conf lib: read: 'rec_silence_len 70'
05/06 17:34:47 dem conf lib: read: 'rec_silence_threshold 40'
05/06 17:34:47 dem conf lib: read: 'rec_remove_silence false'
05/06 17:34:47 dem conf lib: read: 'rec_max_len 300'
05/06 17:34:47 dem conf lib: read: 'rec_min_len 0'
05/06 17:34:47 dem conf lib: read: 'do_hard_flow true'
05/06 17:34:47 dem conf lib: read: 'beep_frequency 933'
05/06 17:34:47 dem conf lib: read: 'beep_length 100'
05/06 17:34:47 dem conf lib: read: 'max_tries 3'
05/06 17:34:47 dem conf lib: read: 'retry_delay 5'
05/06 17:34:47 dem conf lib: read: 'watchdog_timeout 60'
05/06 17:34:47 dem conf lib: read: 'receive_gain -1'
05/06 17:34:47 dem conf lib: read: 'transmit_gain -1'
05/06 17:34:47 dem conf lib: read: 'enable_command_echo true'
05/06 17:34:47 dem conf lib: read: 'program vgetty'
05/06 17:34:47 dem section: program vgetty, **found**
05/06 17:34:47 dem conf lib: read: 'rings 3'
05/06 17:34:47 dem conf lib: read: 'answer_mode voice:fax:data'
05/06 17:34:47 dem conf lib: read: 'force_autodetect true'
05/06 17:34:47 dem conf lib: read: 'toll_saver_rings 0'
05/06 17:34:47 dem conf lib: read: 'rec_always_keep true'
05/06 17:34:47 dem conf lib: read: 'button_program '
05/06 17:34:47 dem conf lib: read: 'call_program '
05/06 17:34:47 dem conf lib: read: 'dtmf_program '
05/06 17:34:47 dem conf lib: read: 'message_program '
05/06 17:34:47 dem conf lib: read: 'do_message_light true'
05/06 17:34:47 dem conf lib: read: 'program vm'
05/06 17:34:47 dem section: program vm, ignore
05/06 17:34:47 dem conf lib: read: 'voice_devices modem'
05/06 17:34:47 dem conf lib: read: 'dialout_timeout 90'
05/06 17:34:47 dem conf lib: read: 'program pvf'
05/06 17:34:47 dem section: program pvf, ignore
05/06 17:34:47 dem conf lib: read: 'port modem'
05/06 17:34:47 dem conf lib: read: 'port ttyS5'
05/06 17:34:47 dem conf lib: read: 'ring_type virtual'
05/06 17:34:47 dem conf lib: read: 'answer_mode voice:fax:data'
05/06 17:34:47 dem conf lib: read: 'ring_type ring'
05/06 17:34:47 dem conf lib: read: 'ring_type ring1'
05/06 17:34:47 dem key: 'part', type=6, flags=4, data=(ignored)
05/06 17:34:47 dem key: 'program', type=6, flags=4, data=(ignored)
05/06 17:34:47 dem key: 'port', type=6, flags=4, data=(ignored)
05/06 17:34:47 dem key: 'ring_type', type=6, flags=4, data=(ignored)
05/06 17:34:47 dem key: 'voice_log_level', type=0, flags=3, data=6
05/06 17:34:47 dem key: 'voice_shell', type=1, flags=3, data=/bin/sh
05/06 17:34:47 dem key: 'port_speed', type=0, flags=1, data=38400
05/06 17:34:47 dem key: 'port_timeout', type=0, flags=3, data=120
05/06 17:34:47 dem key: 'dial_timeout', type=0, flags=3, data=90
05/06 17:34:47 dem key: 'command_delay', type=0, flags=3, data=100
05/06 17:34:47 dem key: 'dtmf_len', type=0, flags=3, data=30
05/06 17:34:47 dem key: 'dtmf_threshold', type=0, flags=3, data=40
05/06 17:34:47 dem key: 'dtmf_wait', type=0, flags=3, data=7
05/06 17:34:47 dem key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
05/06 17:34:47 dem key: 'raw_data', type=3, flags=3, data=FALSE
05/06 17:34:47 dem key: 'rec_compression', type=0, flags=3, data=0
05/06 17:34:47 dem key: 'rec_speed', type=0, flags=3, data=0
05/06 17:34:47 dem key: 'rec_silence_len', type=0, flags=3, data=70
05/06 17:34:47 dem key: 'rec_silence_threshold', type=0, flags=3, data=40
05/06 17:34:47 dem key: 'rec_remove_silence', type=3, flags=3, data=FALSE
05/06 17:34:47 dem key: 'rec_max_len', type=0, flags=3, data=300
05/06 17:34:47 dem key: 'rec_min_len', type=0, flags=3, data=0
05/06 17:34:47 dem key: 'do_hard_flow', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'force_autodetect', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'watchdog_timeout', type=0, flags=3, data=60
05/06 17:34:47 dem key: 'receive_gain', type=0, flags=3, data=-1
05/06 17:34:47 dem key: 'transmit_gain', type=0, flags=3, data=-1
05/06 17:34:47 dem key: 'enable_command_echo', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'poll_interval', type=0, flags=1, data=10
05/06 17:34:47 dem key: 'rings', type=1, flags=3, data=3
05/06 17:34:47 dem key: 'answer_mode', type=1, flags=3, data=voice:fax:data
05/06 17:34:47 dem key: 'toll_saver_rings', type=0, flags=3, data=0
05/06 17:34:47 dem key: 'rec_always_keep', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
05/06 17:34:47 dem key: 'phone_owner', type=1, flags=1, data=root
05/06 17:34:47 dem key: 'phone_group', type=1, flags=1, data=phone
05/06 17:34:47 dem key: 'phone_mode', type=0, flags=1, data=432
05/06 17:34:47 dem key: 'message_flag_file', type=1, flags=3, data=.flag
05/06 17:34:47 dem key: 'receive_dir', type=1, flags=3, data=incoming
05/06 17:34:47 dem key: 'message_dir', type=1, flags=3, data=messages
05/06 17:34:47 dem key: 'message_list', type=1, flags=3, data=Index
05/06 17:34:47 dem key: 'backup_message', type=1, flags=3, data=standard.adpcm
05/06 17:34:47 dem key: 'button_program', type=1, flags=3, data=
05/06 17:34:47 dem key: 'call_program', type=1, flags=3, data=
05/06 17:34:47 dem key: 'dtmf_program', type=1, flags=3, data=
05/06 17:34:47 dem key: 'message_program', type=1, flags=3, data=
05/06 17:34:47 dem key: 'do_message_light', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'beep_frequency', type=0, flags=3, data=933
05/06 17:34:47 dem key: 'beep_length', type=0, flags=3, data=100
05/06 17:34:47 dem key: 'max_tries', type=0, flags=3, data=3
05/06 17:34:47 dem key: 'retry_delay', type=0, flags=3, data=5
05/06 17:34:47 dem key: 'dialout_timeout', type=0, flags=1, data=90
05/06 17:34:47 dem key: 'voice_devices', type=1, flags=1, data=
05/06 17:34:47 dem reading port modem configuration from config file /usr/local
/etc/mgetty+sendfax/voice.conf
05/06 17:34:47 dem reading /usr/local/etc/mgetty+sendfax/voice.conf...
05/06 17:34:47 dem conf lib: read: 'part generic'
05/06 17:34:47 dem found CT_KEYWORD part generic
05/06 17:34:47 dem conf lib: read: 'voice_log_level 6'
05/06 17:34:47 dem conf lib: read: 'voice_dir /var/spool/voice'
05/06 17:34:47 dem conf lib: read: 'message_flag_file .flag'
05/06 17:34:47 dem conf lib: read: 'receive_dir incoming'
05/06 17:34:47 dem conf lib: read: 'message_dir messages'
05/06 17:34:47 dem conf lib: read: 'message_list Index'
05/06 17:34:47 dem conf lib: read: 'backup_message standard.adpcm'
05/06 17:34:47 dem conf lib: read: 'voice_shell /bin/sh'
05/06 17:34:47 dem conf lib: read: 'port_timeout 120'
05/06 17:34:47 dem conf lib: read: 'dial_timeout 90'
05/06 17:34:47 dem conf lib: read: 'command_delay 100'
05/06 17:34:47 dem conf lib: read: 'dtmf_len 30'
05/06 17:34:47 dem conf lib: read: 'dtmf_threshold 40'
05/06 17:34:47 dem conf lib: read: 'dtmf_wait 7'
05/06 17:34:47 dem conf lib: read: 'ignore_fax_dle false'
05/06 17:34:47 dem conf lib: read: 'raw_data false'
05/06 17:34:47 dem conf lib: read: 'rec_compression 0'
05/06 17:34:47 dem conf lib: read: 'rec_speed 0'
05/06 17:34:47 dem conf lib: read: 'rec_silence_len 70'
05/06 17:34:47 dem conf lib: read: 'rec_silence_threshold 40'
05/06 17:34:47 dem conf lib: read: 'rec_remove_silence false'
05/06 17:34:47 dem conf lib: read: 'rec_max_len 300'
05/06 17:34:47 dem conf lib: read: 'rec_min_len 0'
05/06 17:34:47 dem conf lib: read: 'do_hard_flow true'
05/06 17:34:47 dem conf lib: read: 'beep_frequency 933'
05/06 17:34:47 dem conf lib: read: 'beep_length 100'
05/06 17:34:47 dem conf lib: read: 'max_tries 3'
05/06 17:34:47 dem conf lib: read: 'retry_delay 5'
05/06 17:34:47 dem conf lib: read: 'watchdog_timeout 60'
05/06 17:34:47 dem conf lib: read: 'receive_gain -1'
05/06 17:34:47 dem conf lib: read: 'transmit_gain -1'
05/06 17:34:47 dem conf lib: read: 'enable_command_echo true'
05/06 17:34:47 dem conf lib: read: 'program vgetty'
05/06 17:34:47 dem conf lib: read: 'rings 3'
05/06 17:34:47 dem conf lib: read: 'answer_mode voice:fax:data'
05/06 17:34:47 dem conf lib: read: 'force_autodetect true'
05/06 17:34:47 dem conf lib: read: 'toll_saver_rings 0'
05/06 17:34:47 dem conf lib: read: 'rec_always_keep true'
05/06 17:34:47 dem conf lib: read: 'button_program '
05/06 17:34:47 dem conf lib: read: 'call_program '
05/06 17:34:47 dem conf lib: read: 'dtmf_program '
05/06 17:34:47 dem conf lib: read: 'message_program '
05/06 17:34:47 dem conf lib: read: 'do_message_light true'
05/06 17:34:47 dem conf lib: read: 'program vm'
05/06 17:34:47 dem conf lib: read: 'voice_devices modem'
05/06 17:34:47 dem conf lib: read: 'dialout_timeout 90'
05/06 17:34:47 dem conf lib: read: 'program pvf'
05/06 17:34:47 dem conf lib: read: 'port modem'
05/06 17:34:47 dem section: port modem, **found**
05/06 17:34:47 dem conf lib: read: 'port ttyS5'
05/06 17:34:47 dem section: port ttyS5, ignore
05/06 17:34:47 dem conf lib: read: 'ring_type virtual'
05/06 17:34:47 dem conf lib: read: 'answer_mode voice:fax:data'
05/06 17:34:47 dem conf lib: read: 'ring_type ring'
05/06 17:34:47 dem conf lib: read: 'ring_type ring1'
05/06 17:34:47 dem key: 'part', type=6, flags=4, data=(ignored)
05/06 17:34:47 dem key: 'program', type=6, flags=4, data=(ignored)
05/06 17:34:47 dem key: 'port', type=6, flags=4, data=(ignored)
05/06 17:34:47 dem key: 'ring_type', type=6, flags=4, data=(ignored)
05/06 17:34:47 dem key: 'voice_log_level', type=0, flags=3, data=6
05/06 17:34:47 dem key: 'voice_shell', type=1, flags=3, data=/bin/sh
05/06 17:34:47 dem key: 'port_speed', type=0, flags=1, data=38400
05/06 17:34:47 dem key: 'port_timeout', type=0, flags=3, data=120
05/06 17:34:47 dem key: 'dial_timeout', type=0, flags=3, data=90
05/06 17:34:47 dem key: 'command_delay', type=0, flags=3, data=100
05/06 17:34:47 dem key: 'dtmf_len', type=0, flags=3, data=30
05/06 17:34:47 dem key: 'dtmf_threshold', type=0, flags=3, data=40
05/06 17:34:47 dem key: 'dtmf_wait', type=0, flags=3, data=7
05/06 17:34:47 dem key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
05/06 17:34:47 dem key: 'raw_data', type=3, flags=3, data=FALSE
05/06 17:34:47 dem key: 'rec_compression', type=0, flags=3, data=0
05/06 17:34:47 dem key: 'rec_speed', type=0, flags=3, data=0
05/06 17:34:47 dem key: 'rec_silence_len', type=0, flags=3, data=70
05/06 17:34:47 dem key: 'rec_silence_threshold', type=0, flags=3, data=40
05/06 17:34:47 dem key: 'rec_remove_silence', type=3, flags=3, data=FALSE
05/06 17:34:47 dem key: 'rec_max_len', type=0, flags=3, data=300
05/06 17:34:47 dem key: 'rec_min_len', type=0, flags=3, data=0
05/06 17:34:47 dem key: 'do_hard_flow', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'force_autodetect', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'watchdog_timeout', type=0, flags=3, data=60
05/06 17:34:47 dem key: 'receive_gain', type=0, flags=3, data=-1
05/06 17:34:47 dem key: 'transmit_gain', type=0, flags=3, data=-1
05/06 17:34:47 dem key: 'enable_command_echo', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'poll_interval', type=0, flags=1, data=10
05/06 17:34:47 dem key: 'rings', type=1, flags=3, data=3
05/06 17:34:47 dem key: 'answer_mode', type=1, flags=3, data=voice:fax:data
05/06 17:34:47 dem key: 'toll_saver_rings', type=0, flags=3, data=0
05/06 17:34:47 dem key: 'rec_always_keep', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
05/06 17:34:47 dem key: 'phone_owner', type=1, flags=1, data=root
05/06 17:34:47 dem key: 'phone_group', type=1, flags=1, data=phone
05/06 17:34:47 dem key: 'phone_mode', type=0, flags=1, data=432
05/06 17:34:47 dem key: 'message_flag_file', type=1, flags=3, data=.flag
05/06 17:34:47 dem key: 'receive_dir', type=1, flags=3, data=incoming
05/06 17:34:47 dem key: 'message_dir', type=1, flags=3, data=messages
05/06 17:34:47 dem key: 'message_list', type=1, flags=3, data=Index
05/06 17:34:47 dem key: 'backup_message', type=1, flags=3, data=standard.adpcm
05/06 17:34:47 dem key: 'button_program', type=1, flags=3, data=
05/06 17:34:47 dem key: 'call_program', type=1, flags=3, data=
05/06 17:34:47 dem key: 'dtmf_program', type=1, flags=3, data=
05/06 17:34:47 dem key: 'message_program', type=1, flags=3, data=
05/06 17:34:47 dem key: 'do_message_light', type=3, flags=3, data=TRUE
05/06 17:34:47 dem key: 'beep_frequency', type=0, flags=3, data=933
05/06 17:34:47 dem key: 'beep_length', type=0, flags=3, data=100
05/06 17:34:47 dem key: 'max_tries', type=0, flags=3, data=3
05/06 17:34:47 dem key: 'retry_delay', type=0, flags=3, data=5
05/06 17:34:47 dem key: 'dialout_timeout', type=0, flags=1, data=90
05/06 17:34:47 dem key: 'voice_devices', type=1, flags=1, data=
05/06 17:34:47 dem check for lockfiles
05/06 17:34:47 dem checklock: stat failed, no file
05/06 17:34:47 dem locking the line
05/06 17:34:47 dem makelock(modem) called
05/06 17:34:47 dem do_makelock: lock='/var/lock/LCK..modem'
05/06 17:34:47 dem lock made
05/06 17:34:48 dem tio_get_rs232_lines: status: RTS CTS DSR DTR
05/06 17:34:48 dem lowering DTR to reset Modem
05/06 17:34:48 dem tss: set speed to 38400 (017)
05/06 17:34:48 dem tio_set_flow_control( HARD )
05/06 17:34:48 dem waiting for line to clear (VTIME), read:
05/06 17:34:49 dem send: AT[0d]
05/06 17:34:49 dem waiting for ``OK''
05/06 17:34:49 dem got: AT[0d]
05/06 17:34:49 dem CND: AT[0d][0a]OK ** found **
05/06 17:34:49 dem mdm_send: 'ATI'
05/06 17:34:49 dem got:[0d][0a]ATI[0d]
05/06 17:34:49 dem got:[0d][0a]56000[0d]
05/06 17:34:49 dem mdm_gis: string 1: '56000'
05/06 17:34:49 dem got:[0a][0d][0a]OK[0d]
05/06 17:34:49 dem mdm_identify: string '56000'
05/06 17:34:49 dem Generic Rockwell modem (56000)
05/06 17:34:49 dem mdm_send: 'ATI3'
05/06 17:34:49 dem got:[0a]ATI3[0d]
05/06 17:34:49 dem got:[0d][0a]Rev 2.000-01 (c) 1998[0d]
05/06 17:34:49 dem mdm_gis: string 1: 'Rev 2.000-01 (c) 1998'
05/06 17:34:49 dem got:[0a]SupraExpress 56e PRO[0d]
05/06 17:34:49 dem mdm_gis: string 2: 'SupraExpress 56e PRO'
05/06 17:34:49 dem got:[0a][0d][0a][0d][0a]OK[0d]
05/06 17:34:49 dem mdm_send: 'ATI4'
05/06 17:34:49 dem got:[0a]ATI4[0d]
05/06 17:34:49 dem got:[0d][0a]OK[0d]
05/06 17:34:49 dem additional info: ''
05/06 17:34:49 dem modem quirks: 0004
05/06 17:34:49 dem mdm_send: 'AT+FCLASS=2'
05/06 17:34:49 dem got:[0a]AT+FCLASS=2[0d]
05/06 17:34:49 dem mdm_command: string 'AT+FCLASS=2'
05/06 17:34:49 dem got:[0d][0a]OK[0d]
05/06 17:34:49 dem mdm_command: string 'OK' -> OK
05/06 17:34:49 dem mdm_send: 'AT+FCLASS=0'
05/06 17:34:49 dem got:[0a]AT+FCLASS=0[0d]
05/06 17:34:49 dem mdm_command: string 'AT+FCLASS=0'
05/06 17:34:49 dem got:[0d][0a]OK[0d]
05/06 17:34:49 dem mdm_command: string 'OK' -> OK
05/06 17:34:49 dem mdm_send: 'AT+FAA=1;+FCR=1'
05/06 17:34:49 dem got:[0a]AT+FAA=1;+FCR=1[0d]
05/06 17:34:49 dem mdm_command: string 'AT+FAA=1;+FCR=1'
05/06 17:34:49 dem got:[0d][0a]OK[0d]
05/06 17:34:49 dem mdm_command: string 'OK' -> OK
05/06 17:34:49 dem mdm_send: 'AT+FBOR=0'
05/06 17:34:49 dem got:[0a]AT+FBOR=0[0d]
05/06 17:34:49 dem mdm_command: string 'AT+FBOR=0'
05/06 17:34:49 dem got:[0d][0a]OK[0d]
05/06 17:34:49 dem mdm_command: string 'OK' -> OK
05/06 17:34:49 dem mdm_send: 'AT+FLID="49 721 853213"'
05/06 17:34:49 dem got:[0a]AT+FLID="49 721 853213"[0d]
05/06 17:34:49 dem mdm_command: string 'AT+FLID="49 721 853213"'
05/06 17:34:49 dem got:[0d][0a]OK[0d]
05/06 17:34:49 dem mdm_command: string 'OK' -> OK
05/06 17:34:49 dem mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0'
05/06 17:34:49 dem got:[0a]AT+FDCC=1,5,0,2,0,0,0,0[0d]
05/06 17:34:49 dem mdm_command: string 'AT+FDCC=1,5,0,2,0,0,0,0'
05/06 17:34:49 dem got:[0d][0a]OK[0d]
05/06 17:34:49 dem mdm_command: string 'OK' -> OK
05/06 17:34:49 dem tss: set speed to 38400 (017)
05/06 17:34:49 dem tio_set_flow_control( HARD )
05/06 17:34:49 dem detecting voice modem type
05/06 17:34:49 dem vgetty: ATE1
05/06 17:34:49 dem voice command: '' -> 'OK|ATE1'
05/06 17:34:49 dem serial port: ATE1
05/06 17:34:49 dem serial port: OK
05/06 17:34:49 dem voice command: 'ATI' -> ''
05/06 17:34:50 dem vgetty: ATI
05/06 17:34:50 dem serial port: ATI
05/06 17:34:50 dem serial port: 56000
05/06 17:34:50 dem Rockwell: OK
05/06 17:34:50 dem Rockwell detected
05/06 17:34:50 dem vgetty: entering voice mode
05/06 17:34:50 dem vgetty: Installing signal handlers
05/06 17:34:50 dem voice command: 'AT#CLS?' -> ''
05/06 17:34:50 dem vgetty: AT#CLS?
05/06 17:34:50 dem Rockwell: AT#CLS?
05/06 17:34:50 dem Rockwell: 0
05/06 17:34:50 dem voice command: '' -> 'OK'
05/06 17:34:50 dem Rockwell: OK
05/06 17:34:50 dem voice command: 'AT#CLS=8' -> 'OK'
05/06 17:34:50 dem vgetty: AT#CLS=8
05/06 17:34:50 dem Rockwell: AT#CLS=8
05/06 17:34:50 dem Rockwell: OK
05/06 17:34:50 dem voice command: 'AT' -> 'OK'
05/06 17:34:50 dem vgetty: AT
05/06 17:34:50 dem Rockwell: <DLE> <h>
05/06 17:34:50 dem vgetty: queued event HANDSET_ON_HOOK at position 0000
05/06 17:34:50 dem Rockwell:
05/06 17:34:50 dem Rockwell: AT
05/06 17:34:50 dem Rockwell: OK
05/06 17:34:50 dem vgetty: queued event RESET_WATCHDOG at position 0001
05/06 17:34:50 dem initializing ROCKWELL voice modem
05/06 17:34:50 dem voice command: 'AT#VSP=70' -> 'OK'
05/06 17:34:50 dem vgetty: AT#VSP=70
05/06 17:34:50 dem vgetty: unqueued event HANDSET_ON_HOOK at position 0000
05/06 17:34:50 dem vgetty: voice_handle_event got event HANDSET_ON_HOOK with
data <
05/06 17:34:50 dem vgetty: unqueued event RESET_WATCHDOG at position 0001
05/06 17:34:50 dem vgetty: voice_handle_event got event RESET_WATCHDOG with d
ata <
05/06 17:34:50 dem Rockwell: AT#VSP=70
05/06 17:34:50 dem Rockwell: OK
05/06 17:34:50 dem voice command: 'AT#VSD=0' -> 'OK'
05/06 17:34:51 dem vgetty: AT#VSD=0
05/06 17:34:51 dem Rockwell: AT#VSD=0
05/06 17:34:51 dem Rockwell: OK
05/06 17:34:51 dem voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
05/06 17:34:51 dem vgetty: AT#VTD=3F,3F,3F
05/06 17:34:51 dem Rockwell: AT#VTD=3F,3F,3F
05/06 17:34:51 dem Rockwell: OK
05/06 17:34:51 dem voice command: 'AT#VSS=1' -> 'OK'
05/06 17:34:51 dem vgetty: AT#VSS=1
05/06 17:34:51 dem Rockwell: AT#VSS=1
05/06 17:34:51 dem Rockwell: OK
05/06 17:34:51 dem voice command: 'AT&K3' -> 'OK'
05/06 17:34:51 dem vgetty: AT&K3
05/06 17:34:51 dem Rockwell: AT&K3
05/06 17:34:51 dem Rockwell: OK
05/06 17:34:51 dem tio_set_flow_control( HARD )
05/06 17:34:51 dem vgetty: leaving voice mode
05/06 17:34:51 dem voice command: 'AT#CLS=0' -> 'OK'
05/06 17:34:51 dem vgetty: AT#CLS=0
05/06 17:34:51 dem Rockwell: AT#CLS=0
05/06 17:34:52 dem Rockwell: OK
05/06 17:34:52 dem voice command: 'AT' -> 'OK'
05/06 17:34:52 dem vgetty: AT
05/06 17:34:52 dem Rockwell: AT
05/06 17:34:52 dem Rockwell: OK
05/06 17:34:52 dem vgetty: Restoring signal handlers
05/06 17:34:52 dem vgetty: checking for message flag file /var/spool/voice/.f
lag
05/06 17:34:52 dem voice command: 'ATS0=0' -> 'OK'
05/06 17:34:52 dem vgetty: ATS0=0
05/06 17:34:52 dem Rockwell: ATS0=0
05/06 17:34:52 dem Rockwell: OK
05/06 17:34:52 dem waiting for line to clear (VTIME), read:
05/06 17:34:52 dem removing lock file
05/06 17:34:52 dem waiting...
05/06 17:35:07 dem select returned 1
05/06 17:35:07 dem checking lockfiles, locking the line
05/06 17:35:07 dem makelock(modem) called
05/06 17:35:07 dem do_makelock: lock='/var/lock/LCK..modem'
05/06 17:35:07 dem lock made
05/06 17:35:07 dem vgetty: number of rings (3) was set directly
05/06 17:35:07 dem wfr: waiting for ``RING''
05/06 17:35:07 dem got: [0a][0d][0a]RING[0d]
05/06 17:35:07 dem CND: RING
05/06 17:35:07 dem wfr: rc=0, drn=0
05/06 17:35:07 dem wfr: waiting for ``RING''
05/06 17:35:07 dem got: [0a][0d][0a]RING[0d]
05/06 17:35:13 dem CND: RING
05/06 17:35:13 dem wfr: rc=0, drn=0
05/06 17:35:13 dem wfr: waiting for ``RING''
05/06 17:35:13 dem got: [0a][0d][0a]RING[0d]
05/06 17:35:19 dem CND: RING
05/06 17:35:19 dem wfr: rc=0, drn=0
05/06 17:35:19 dem reading ring_type ring configuration from config file /usr/l
ocal/etc/mgetty+sendfax/voice.conf
05/06 17:35:19 dem reading /usr/local/etc/mgetty+sendfax/voice.conf...
05/06 17:35:19 dem conf lib: read: 'part generic'
05/06 17:35:19 dem found CT_KEYWORD part generic
05/06 17:35:19 dem conf lib: read: 'voice_log_level 6'
05/06 17:35:19 dem conf lib: read: 'voice_dir /var/spool/voice'
05/06 17:35:19 dem conf lib: read: 'message_flag_file .flag'
05/06 17:35:19 dem conf lib: read: 'receive_dir incoming'
05/06 17:35:19 dem conf lib: read: 'message_dir messages'
05/06 17:35:19 dem conf lib: read: 'message_list Index'
05/06 17:35:19 dem conf lib: read: 'backup_message standard.adpcm'
05/06 17:35:19 dem conf lib: read: 'voice_shell /bin/sh'
05/06 17:35:19 dem conf lib: read: 'port_timeout 120'
05/06 17:35:19 dem conf lib: read: 'dial_timeout 90'
05/06 17:35:19 dem conf lib: read: 'command_delay 100'
05/06 17:35:19 dem conf lib: read: 'dtmf_len 30'
05/06 17:35:19 dem conf lib: read: 'dtmf_threshold 40'
05/06 17:35:19 dem conf lib: read: 'dtmf_wait 7'
05/06 17:35:19 dem conf lib: read: 'ignore_fax_dle false'
05/06 17:35:19 dem conf lib: read: 'raw_data false'
05/06 17:35:19 dem conf lib: read: 'rec_compression 0'
05/06 17:35:19 dem conf lib: read: 'rec_speed 0'
05/06 17:35:19 dem conf lib: read: 'rec_silence_len 70'
05/06 17:35:19 dem conf lib: read: 'rec_silence_threshold 40'
05/06 17:35:19 dem conf lib: read: 'rec_remove_silence false'
05/06 17:35:19 dem conf lib: read: 'rec_max_len 300'
05/06 17:35:19 dem conf lib: read: 'rec_min_len 0'
05/06 17:35:19 dem conf lib: read: 'do_hard_flow true'
05/06 17:35:19 dem conf lib: read: 'beep_frequency 933'
05/06 17:35:19 dem conf lib: read: 'beep_length 100'
05/06 17:35:19 dem conf lib: read: 'max_tries 3'
05/06 17:35:19 dem conf lib: read: 'retry_delay 5'
05/06 17:35:19 dem conf lib: read: 'watchdog_timeout 60'
05/06 17:35:19 dem conf lib: read: 'receive_gain -1'
05/06 17:35:19 dem conf lib: read: 'transmit_gain -1'
05/06 17:35:19 dem conf lib: read: 'enable_command_echo true'
05/06 17:35:19 dem conf lib: read: 'program vgetty'
05/06 17:35:19 dem conf lib: read: 'rings 3'
05/06 17:35:19 dem conf lib: read: 'answer_mode voice:fax:data'
05/06 17:35:19 dem conf lib: read: 'force_autodetect true'
05/06 17:35:19 dem conf lib: read: 'toll_saver_rings 0'
05/06 17:35:19 dem conf lib: read: 'rec_always_keep true'
05/06 17:35:19 dem conf lib: read: 'button_program '
05/06 17:35:19 dem conf lib: read: 'call_program '
05/06 17:35:19 dem conf lib: read: 'dtmf_program '
05/06 17:35:19 dem conf lib: read: 'message_program '
05/06 17:35:19 dem conf lib: read: 'do_message_light true'
05/06 17:35:19 dem conf lib: read: 'program vm'
05/06 17:35:19 dem conf lib: read: 'voice_devices modem'
05/06 17:35:19 dem conf lib: read: 'dialout_timeout 90'
05/06 17:35:19 dem conf lib: read: 'program pvf'
05/06 17:35:19 dem conf lib: read: 'port modem'
05/06 17:35:19 dem conf lib: read: 'port ttyS5'
05/06 17:35:19 dem conf lib: read: 'ring_type virtual'
05/06 17:35:19 dem section: ring_type virtual, ignore
05/06 17:35:19 dem conf lib: read: 'answer_mode voice:fax:data'
05/06 17:35:19 dem conf lib: read: 'ring_type ring'
05/06 17:35:19 dem section: ring_type ring, **found**
05/06 17:35:19 dem conf lib: read: 'ring_type ring1'
05/06 17:35:19 dem section: ring_type ring1, ignore
05/06 17:35:19 dem key: 'part', type=6, flags=4, data=(ignored)
05/06 17:35:19 dem key: 'program', type=6, flags=4, data=(ignored)
05/06 17:35:19 dem key: 'port', type=6, flags=4, data=(ignored)
05/06 17:35:19 dem key: 'ring_type', type=6, flags=4, data=(ignored)
05/06 17:35:19 dem key: 'voice_log_level', type=0, flags=3, data=6
05/06 17:35:19 dem key: 'voice_shell', type=1, flags=3, data=/bin/sh
05/06 17:35:19 dem key: 'port_speed', type=0, flags=1, data=38400
05/06 17:35:19 dem key: 'port_timeout', type=0, flags=3, data=120
05/06 17:35:19 dem key: 'dial_timeout', type=0, flags=3, data=90
05/06 17:35:19 dem key: 'command_delay', type=0, flags=3, data=100
05/06 17:35:19 dem key: 'dtmf_len', type=0, flags=3, data=30
05/06 17:35:19 dem key: 'dtmf_threshold', type=0, flags=3, data=40
05/06 17:35:19 dem key: 'dtmf_wait', type=0, flags=3, data=7
05/06 17:35:19 dem key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
05/06 17:35:19 dem key: 'raw_data', type=3, flags=3, data=FALSE
05/06 17:35:19 dem key: 'rec_compression', type=0, flags=3, data=0
05/06 17:35:19 dem key: 'rec_speed', type=0, flags=3, data=0
05/06 17:35:19 dem key: 'rec_silence_len', type=0, flags=3, data=70
05/06 17:35:19 dem key: 'rec_silence_threshold', type=0, flags=3, data=40
05/06 17:35:19 dem key: 'rec_remove_silence', type=3, flags=3, data=FALSE
05/06 17:35:19 dem key: 'rec_max_len', type=0, flags=3, data=300
05/06 17:35:19 dem key: 'rec_min_len', type=0, flags=3, data=0
05/06 17:35:19 dem key: 'do_hard_flow', type=3, flags=3, data=TRUE
05/06 17:35:19 dem key: 'force_autodetect', type=3, flags=3, data=TRUE
05/06 17:35:19 dem key: 'watchdog_timeout', type=0, flags=3, data=60
05/06 17:35:19 dem key: 'receive_gain', type=0, flags=3, data=-1
05/06 17:35:19 dem key: 'transmit_gain', type=0, flags=3, data=-1
05/06 17:35:19 dem key: 'enable_command_echo', type=3, flags=3, data=TRUE
05/06 17:35:19 dem key: 'poll_interval', type=0, flags=1, data=10
05/06 17:35:19 dem key: 'rings', type=1, flags=3, data=3
05/06 17:35:19 dem key: 'answer_mode', type=1, flags=3, data=voice:fax:data
05/06 17:35:19 dem key: 'toll_saver_rings', type=0, flags=3, data=0
05/06 17:35:19 dem key: 'rec_always_keep', type=3, flags=3, data=TRUE
05/06 17:35:19 dem key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
05/06 17:35:19 dem key: 'phone_owner', type=1, flags=1, data=root
05/06 17:35:19 dem key: 'phone_group', type=1, flags=1, data=phone
05/06 17:35:19 dem key: 'phone_mode', type=0, flags=1, data=432
05/06 17:35:19 dem key: 'message_flag_file', type=1, flags=3, data=.flag
05/06 17:35:19 dem key: 'receive_dir', type=1, flags=3, data=incoming
05/06 17:35:19 dem key: 'message_dir', type=1, flags=3, data=messages
05/06 17:35:19 dem key: 'message_list', type=1, flags=3, data=Index
05/06 17:35:19 dem key: 'backup_message', type=1, flags=3, data=standard.adpcm
05/06 17:35:19 dem key: 'button_program', type=1, flags=3, data=
05/06 17:35:19 dem key: 'call_program', type=1, flags=3, data=
05/06 17:35:19 dem key: 'dtmf_program', type=1, flags=3, data=
05/06 17:35:19 dem key: 'message_program', type=1, flags=3, data=
05/06 17:35:19 dem key: 'do_message_light', type=3, flags=3, data=TRUE
05/06 17:35:19 dem key: 'beep_frequency', type=0, flags=3, data=933
05/06 17:35:19 dem key: 'beep_length', type=0, flags=3, data=100
05/06 17:35:19 dem key: 'max_tries', type=0, flags=3, data=3
05/06 17:35:19 dem key: 'retry_delay', type=0, flags=3, data=5
05/06 17:35:19 dem key: 'dialout_timeout', type=0, flags=1, data=90
05/06 17:35:19 dem key: 'voice_devices', type=1, flags=1, data=
05/06 17:35:19 dem vgetty: answer mode was set directly
05/06 17:35:19 dem vgetty: answer mode is [data|fax|voice]
05/06 17:35:19 dem vgetty: opening list file /var/spool/voice/messages/Index
05/06 17:35:19 dem vgetty: found 1 messages, picked message number 1
05/06 17:35:19 dem vgetty: message name is /var/spool/voice/messages/ansage.r
md
05/06 17:35:19 dem vgetty: entering voice mode
05/06 17:35:19 dem vgetty: Installing signal handlers
05/06 17:35:19 dem voice command: 'AT#CLS?' -> ''
05/06 17:35:19 dem vgetty: AT#CLS?
05/06 17:35:19 dem Rockwell: AT#CLS?
05/06 17:35:19 dem Rockwell: 0
05/06 17:35:19 dem voice command: '' -> 'OK'
05/06 17:35:19 dem Rockwell: OK
05/06 17:35:19 dem voice command: 'AT#CLS=8' -> 'OK'
05/06 17:35:19 dem vgetty: AT#CLS=8
05/06 17:35:19 dem Rockwell: AT#CLS=8
05/06 17:35:19 dem Rockwell: OK
05/06 17:35:19 dem voice command: 'AT' -> 'OK'
05/06 17:35:20 dem vgetty: AT
05/06 17:35:20 dem Rockwell: <DLE> <h>
05/06 17:35:20 dem vgetty: queued event HANDSET_ON_HOOK at position 0002
05/06 17:35:20 dem Rockwell:
05/06 17:35:20 dem Rockwell: AT
05/06 17:35:20 dem Rockwell: OK
05/06 17:35:20 dem vgetty: queued event RESET_WATCHDOG at position 0003
05/06 17:35:20 dem voice command: 'AT#VLS=4' -> 'OK'
05/06 17:35:20 dem vgetty: AT#VLS=4
05/06 17:35:20 dem vgetty: unqueued event HANDSET_ON_HOOK at position 0002
05/06 17:35:20 dem vgetty: voice_handle_event got event HANDSET_ON_HOOK with
data <
05/06 17:35:20 dem vgetty: unqueued event RESET_WATCHDOG at position 0003
05/06 17:35:20 dem vgetty: voice_handle_event got event RESET_WATCHDOG with d
ata <
05/06 17:35:20 dem Rockwell: AT#VLS=4
05/06 17:35:20 dem Rockwell: OK
05/06 17:35:20 dem voice command: 'ATA' -> 'VCON'
05/06 17:35:20 dem vgetty: ATA
05/06 17:35:20 dem Rockwell: ATA
05/06 17:35:21 dem Rockwell: VCON
05/06 17:35:21 dem playing voice file /var/spool/voice/messages/ansage.rmd
05/06 17:35:21 dem vgetty: raw modem data header found
05/06 17:35:21 dem vgetty: modem type Rockwell found
05/06 17:35:21 dem vgetty: compression method 0x0004, speed 7200, bits 4
05/06 17:35:21 dem vgetty: queued event RESET_WATCHDOG at position 0004
05/06 17:35:21 dem voice command: 'AT#VBS=4' -> 'OK'
05/06 17:35:21 dem vgetty: AT#VBS=4
05/06 17:35:21 dem vgetty: unqueued event RESET_WATCHDOG at position 0004
05/06 17:35:21 dem vgetty: voice_handle_event got event RESET_WATCHDOG with d
ata <d>
05/06 17:35:21 dem Rockwell: AT#VBS=4
05/06 17:35:21 dem Rockwell: OK
05/06 17:35:21 dem vgetty: queued event RESET_WATCHDOG at position 0005
05/06 17:35:21 dem vgetty: unqueued event RESET_WATCHDOG at position 0005
05/06 17:35:21 dem vgetty: voice_handle_event got event RESET_WATCHDOG with d
ata <d>
05/06 17:35:21 dem voice command: 'AT&K3' -> 'OK'
05/06 17:35:21 dem vgetty: AT&K3
05/06 17:35:21 dem Rockwell: AT&K3
05/06 17:35:21 dem Rockwell: OK
05/06 17:35:21 dem tio_set_flow_control( HARD XON_OUT )
05/06 17:35:21 dem voice command: 'AT#VTX' -> 'CONNECT'
05/06 17:35:22 dem vgetty: AT#VTX
05/06 17:35:22 dem Rockwell: AT#VTX
05/06 17:35:22 dem Rockwell: CONNECT
05/06 17:35:35 dem vgetty: <VOICE DATA 53575 bytes>
05/06 17:35:35 dem vgetty: queued event RESET_WATCHDOG at position 0006
05/06 17:35:35 dem vgetty: unqueued event RESET_WATCHDOG at position 0006
05/06 17:35:35 dem vgetty: voice_handle_event got event RESET_WATCHDOG with d
ata <d>
05/06 17:35:35 dem vgetty: <STOP PLAY>
05/06 17:35:35 dem voice command: '' -> 'OK|VCON'
05/06 17:35:35 dem Rockwell: VCON
05/06 17:35:37 dem vgetty: queued event RESET_WATCHDOG at position 0007
05/06 17:35:37 dem voice command: 'AT#VTS=[933,0,1]' -> ''
05/06 17:35:37 dem vgetty: AT#VTS=[933,0,1]
05/06 17:35:37 dem vgetty: unqueued event RESET_WATCHDOG at position 0007
05/06 17:35:37 dem vgetty: voice_handle_event got event RESET_WATCHDOG with d
ata <
05/06 17:35:37 dem Rockwell: AT#VTS=[933,0,1]
05/06 17:35:37 dem voice command: '' -> 'OK'
05/06 17:35:37 dem Rockwell: OK
05/06 17:35:37 dem recording voice file /var/spool/voice/incoming/va01205.rmd
05/06 17:35:37 dem vgetty: queued event RESET_WATCHDOG at position 0008
05/06 17:35:37 dem voice command: 'AT#VBS=2' -> 'OK'
05/06 17:35:37 dem vgetty: AT#VBS=2
05/06 17:35:37 dem vgetty: unqueued event RESET_WATCHDOG at position 0008
05/06 17:35:37 dem vgetty: voice_handle_event got event RESET_WATCHDOG with d
ata <d>
05/06 17:35:37 dem Rockwell: AT#VBS=2
05/06 17:35:37 dem Rockwell: OK
05/06 17:35:37 dem vgetty: queued event RESET_WATCHDOG at position 0009
05/06 17:35:37 dem vgetty: unqueued event RESET_WATCHDOG at position 0009
05/06 17:35:37 dem vgetty: voice_handle_event got event RESET_WATCHDOG with d
ata <d>
05/06 17:35:37 dem voice command: 'AT&K3' -> 'OK'
05/06 17:35:37 dem vgetty: AT&K3
05/06 17:35:37 dem Rockwell: AT&K3
05/06 17:35:37 dem Rockwell: OK
05/06 17:35:37 dem tio_set_flow_control( HARD XON_IN )
05/06 17:35:37 dem voice command: 'AT#VRX' -> 'CONNECT'
05/06 17:35:38 dem vgetty: AT#VRX
05/06 17:35:38 dem Rockwell: AT#VRX
05/06 17:35:38 dem Rockwell: CONNECT
05/06 17:35:45 dem Rockwell: <DLE> <s>
05/06 17:35:45 dem vgetty: queued event NO_VOICE_ENERGY at position 0010
05/06 17:35:45 dem vgetty: unqueued event NO_VOICE_ENERGY at position 0010
05/06 17:35:45 dem vgetty: voice_handle_event got event NO_VOICE_ENERGY with
data <d>
05/06 17:35:45 dem vgetty: <STOP RECORDING>
05/06 17:35:45 dem Rockwell: <VOICE DATA 12698 bytes>
05/06 17:35:45 dem Rockwell: <DLE> <ETX>
05/06 17:35:45 dem voice command: '' -> 'OK|VCON'
05/06 17:35:45 dem Rockwell: VCON
05/06 17:35:45 dem vgetty: trying data and fax connection
05/06 17:35:45 dem voice command: 'AT#CLS=2' -> 'OK'
05/06 17:35:45 dem vgetty: AT#CLS=2
05/06 17:35:45 dem Rockwell: AT#CLS=2
05/06 17:35:46 dem Rockwell: OK
05/06 17:35:46 dem voice command: 'AT' -> 'OK'
05/06 17:35:46 dem vgetty: AT
05/06 17:35:46 dem Rockwell: AT
05/06 17:35:46 dem Rockwell: OK
05/06 17:35:46 dem voice command: 'AT+FAA=1' -> 'OK'
05/06 17:35:46 dem vgetty: AT+FAA=1
05/06 17:35:46 dem Rockwell: AT+FAA=1
05/06 17:35:46 dem Rockwell: OK
05/06 17:35:46 dem vgetty: Restoring signal handlers
05/06 17:35:46 dem mdm_send: 'AT+FBOR=0'
05/06 17:35:46 dem got:[0a]AT+FBOR=0[0d]
05/06 17:35:46 dem mdm_command: string 'AT+FBOR=0'
05/06 17:35:46 dem got:[0d][0a]OK[0d]
05/06 17:35:46 dem mdm_command: string 'OK' -> OK
05/06 17:35:46 dem send: ATA[0d]
05/06 17:35:46 dem waiting for ``CONNECT''
05/06 17:35:46 dem got: ATA[0d]
05/06 17:35:46 dem CND: OKATA[0d][0a]+FHNG:
05/06 17:36:30 dem found action string: ``+FHNG:''
05/06 17:36:30 ##### failed A_FAIL dev=modem, pid=1205, caller='none', conn='',
name=''
05/06 17:36:30 dem removing lock file