vgetty does not switch to data (but mgetty does!)
Dmitry Pogosyan (pogosyan@cita.utoronto.ca)
Tue, 11 May 1999 21:39:57 -0400
Hi,
First of all, thanks Gert for the advice to set fax-max-speed 9600
to fight with my problem that after fax initialization with
+FDCC=1,5,0,2,0,0,0,0 all reception occurs at crawling 1,0,0,2,0,0,0,3.
Restricting speed seems really to help = 1,3,..... mode is stable.
Let us consider this a problem of Zoom fax firmware, I guess.
(One question, does sendfax use separate fax-max-speed, as to continue
to send faxes at 14400 ?)
The problem I encontered now is different. Running mgetty, both
fax reception and remote login works great. If, however, I run vgetty
in voice/fax/data mode, voice and fax works fine, however program
fails if it is incoming data. I have included short log of
succesfull fax, and longer of failed data call.
I tried both 'force-autodetect' false and true with same result
(actually, I have not noticed any difference in log ??)
Would be much obliged for the advice !
Dmitri Pogosyan
------------- succesfull fax -----------------
05/11 18:27:48 yS3 vgetty: <STOP RECORDING>
05/11 18:27:48 yS3 Rockwell: <VOICE DATA 2209 bytes>
05/11 18:27:48 yS3 Rockwell: <DLE> <ETX>
05/11 18:27:48 yS3 voice command: '' -> 'OK|VCON'
05/11 18:27:48 yS3 Rockwell: VCON
05/11 18:27:48 yS3 vgetty: trying fax connection
05/11 18:27:48 yS3 voice command: 'AT#CLS=2' -> 'OK'
05/11 18:27:48 yS3 vgetty: AT#CLS=2
05/11 18:27:48 yS3 Rockwell: OK
05/11 18:27:49 yS3 voice command: 'AT' -> 'OK'
05/11 18:27:49 yS3 vgetty: AT
05/11 18:27:49 yS3 Rockwell: OK
05/11 18:27:49 yS3 voice command: 'AT+FAA=0' -> 'OK'
05/11 18:27:49 yS3 vgetty: AT+FAA=0
05/11 18:27:49 yS3 Rockwell: OK
05/11 18:27:49 yS3 vgetty: Restoring signal handlers
05/11 18:27:49 yS3 mdm_send: 'AT+FBOR=0'
05/11 18:27:49 yS3 got:[0a][0d][0a]OK[0d]
05/11 18:27:49 yS3 mdm_command: string 'OK' -> OK
05/11 18:27:49 yS3 send: ATA[0d]
05/11 18:27:49 yS3 waiting for ``CONNECT''
05/11 18:27:49 yS3 got: [0d]
05/11 18:27:55 yS3 CND: OK[0a]+FCON
05/11 18:27:55 yS3 found action string: ``+FCON''
05/11 18:27:55 yS3 start fax receiver...
.....................
05/11 18:28:32 yS3 fax_wait_for: string '+FHNG: 0'
05/11 18:28:32 yS3 connection hangup: '+FHNG: 0'
05/11 18:28:32 yS3 (Normal and proper end of connection)** found **
-------------- failed data --------------------------------------
05/11 18:55:29 yS3 waiting...
05/11 18:57:57 yS3 select returned 1
05/11 18:57:57 yS3 checking lockfiles, locking the line
05/11 18:57:57 yS3 makelock(ttyS3) called
05/11 18:57:57 yS3 do_makelock: lock='/var/lock/LCK..ttyS3'
05/11 18:57:57 yS3 lock made
05/11 18:57:57 yS3 vgetty: number of rings (5) was set directly
05/11 18:57:57 yS3 vgetty: decremented number of rings (5) by 0
05/11 18:57:57 yS3 wfr: waiting for ``RING''
05/11 18:57:57 yS3 got: [0a][0d][0a]RING[0d]
05/11 18:57:57 yS3 CND: RING
05/11 18:57:57 yS3 wfr: rc=0, drn=0
05/11 18:57:57 yS3 wfr: waiting for ``RING''
05/11 18:57:57 yS3 got: [0a][0d][0a]RING[0d]
05/11 18:58:03 yS3 CND: RING
05/11 18:58:03 yS3 wfr: rc=0, drn=0
05/11 18:58:03 yS3 wfr: waiting for ``RING''
05/11 18:58:03 yS3 got: [0a][0d][0a]RING[0d]
05/11 18:58:09 yS3 CND: RING
05/11 18:58:09 yS3 wfr: rc=0, drn=0
05/11 18:58:09 yS3 wfr: waiting for ``RING''
05/11 18:58:09 yS3 got: [0a][0d][0a]RING[0d]
05/11 18:58:16 yS3 CND: RING
05/11 18:58:16 yS3 wfr: rc=0, drn=0
05/11 18:58:16 yS3 wfr: waiting for ``RING''
05/11 18:58:16 yS3 got: [0a][0d][0a]RING[0d]
05/11 18:58:22 yS3 CND: RING
05/11 18:58:22 yS3 wfr: rc=0, drn=0
05/11 18:58:22 yS3 CND: check no: 'none'
05/11 18:58:22 yS3 reading ring_type ring configuration from config file /etc/mgetty+sendfax/voice.conf
05/11 18:58:22 yS3 reading /etc/mgetty+sendfax/voice.conf...
............ skipped ..............................
05/11 18:58:22 yS3 key: 'part', type=6, flags=4, data=(ignored)
05/11 18:58:22 yS3 key: 'program', type=6, flags=4, data=(ignored)
05/11 18:58:22 yS3 key: 'port', type=6, flags=4, data=(ignored)
05/11 18:58:22 yS3 key: 'ring_type', type=6, flags=4, data=(ignored)
05/11 18:58:22 yS3 key: 'voice_log_level', type=0, flags=3, data=6
05/11 18:58:22 yS3 key: 'voice_shell', type=1, flags=3, data=/bin/sh
05/11 18:58:22 yS3 key: 'port_speed', type=0, flags=3, data=115200
05/11 18:58:22 yS3 key: 'port_timeout', type=0, flags=3, data=10
05/11 18:58:22 yS3 key: 'dial_timeout', type=0, flags=3, data=90
05/11 18:58:22 yS3 key: 'command_delay', type=0, flags=3, data=100
05/11 18:58:22 yS3 key: 'dtmf_len', type=0, flags=3, data=30
05/11 18:58:22 yS3 key: 'dtmf_threshold', type=0, flags=3, data=40
05/11 18:58:22 yS3 key: 'dtmf_wait', type=0, flags=3, data=7
05/11 18:58:22 yS3 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
05/11 18:58:22 yS3 key: 'raw_data', type=3, flags=3, data=FALSE
05/11 18:58:22 yS3 key: 'rec_compression', type=0, flags=3, data=0
05/11 18:58:22 yS3 key: 'rec_speed', type=0, flags=3, data=0
05/11 18:58:22 yS3 key: 'rec_silence_len', type=0, flags=3, data=70
05/11 18:58:22 yS3 key: 'rec_silence_threshold', type=0, flags=3, data=40
05/11 18:58:22 yS3 key: 'rec_remove_silence', type=3, flags=3, data=FALSE
05/11 18:58:22 yS3 key: 'rec_max_len', type=0, flags=3, data=300
05/11 18:58:22 yS3 key: 'rec_min_len', type=0, flags=3, data=0
05/11 18:58:22 yS3 key: 'do_hard_flow', type=3, flags=3, data=TRUE
05/11 18:58:22 yS3 key: 'force_autodetect', type=3, flags=3, data=TRUE
05/11 18:58:22 yS3 key: 'watchdog_timeout', type=0, flags=3, data=60
05/11 18:58:22 yS3 key: 'receive_gain', type=0, flags=3, data=-1
05/11 18:58:22 yS3 key: 'transmit_gain', type=0, flags=3, data=-1
05/11 18:58:22 yS3 key: 'enable_command_echo', type=3, flags=3, data=FALSE
05/11 18:58:22 yS3 key: 'poll_interval', type=0, flags=3, data=10
05/11 18:58:22 yS3 key: 'rings', type=1, flags=3, data=5
05/11 18:58:22 yS3 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
05/11 18:58:22 yS3 key: 'toll_saver_rings', type=0, flags=3, data=0
05/11 18:58:22 yS3 key: 'rec_always_keep', type=3, flags=3, data=TRUE
05/11 18:58:22 yS3 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
05/11 18:58:22 yS3 key: 'phone_owner', type=1, flags=3, data=root
05/11 18:58:22 yS3 key: 'phone_group', type=1, flags=3, data=phone
05/11 18:58:22 yS3 key: 'phone_mode', type=0, flags=3, data=432
05/11 18:58:22 yS3 key: 'message_flag_file', type=1, flags=3, data=.flag
05/11 18:58:22 yS3 key: 'receive_dir', type=1, flags=3, data=incoming
05/11 18:58:22 yS3 key: 'message_dir', type=1, flags=3, data=messages
05/11 18:58:22 yS3 key: 'message_list', type=1, flags=3, data=Index
05/11 18:58:22 yS3 key: 'backup_message', type=1, flags=3, data=standard.rmd
05/11 18:58:22 yS3 key: 'button_program', type=1, flags=3, data=
05/11 18:58:22 yS3 key: 'call_program', type=1, flags=3, data=
05/11 18:58:22 yS3 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
05/11 18:58:22 yS3 key: 'message_program', type=1, flags=3, data=
05/11 18:58:22 yS3 key: 'do_message_light', type=3, flags=3, data=FALSE
05/11 18:58:22 yS3 key: 'beep_frequency', type=0, flags=3, data=933
05/11 18:58:22 yS3 key: 'beep_length', type=0, flags=3, data=1500
05/11 18:58:22 yS3 key: 'max_tries', type=0, flags=3, data=3
05/11 18:58:22 yS3 key: 'retry_delay', type=0, flags=3, data=5
05/11 18:58:22 yS3 key: 'dialout_timeout', type=0, flags=1, data=90
05/11 18:58:22 yS3 key: 'voice_devices', type=1, flags=1, data=
05/11 18:58:22 yS3 vgetty: answer mode was set directly
05/11 18:58:22 yS3 vgetty: answer mode is [data|fax|voice]
05/11 18:58:22 yS3 vgetty: opening list file /var/spool/voice/messages/Index
05/11 18:58:22 yS3 vgetty: message name is /var/spool/voice/messages/standard.rmd
05/11 18:58:22 yS3 vgetty: entering voice mode
05/11 18:58:22 yS3 vgetty: Installing signal handlers
05/11 18:58:22 yS3 voice command: 'AT#CLS?' -> ''
05/11 18:58:22 yS3 vgetty: AT#CLS?
05/11 18:58:22 yS3 Rockwell: 0
05/11 18:58:22 yS3 voice command: '' -> 'OK'
05/11 18:58:22 yS3 Rockwell: OK
05/11 18:58:22 yS3 voice command: 'AT#CLS=8' -> 'OK'
05/11 18:58:22 yS3 vgetty: AT#CLS=8
05/11 18:58:22 yS3 Rockwell: OK
05/11 18:58:22 yS3 voice command: 'AT' -> 'OK'
05/11 18:58:22 yS3 vgetty: AT
05/11 18:58:22 yS3 Rockwell: <DLE> <h>
05/11 18:58:22 yS3 vgetty: queued event HANDSET_ON_HOOK at position 0002
05/11 18:58:22 yS3 Rockwell:
05/11 18:58:22 yS3 Rockwell: OK
05/11 18:58:22 yS3 vgetty: queued event RESET_WATCHDOG at position 0003
05/11 18:58:22 yS3 voice command: 'AT#VLS=4' -> 'OK'
05/11 18:58:22 yS3 vgetty: AT#VLS=4
05/11 18:58:22 yS3 vgetty: unqueued event HANDSET_ON_HOOK at position 0002
05/11 18:58:22 yS3 vgetty: voice_handle_event got event HANDSET_ON_HOOK with data <
05/11 18:58:22 yS3 vgetty: unqueued event RESET_WATCHDOG at position 0003
05/11 18:58:22 yS3 vgetty: voice_handle_event got event RESET_WATCHDOG with data <
05/11 18:58:22 yS3 Rockwell: OK
05/11 18:58:22 yS3 voice command: 'ATA' -> 'VCON'
05/11 18:58:22 yS3 vgetty: ATA
05/11 18:58:22 yS3 Rockwell: VCON
05/11 18:58:24 yS3 playing voice file /var/spool/voice/messages/standard.rmd
05/11 18:58:24 yS3 vgetty: queued event RESET_WATCHDOG at position 0004
05/11 18:58:24 yS3 voice command: 'AT#VTS=[933,0,15]' -> ''
05/11 18:58:24 yS3 vgetty: AT#VTS=[933,0,15]
05/11 18:58:24 yS3 vgetty: unqueued event RESET_WATCHDOG at position 0004
05/11 18:58:24 yS3 vgetty: voice_handle_event got event RESET_WATCHDOG with data <
05/11 18:58:24 yS3 voice command: '' -> 'OK'
05/11 18:58:24 yS3 Rockwell: OK
05/11 18:58:25 yS3 recording voice file /var/spool/voice/incoming/va07956.rmd
05/11 18:58:25 yS3 vgetty: queued event RESET_WATCHDOG at position 0005
05/11 18:58:25 yS3 voice command: 'AT#VBS=2' -> 'OK'
05/11 18:58:26 yS3 vgetty: AT#VBS=2
05/11 18:58:26 yS3 vgetty: unqueued event RESET_WATCHDOG at position 0005
05/11 18:58:26 yS3 vgetty: voice_handle_event got event RESET_WATCHDOG with data <d>
05/11 18:58:26 yS3 Rockwell: OK
05/11 18:58:26 yS3 vgetty: queued event RESET_WATCHDOG at position 0006
05/11 18:58:26 yS3 vgetty: unqueued event RESET_WATCHDOG at position 0006
05/11 18:58:26 yS3 vgetty: voice_handle_event got event RESET_WATCHDOG with data <d>
05/11 18:58:26 yS3 voice command: 'AT&K3' -> 'OK'
05/11 18:58:26 yS3 vgetty: AT&K3
05/11 18:58:26 yS3 Rockwell: OK
05/11 18:58:26 yS3 tio_set_flow_control( HARD XON_IN )
05/11 18:58:26 yS3 voice command: 'AT#VRX' -> 'CONNECT'
05/11 18:58:26 yS3 vgetty: AT#VRX
05/11 18:58:26 yS3 Rockwell: CONNECT
05/11 18:58:33 yS3 Rockwell: <DLE> <s>
05/11 18:58:33 yS3 vgetty: queued event NO_VOICE_ENERGY at position 0007
05/11 18:58:33 yS3 vgetty: unqueued event NO_VOICE_ENERGY at position 0007
05/11 18:58:33 yS3 vgetty: voice_handle_event got event NO_VOICE_ENERGY with data <d>
05/11 18:58:33 yS3 vgetty: <STOP RECORDING>
05/11 18:58:33 yS3 Rockwell: <VOICE DATA 12618 bytes>
05/11 18:58:33 yS3 Rockwell: <DLE> <ETX>
05/11 18:58:33 yS3 voice command: '' -> 'OK|VCON'
05/11 18:58:33 yS3 Rockwell: VCON
05/11 18:58:33 yS3 vgetty: trying data and fax connection
05/11 18:58:33 yS3 voice command: 'AT#CLS=2' -> 'OK'
05/11 18:58:33 yS3 vgetty: AT#CLS=2
05/11 18:58:33 yS3 Rockwell: OK
05/11 18:58:34 yS3 voice command: 'AT' -> 'OK'
05/11 18:58:34 yS3 vgetty: AT
05/11 18:58:34 yS3 Rockwell: OK
05/11 18:58:34 yS3 voice command: 'AT+FAA=1' -> 'OK'
05/11 18:58:34 yS3 vgetty: AT+FAA=1
05/11 18:58:34 yS3 Rockwell: OK
05/11 18:58:34 yS3 vgetty: Restoring signal handlers
05/11 18:58:34 yS3 mdm_send: 'AT+FBOR=0'
05/11 18:58:34 yS3 got:[0a][0d][0a]OK[0d]
05/11 18:58:34 yS3 mdm_command: string 'OK' -> OK
05/11 18:58:34 yS3 send: ATA[0d]
05/11 18:58:34 yS3 waiting for ``CONNECT''
05/11 18:58:34 yS3 got: [0d]
05/11 18:59:18 yS3 CND: OK[0a]+FHNG:
05/11 18:59:18 yS3 found action string: ``+FHNG:''
05/11 18:59:18 ##### failed A_FAIL dev=ttyS3, pid=7956, caller='none', conn='', name=''
05/11 18:59:18 yS3 removing lock file
--
---------------------------------------------------------------------------
Dmitri Pogosian 102 Aldwych Ave.
tel/fax: 1-416-466-4028 Toronto, ON M4J 1X6
e-mail: pogosyan@cita.utoronto.ca Canada
---------------------------------------------------------------------------