switchbd????

Dr. Matthias Prinz (matthias@Tintin.Rhein-Main.De)
Tue, 1 Apr 1997 20:06:27 +0200
Messages sorted by:[ date ][ thread ][ subject ][ author ]
Hi,

This is again Creatix LV 144 VF under Linux.
vgetty works fine here.
But, sometimes strange things happen.
I append a log where vgetty crashes in shortly after starting to
receive a fax.
Since I have no clue who would send me a fax, it could just as well be
a normal voice-call which vgetty 'misunderstood'.
The recorde voice-file /var/spool/voice/incoming/va00081.rmd contains
silence.
I do not set the switchbd-option in mgetty.config, regardless what the
log says.
I #define FAX_RECV_SWITCHBD 19200 in policy.h, because it is
recommended there.

Could anybody help me with this error-message?

N.B.: This effect happened for the second time. But I see no way to
reproduce it.
This version of vgetty was able to receive a fax (sent by me as a
test) two weeks ago.

Matthias
--
04/01 18:08:50 dem select returned 1
04/01 18:08:50 dem checking lockfiles, locking the line
04/01 18:08:50 dem makelock(modem) called
04/01 18:08:50 dem do_makelock: lock='/var/lock/LCK..modem'
04/01 18:08:50 dem lock made
04/01 18:08:50 dem vgetty: number of rings (3) was set directly
04/01 18:08:50 dem vgetty: decremented number of rings (3) by 0
04/01 18:08:50 dem waiting for ``RING''
04/01 18:08:50 dem got: [0d]
04/01 18:08:50 dem CND: OK[0a]RING[0d]
04/01 18:08:50 dem CND: RING ** found **
04/01 18:08:50 dem waiting for ``RING''
04/01 18:08:50 dem got: [0a][0d][0a]RING[0d]
04/01 18:08:55 dem CND: RING ** found **
04/01 18:08:55 dem waiting for ``RING''
04/01 18:08:55 dem got: [0a][0d][0a]RING[0d]
04/01 18:09:01 dem CND: RING ** found **
04/01 18:09:01 dem reading ring_type ring configuration from config file /usr/local/etc/mgetty+sendfax/voice.conf
04/01 18:09:01 dem reading /usr/local/etc/mgetty+sendfax/voice.conf...
04/01 18:09:01 dem conf lib: read: 'part generic'
04/01 18:09:01 dem found CT_KEYWORD part generic
04/01 18:09:01 dem conf lib: read: 'voice_log_level 8'
04/01 18:09:01 dem conf lib: read: 'voice_dir /var/spool/voice'
04/01 18:09:01 dem conf lib: read: 'message_flag_file .flag'
04/01 18:09:01 dem conf lib: read: 'receive_dir incoming'
04/01 18:09:01 dem conf lib: read: 'message_dir messages'
04/01 18:09:01 dem conf lib: read: 'message_list Index'
04/01 18:09:01 dem conf lib: read: 'backup_message standard.rmd'
04/01 18:09:01 dem conf lib: read: 'port_speed 38400'
04/01 18:09:01 dem conf lib: read: 'voice_shell /bin/sh'
04/01 18:09:01 dem conf lib: read: 'port_timeout 10'
04/01 18:09:01 dem conf lib: read: 'dial_timeout 90'
04/01 18:09:01 dem conf lib: read: 'command_delay 200'
04/01 18:09:01 dem conf lib: read: 'dtmf_len 30'
04/01 18:09:01 dem conf lib: read: 'dtmf_threshold 40'
04/01 18:09:01 dem conf lib: read: 'dtmf_wait 7'
04/01 18:09:01 dem conf lib: read: 'ignore_fax_dle false'
04/01 18:09:01 dem conf lib: read: 'raw_data false'
04/01 18:09:01 dem conf lib: read: 'rec_compression 0'
04/01 18:09:01 dem conf lib: read: 'rec_speed 0'
04/01 18:09:01 dem conf lib: read: 'rec_silence_len 70'
04/01 18:09:01 dem conf lib: read: 'rec_silence_threshold 90'
04/01 18:09:01 dem conf lib: read: 'rec_remove_silence false'
04/01 18:09:01 dem conf lib: read: 'rec_max_len 300'
04/01 18:09:01 dem conf lib: read: 'rec_min_len 0'
04/01 18:09:01 dem conf lib: read: 'do_hard_flow true'
04/01 18:09:01 dem conf lib: read: 'beep_frequency 933'
04/01 18:09:01 dem conf lib: read: 'beep_length 150'
04/01 18:09:01 dem conf lib: read: 'max_tries 3'
04/01 18:09:01 dem conf lib: read: 'retry_delay 5'
04/01 18:09:01 dem conf lib: read: 'watchdog_timeout 40'
04/01 18:09:01 dem conf lib: read: 'program vgetty'
04/01 18:09:01 dem conf lib: read: 'speaker_answer_volume 100'
04/01 18:09:01 dem conf lib: read: 'rings 3'
04/01 18:09:01 dem conf lib: read: 'answer_mode voice:fax:data'
04/01 18:09:01 dem conf lib: read: 'force_autodetect false'
04/01 18:09:01 dem conf lib: read: 'toll_saver_rings 0'
04/01 18:09:01 dem conf lib: read: 'rec_always_keep true'
04/01 18:09:01 dem conf lib: read: 'button_program '
04/01 18:09:01 dem conf lib: read: 'call_program '
04/01 18:09:01 dem conf lib: read: 'dtmf_program dtmf.sh'
04/01 18:09:01 dem conf lib: read: 'message_program mx.sh'
04/01 18:09:01 dem conf lib: read: 'do_message_light false'
04/01 18:09:01 dem conf lib: read: 'program vm'
04/01 18:09:01 dem conf lib: read: 'voice_devices modem'
04/01 18:09:01 dem conf lib: read: 'dialout_timeout 90'
04/01 18:09:01 dem conf lib: read: 'speaker_play_volume 100'
04/01 18:09:01 dem conf lib: read: 'program pvf'
04/01 18:09:01 dem conf lib: read: 'port modem'
04/01 18:09:01 dem key: 'part', type=6, flags=4, data=(ignored)
04/01 18:09:01 dem key: 'program', type=6, flags=4, data=(ignored)
04/01 18:09:01 dem key: 'port', type=6, flags=4, data=(ignored)
04/01 18:09:01 dem key: 'ring_type', type=6, flags=4, data=(ignored)
04/01 18:09:01 dem key: 'voice_log_level', type=0, flags=3, data=8
04/01 18:09:01 dem key: 'voice_devices', type=1, flags=0, data=(empty)
04/01 18:09:01 dem key: 'port_speed', type=0, flags=3, data=38400
04/01 18:09:01 dem key: 'port_timeout', type=0, flags=3, data=10
04/01 18:09:01 dem key: 'dtmf_len', type=0, flags=3, data=30
04/01 18:09:01 dem key: 'dtmf_threshold', type=0, flags=3, data=40
04/01 18:09:01 dem key: 'dtmf_wait', type=0, flags=3, data=7
04/01 18:09:01 dem key: 'rec_compression', type=0, flags=3, data=0
04/01 18:09:01 dem key: 'rec_speed', type=0, flags=3, data=0
04/01 18:09:01 dem key: 'rec_silence_len', type=0, flags=3, data=70
04/01 18:09:01 dem key: 'rec_silence_threshold', type=0, flags=3, data=90
04/01 18:09:01 dem key: 'rec_remove_silence', type=3, flags=3, data=FALSE
04/01 18:09:01 dem key: 'rec_max_len', type=0, flags=3, data=300
04/01 18:09:01 dem key: 'speaker_answer_volume', type=0, flags=3, data=100
04/01 18:09:01 dem key: 'rings', type=1, flags=3, data=3
04/01 18:09:01 dem key: 'answer_mode', type=1, flags=3, data=voice:fax:data
04/01 18:09:01 dem key: 'toll_saver_rings', type=0, flags=3, data=0
04/01 18:09:01 dem key: 'rec_always_keep', type=3, flags=3, data=TRUE
04/01 18:09:01 dem key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
04/01 18:09:01 dem key: 'message_flag_file', type=1, flags=3, data=.flag
04/01 18:09:01 dem key: 'receive_dir', type=1, flags=3, data=incoming
04/01 18:09:01 dem key: 'message_dir', type=1, flags=3, data=messages
04/01 18:09:01 dem key: 'message_list', type=1, flags=3, data=Index
04/01 18:09:01 dem key: 'backup_message', type=1, flags=3, data=standard.rmd
04/01 18:09:01 dem key: 'dialout_timeout', type=0, flags=1, data=90
04/01 18:09:01 dem key: 'speaker_play_volume', type=0, flags=1, data=100
04/01 18:09:01 dem key: 'beep_frequency', type=0, flags=3, data=933
04/01 18:09:01 dem key: 'beep_length', type=0, flags=3, data=150
04/01 18:09:01 dem key: 'raw_data', type=3, flags=3, data=FALSE
04/01 18:09:01 dem key: 'max_tries', type=0, flags=3, data=3
04/01 18:09:01 dem key: 'retry_delay', type=0, flags=3, data=5
04/01 18:09:01 dem key: 'voice_shell', type=1, flags=3, data=/bin/sh
04/01 18:09:01 dem key: 'button_program', type=1, flags=3, data=
04/01 18:09:01 dem key: 'call_program', type=1, flags=3, data=
04/01 18:09:01 dem key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
04/01 18:09:01 dem key: 'message_program', type=1, flags=3, data=mx.sh
04/01 18:09:01 dem key: 'do_message_light', type=3, flags=3, data=FALSE
04/01 18:09:01 dem key: 'do_hard_flow', type=3, flags=3, data=TRUE
04/01 18:09:01 dem key: 'force_autodetect', type=3, flags=3, data=FALSE
04/01 18:09:01 dem key: 'watchdog_timeout', type=0, flags=3, data=40
04/01 18:09:01 dem key: 'rec_min_len', type=0, flags=3, data=0
04/01 18:09:01 dem key: 'command_delay', type=0, flags=3, data=200
04/01 18:09:01 dem key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
04/01 18:09:01 dem key: 'dial_timeout', type=0, flags=3, data=90
04/01 18:09:01 dem vgetty: answer mode was set directly
04/01 18:09:01 dem vgetty: answer mode is [data|fax|voice]
04/01 18:09:01 dem vgetty: opening list file /var/spool/voice/messages/Index
04/01 18:09:01 dem vgetty: found 1 messages, picked message number 1
04/01 18:09:01 dem vgetty: message name is /var/spool/voice/messages/hallihallo.rmd
04/01 18:09:01 dem vgetty: entering voice mode
04/01 18:09:01 dem vgetty: Installing signal handlers
04/01 18:09:01 dem voice command: 'AT#CLS?' -> ''
04/01 18:09:01 dem vgetty: AT#CLS?
04/01 18:09:01 dem Rockwell: AT#CLS?
04/01 18:09:01 dem Rockwell: 0
04/01 18:09:01 dem voice command: '' -> 'OK'
04/01 18:09:01 dem Rockwell: OK
04/01 18:09:01 dem voice command: 'AT#CLS=8' -> 'OK'
04/01 18:09:02 dem vgetty: AT#CLS=8
04/01 18:09:02 dem Rockwell: AT#CLS=8
04/01 18:09:02 dem Rockwell: OK
04/01 18:09:02 dem voice command: 'ATA' -> 'VCON'
04/01 18:09:02 dem vgetty: ATA
04/01 18:09:02 dem Rockwell: ATA
04/01 18:09:02 dem Rockwell: VCON
04/01 18:09:02 dem playing voice file /var/spool/voice/messages/hallihallo.rmd
04/01 18:09:02 dem vgetty: raw modem data header found
04/01 18:09:02 dem vgetty: modem type Rockwell found
04/01 18:09:02 dem vgetty: compression method 0x0002, speed 7200, bits 2
04/01 18:09:02 dem voice command: 'AT#VBS=2' -> 'OK'
04/01 18:09:02 dem vgetty: AT#VBS=2
04/01 18:09:02 dem Rockwell: AT#VBS=2
04/01 18:09:02 dem Rockwell: OK
04/01 18:09:02 dem voice command: 'AT&K3' -> 'OK'
04/01 18:09:03 dem vgetty: AT&K3
04/01 18:09:03 dem Rockwell: AT&K3
04/01 18:09:03 dem Rockwell: OK
04/01 18:09:03 dem tio_set_flow_control( HARD XON_OUT )
04/01 18:09:03 dem voice command: 'AT#VTX' -> 'CONNECT'
04/01 18:09:03 dem vgetty: AT#VTX
04/01 18:09:03 dem Rockwell: AT#VTX
04/01 18:09:03 dem Rockwell: CONNECT
04/01 18:09:03 dem vgetty: <DATA 180 bytes>

...

04/01 18:09:05 dem vgetty: <DATA 178 bytes>
04/01 18:09:05 dem vgetty: <DLE> <ETX>
04/01 18:09:05 dem voice command: '' -> 'OK|VCON'
04/01 18:09:05 dem Rockwell: VCON
04/01 18:09:10 dem voice command: 'AT' -> 'OK'
04/01 18:09:10 dem vgetty: AT
04/01 18:09:10 dem Rockwell: AT
04/01 18:09:10 dem Rockwell: OK
04/01 18:09:10 dem voice command: 'AT#VTS=[933,0,15]' -> 'OK'
04/01 18:09:11 dem vgetty: AT#VTS=[933,0,15]
04/01 18:09:11 dem Rockwell: AT#VTS=[933,0,15]
04/01 18:09:12 dem Rockwell: OK
04/01 18:09:12 dem recording voice file /var/spool/voice/incoming/va00081.rmd
04/01 18:09:12 dem voice command: 'AT#VBS=2' -> 'OK'
04/01 18:09:13 dem vgetty: AT#VBS=2
04/01 18:09:13 dem Rockwell: AT#VBS=2
04/01 18:09:13 dem Rockwell: OK
04/01 18:09:13 dem voice command: 'AT&K3' -> 'OK'
04/01 18:09:13 dem vgetty: AT&K3
04/01 18:09:13 dem Rockwell: AT&K3
04/01 18:09:13 dem Rockwell: OK
04/01 18:09:13 dem tio_set_flow_control( HARD XON_OUT )
04/01 18:09:13 dem voice command: 'AT#VRX' -> 'CONNECT'
04/01 18:09:13 dem vgetty: AT#VRX
04/01 18:09:13 dem Rockwell: AT#VRX
04/01 18:09:13 dem Rockwell: CONNECT
04/01 18:09:14 dem Rockwell: <DATA 180 bytes>

...

04/01 18:09:21 dem Rockwell: <DATA 180 bytes>
04/01 18:09:22 dem Rockwell: <DLE> <s>
04/01 18:09:22 dem vgetty: queued event 0x200d at position 0000
04/01 18:09:22 dem Rockwell: <DATA 178 bytes>
04/01 18:09:22 dem vgetty: unqueued event 0x200d at position 0000
04/01 18:09:22 dem vgetty: voice_handle_event got event 0x200d with data <ü>
04/01 18:09:22 dem vgetty: !
04/01 18:09:22 dem Rockwell: <DATA 18 bytes>
04/01 18:09:22 dem Rockwell: <DLE> <ETX>
04/01 18:09:22 dem Rockwell: OK|VCON
04/01 18:09:22 dem voice command: 'AT' -> 'OK'
04/01 18:09:22 dem vgetty: AT
04/01 18:09:22 dem Rockwell: AT
04/01 18:09:22 dem Rockwell: OK
04/01 18:09:22 dem vgetty: trying data and fax connection
04/01 18:09:22 dem voice command: 'AT#CLS=2' -> 'OK'
04/01 18:09:22 dem vgetty: AT#CLS=2
04/01 18:09:22 dem Rockwell: AT#CLS=2
04/01 18:09:23 dem Rockwell: OK
04/01 18:09:23 dem voice command: 'AT+FAA=1' -> 'OK'
04/01 18:09:23 dem vgetty: AT+FAA=1
04/01 18:09:23 dem Rockwell: AT+FAA=1
04/01 18:09:23 dem Rockwell: OK
04/01 18:09:23 dem mdm_send: 'AT+FBOR=0'
04/01 18:09:23 dem got:[0a]AT+FBOR=0[0d]
04/01 18:09:23 dem mdm_command: string 'AT+FBOR=0'
04/01 18:09:23 dem got:[0d][0a]OK[0d]
04/01 18:09:23 dem mdm_command: string 'OK' -> OK
04/01 18:09:23 dem vgetty: Restoring signal handlers
04/01 18:09:23 dem send: ATA[0d]
04/01 18:09:23 dem waiting for ``CONNECT''
04/01 18:09:23 dem got: ATA[0d]
04/01 18:09:23 dem CND: ATA[0d][0a]FAX
04/01 18:09:24 dem found action string: ``FAX''
04/01 18:09:24 dem start fax receiver...
04/01 18:09:24 dem fax receiver: entry
04/01 18:09:24 dem tss: set speed to 19200 (016)
04/01 18:09:24 dem fax_wait_for(OK)
04/01 18:09:24 dem got:[0a][0d][0a][00][00][00]
04/01 18:11:24 dem Warning: got alarm signal!
04/01 18:11:24 dem mdm_read_byte: read returned -1: Interrupted system call
04/01 18:11:24 dem mdm_get_line: cannot read byte, return: Interrupted system call
04/01 18:11:24 dem >> The problem seen above might be caused by a wrong value of the
04/01 18:11:24 dem >> 'switchbd' option in 'mgetty.config' (currently set to '19200')
04/01 18:11:24 dem >> try using 'switchbd 0'
04/01 18:11:24 dem tio_set_flow_control( HARD XON_IN )
04/01 18:11:25 dem fax_get_pages: no pages to receive
04/01 18:11:25 dem fax receiver: hangup & end
04/01 18:11:25 dem fax_notify_mail: sending mail to: faxadmin
04/01 18:11:25 dem notify: '/usr/local/lib/mgetty+sendfax/new_fax -6 '' 0 >/dev/console 2>&1 </dev/null'
04/01 18:11:25 ##### fax dev=modem, pid=81, caller=, name='', id='', +FHNG=-06, pages=0, time=00:02:24


04/01 18:11:27 dem mdm_send: 'ATH0'
04/01 18:11:27 dem got:ATH0[0d]
04/01 18:11:27 dem mdm_command: string 'ATH0'
04/01 18:11:27 dem got:[0d][0a]OK[0d]
04/01 18:11:27 dem mdm_command: string 'OK' -> OK
04/01 18:11:27 dem removing lock file
--
-- 
Matthias Prinz
-- 
Matthias Prinz
.