brocken voice send in vgetty-21

Gero Guenther (gero@space-server.bln.sub.org)
Sat, 14 Oct 1995 16:24:14 +0100



--text follows this line--
Hello readers.

After some debuging, I'can count flow control problems out -
I'm using  HARD | XON_IN | XON_OUT.

What is happening, is that in

10/14 15:57:13  tio_set_flow_control( HARD XON_IN XON_OUT )
10/14 15:57:13  voice_command: send 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:57:13  voice_wait_for(VCON|OK)
10/14 15:57:13  voice_wait_for: string 'ATS39.7=0 S39.6=1 +VIT=60þ'
10/14 15:57:13  voice_wait_for: string '1496'
10/14 15:57:16  voice_wait_for: string 'NO DIALTONE'
10/14 15:59:13  Warning: got alarm signal!

the modem is doing a hangup and something like ATA  after/while
10/14 15:57:13  voice_wait_for: string '1496'

with the result of
10/14 15:57:16  voice_wait_for: string 'NO DIALTONE'

what I would do next, is duplicate the modems file discriptor in a
debug file - to see what modem actually gets.

		Gero

-- 
Best wishes --- Gero :)
# uname --smart          gero@(cs.tu-berlin.de|space-server.bln.sub.org)
smart=((apple||windows||os2||dos)==outside) && (linux==inside);


------------------v o i c e l o g--------------------------------------------
USING ZPLAY STAND ALONE:
--
10/14 15:56:19  makelock(ttyS4) called
10/14 15:56:19  do_makelock: lock='/usr/spool/uucp/LCK..ttyS4'
10/14 15:56:19  stale lockfile, created by process 12224, ignoring
10/14 15:56:19  lock made
10/14 15:56:19  tio_set_flow_control( XON_IN XON_OUT )
10/14 15:56:19  voice_open_device succeeded, ttyS4 -> 4
10/14 15:56:19  voice_command: send 'AT'
10/14 15:56:19  voice_wait_for(OK)
10/14 15:56:19  voice_wait_for: string 'AT'
10/14 15:56:19  voice_wait_for: string 'OK'** found **
10/14 15:56:19  voice code version 021-Aug21b
10/14 15:56:19  voice_command: send 'AT+FCLASS=8'
10/14 15:56:19  voice_wait_for(VCON|OK)
10/14 15:56:19  voice_wait_for: string 'AT+FCLASS=8'
10/14 15:56:19  voice_wait_for: string 'OK'** found **
10/14 15:56:19  voice_command: send 'ATI'
10/14 15:56:19  voice_wait_for(1496|OK)
10/14 15:56:19  voice_wait_for: string 'ATI'
10/14 15:56:19  voice_wait_for: string '1496'** found **
10/14 15:56:19  voice_command: send ''
10/14 15:56:19  voice_wait_for(OK)
10/14 15:56:19  voice_wait_for: string 'OK'** found **
10/14 15:56:19  modem type ZyXEL
10/14 15:56:19  flushing modem output
10/14 15:56:19  voice_send_file("messages/error.pcm") started...
10/14 15:56:19  sending messages/error.pcm...
10/14 15:56:19  reading zfax header, compression is ADPCM-3
10/14 15:56:19  tio_set_flow_control( HARD XON_IN XON_OUT )
10/14 15:56:19  voice_command: send 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:56:19  voice_wait_for(VCON|OK)
10/14 15:56:19  voice_wait_for: string 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:56:19  voice_wait_for: string 'OK'** found **
10/14 15:56:19  voice_command: send 'AT+VDH=12 +VDD=6'
10/14 15:56:19  voice_wait_for(VCON|OK)
10/14 15:56:20  voice_wait_for: string 'AT+VDH=12 +VDD=6'
10/14 15:56:20  voice_wait_for: string 'OK'** found **
10/14 15:56:20  voice_command: send 'AT+FLO=2'
10/14 15:56:20  voice_wait_for(VCON|OK)
10/14 15:56:20  voice_wait_for: string 'AT+FLO=2'
10/14 15:56:20  voice_wait_for: string 'OK'** found **
10/14 15:56:20  voice_command: send 'ATL7'
10/14 15:56:20  voice_wait_for(VCON|OK)
10/14 15:56:20  voice_wait_for: string 'ATL7'
10/14 15:56:20  voice_wait_for: string 'OK'** found **
10/14 15:56:20  voice_command: send 'AT+VSM=3 +VLS=16'
10/14 15:56:20  voice_wait_for(VCON|OK)
10/14 15:56:20  voice_wait_for: string 'AT+VSM=3 +VLS=16'
10/14 15:56:20  voice_wait_for: string 'VCON'** found **
10/14 15:56:20  voice_command: send 'AT+VTX'
10/14 15:56:20  voice_wait_for(CONNECT)
10/14 15:56:20  voice_wait_for: string 'AT+VTX'
10/14 15:56:20  voice_wait_for: string 'CONNECT'** found **
10/14 15:56:21  sending DLE ETX...
10/14 15:56:21  voice_wait_for(VCON|OK)
10/14 15:56:22  voice_wait_for: string 'VCON'** found **
10/14 15:56:22  voice_command: send 'ATL7'
10/14 15:56:22  voice_wait_for(VCON|OK)
10/14 15:56:22  voice_wait_for: string 'ATL7'
10/14 15:56:22  voice_wait_for: string 'OK'** found **
10/14 15:56:22  voice_command: send 'AT+VLS=16'
10/14 15:56:22  voice_wait_for(VCON|OK)
10/14 15:56:22  voice_wait_for: string 'AT+VLS=16'
10/14 15:56:22  voice_wait_for: string 'VCON'** found **
10/14 15:56:22  voice_command: send 'AT+VTS=[880,0,10]'
10/14 15:56:22  voice_wait_for(VCON|OK)
10/14 15:56:22  voice_wait_for: string 'AT+VTS=[880,0,10]'
10/14 15:56:24  voice_wait_for: string 'OK'** found **
10/14 15:56:24  tio_set_flow_control( HARD XON_IN XON_OUT )
10/14 15:56:24  voice_command: send 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:56:24  voice_wait_for(VCON|OK)
10/14 15:56:24  voice_wait_for: string 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:56:24  voice_wait_for: string 'OK'** found **
10/14 15:56:24  voice_command: send 'AT+VDH=12 +VDD=6'
10/14 15:56:24  voice_wait_for(VCON|OK)
10/14 15:56:24  voice_wait_for: string 'AT+VDH=12 +VDD=6'
10/14 15:56:24  voice_wait_for: string 'OK'** found **
10/14 15:56:24  voice_command: send 'AT+FLO=2'
10/14 15:56:24  voice_wait_for(VCON|OK)
10/14 15:56:24  voice_wait_for: string 'AT+FLO=2'
10/14 15:56:24  voice_wait_for: string 'OK'** found **
10/14 15:56:24  voice_command: send 'ATL7'
10/14 15:56:24  voice_wait_for(VCON|OK)
10/14 15:56:24  voice_wait_for: string 'ATL7'
10/14 15:56:24  voice_wait_for: string 'OK'** found **
10/14 15:56:24  voice_command: send 'AT+VSM=3 +VLS=16 +VSD=19,10'
10/14 15:56:24  voice_wait_for(VCON|OK)
10/14 15:56:24  voice_wait_for: string 'AT+VSM=3 +VLS=16 +VSD=19,10'
10/14 15:56:24  voice_wait_for: string 'VCON'** found **
10/14 15:56:24  voice_command: send 'AT+VRX'
10/14 15:56:24  voice_wait_for(CONNECT)
10/14 15:56:24  voice_wait_for: string 'AT+VRX'
10/14 15:56:24  voice_wait_for: string 'CONNECT'** found **
10/14 15:56:24  voice_record: receiving /tmp/R.pcm...
10/14 15:56:26  input: got <DLE>s
10/14 15:56:26  voice_send: 'Q'
10/14 15:56:26  input: got <DLE>[03]
10/14 15:56:26  got DLE ETX
10/14 15:56:26  voice record: end, bytes received: 3569
10/14 15:56:26  voice_command: send ''
10/14 15:56:26  voice_wait_for(VCON|OK)
10/14 15:56:26  voice_wait_for: string 'VCON'** found **
10/14 15:56:26  voice_command: send 'AT+VLS=0 +FCLASS=0'
10/14 15:56:26  voice_wait_for(OK)
10/14 15:56:26  voice_wait_for: string 'AT+VLS=0 +FCLASS=0'
10/14 15:56:26  voice_wait_for: string 'OK'** found **
10/14 15:56:26  flushing modem output
10/14 15:56:26  removing lock file
--


USING ZPLAY VIA VGETTY:
10/14 15:57:04  flushing modem output
10/14 15:57:04  voice_send_file("/usr/spool/voice/messages/root_menu_c.pcm") started...
10/14 15:57:04  sending /usr/spool/voice/messages/root_menu_c.pcm...
10/14 15:57:04  reading zfax header, compression is ADPCM-3
10/14 15:57:04  tio_set_flow_control( HARD XON_IN XON_OUT )
10/14 15:57:04  voice_command: send 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:57:04  voice_wait_for(VCON|OK)
10/14 15:57:04  voice_wait_for: string 'ATS39.7=0 S39.6=1 +VIT=60þ'
10/14 15:57:04  voice_wait_for: string 'OK'** found **
10/14 15:57:04  voice_command: send 'AT+VDH=12 +VDD=6'
10/14 15:57:04  voice_wait_for(VCON|OK)
10/14 15:57:04  voice_wait_for: string 'AT+VDH=12 +VDD=6þ'
10/14 15:57:04  voice_wait_for: string 'OK'** found **
10/14 15:57:04  voice_command: send 'AT+FLO=2'
10/14 15:57:04  voice_wait_for(VCON|OK)
10/14 15:57:04  voice_wait_for: string 'AT+FLO=2þ'
10/14 15:57:04  voice_wait_for: string 'OK'** found **
10/14 15:57:04  voice_command: send 'ATL7'
10/14 15:57:04  voice_wait_for(VCON|OK)
10/14 15:57:04  voice_wait_for: string 'ATL7þ'
10/14 15:57:04  voice_wait_for: string 'OK'** found **
10/14 15:57:04  voice_command: send 'AT+VSM=3 +VLS=2'
10/14 15:57:04  voice_wait_for(VCON|OK)
10/14 15:57:04  voice_wait_for: string 'AT+VSM=3 +VLS=2þ'
10/14 15:57:04  voice_wait_for: string 'VCON'** found **
10/14 15:57:04  voice_command: send 'AT+VTX'
10/14 15:57:04  voice_wait_for(CONNECT)
10/14 15:57:04  voice_wait_for: string 'AT+VTXþ'
10/14 15:57:04  voice_wait_for: string 'CONNECT'** found **
10/14 15:57:09  input: got <DLE><digit>
10/14 15:57:09  sending DLE ETX...
10/14 15:57:09  voice_wait_for(VCON|OK)
10/14 15:57:09  voice_wait_for: string 'VCON'** found **
10/14 15:57:09  flushing modem output
--
10/14 15:57:10  flushing modem output
10/14 15:57:10  voice_send_file("/usr/voice/sample/3.pcm") started...
10/14 15:57:10  sending /usr/voice/sample/3.pcm...
10/14 15:57:10  reading zfax header, compression is ADPCM-3
10/14 15:57:10  tio_set_flow_control( HARD XON_IN XON_OUT )
10/14 15:57:10  voice_command: send 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:57:10  voice_wait_for(VCON|OK)
10/14 15:57:10  voice_wait_for: string 'ATS39.7=0 S39.6=1 +VIT=60þ'
10/14 15:57:10  voice_wait_for: string 'OK'** found **
10/14 15:57:10  voice_command: send 'AT+VDH=12 +VDD=6'
10/14 15:57:10  voice_wait_for(VCON|OK)
10/14 15:57:10  voice_wait_for: string 'AT+VDH=12 +VDD=6þ'
10/14 15:57:10  voice_wait_for: string 'OK'** found **
10/14 15:57:10  voice_command: send 'AT+FLO=2'
10/14 15:57:10  voice_wait_for(VCON|OK)
10/14 15:57:10  voice_wait_for: string 'AT+FLO=2þ'
10/14 15:57:10  voice_wait_for: string 'OK'** found **
10/14 15:57:10  voice_command: send 'ATL7'
10/14 15:57:10  voice_wait_for(VCON|OK)
10/14 15:57:10  voice_wait_for: string 'ATL7þ'
10/14 15:57:10  voice_wait_for: string 'OK'** found **
10/14 15:57:10  voice_command: send 'AT+VSM=3 +VLS=2'
10/14 15:57:10  voice_wait_for(VCON|OK)
10/14 15:57:10  voice_wait_for: string 'AT+VSM=3 +VLS=2þ'
10/14 15:57:10  voice_wait_for: string 'VCON'** found **
10/14 15:57:10  voice_command: send 'AT+VTX'
10/14 15:57:10  voice_wait_for(CONNECT)
10/14 15:57:10  voice_wait_for: string 'AT+VTXþ'
10/14 15:57:10  voice_wait_for: string 'CONNECT'** found **
10/14 15:57:10  sending DLE ETX...
10/14 15:57:10  voice_wait_for(VCON|OK)
10/14 15:57:11  voice_wait_for: string 'VCON'** found **
10/14 15:57:11  flushing modem output
--
10/14 15:57:13  flushing modem output
10/14 15:57:13  voice_send_file("/usr/voice/synth/the.pcm") started...
10/14 15:57:13  sending /usr/voice/synth/the.pcm...
10/14 15:57:13  reading zfax header, compression is ADPCM-3
10/14 15:57:13  tio_set_flow_control( HARD XON_IN XON_OUT )
10/14 15:57:13  voice_command: send 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:57:13  voice_wait_for(VCON|OK)
10/14 15:57:13  voice_wait_for: string 'ATS39.7=0 S39.6=1 +VIT=60þ'
10/14 15:57:13  voice_wait_for: string '1496'
10/14 15:57:16  voice_wait_for: string 'NO DIALTONE'
10/14 15:59:13  Warning: got alarm signal!
10/14 15:59:13  voice_read_byte: read returned -1: Interrupted system call
10/14 15:59:13  voice_wait_for: cannot read byte, return: Interrupted system call
10/14 15:59:13  voice init failed, continuing
10/14 15:59:13  voice_command: send 'AT+VDH=12 +VDD=6'
10/14 15:59:13  voice_wait_for(VCON|OK)
10/14 15:59:13  voice_wait_for: string 'AT+VDH=12 +VDD=6þ'
10/14 15:59:13  voice_wait_for: string 'OK'** found **
10/14 15:59:13  voice_command: send 'AT+FLO=2'
10/14 15:59:13  voice_wait_for(VCON|OK)
10/14 15:59:13  voice_wait_for: string 'AT+FLO=2þ'
10/14 15:59:13  voice_wait_for: string 'OK'** found **
10/14 15:59:13  voice_command: send 'ATL7'
10/14 15:59:13  voice_wait_for(VCON|OK)
10/14 15:59:13  voice_wait_for: string 'ATL7þ'
10/14 15:59:13  voice_wait_for: string 'OK'** found **
10/14 15:59:13  voice_command: send 'AT+VSM=3 +VLS=2'
10/14 15:59:13  voice_wait_for(VCON|OK)
10/14 15:59:13  voice_wait_for: string 'AT+VSM=3 +VLS=2þ'
10/14 15:59:13  voice_wait_for: string 'VCON'** found **
10/14 15:59:13  voice_command: send 'AT+VTX'
10/14 15:59:13  voice_wait_for(CONNECT)
10/14 15:59:13  voice_wait_for: string 'AT+VTXþ'
10/14 15:59:13  voice_wait_for: string 'CONNECT'** found **
10/14 15:59:13  sending DLE ETX...
10/14 15:59:13  voice_wait_for(VCON|OK)
10/14 15:59:14  voice_wait_for: string 'VCON'** found **
10/14 15:59:14  voice_send_file("/usr/voice/synth/kernel.pcm") started...
10/14 15:59:14  sending /usr/voice/synth/kernel.pcm...
10/14 15:59:14  reading zfax header, compression is ADPCM-3
10/14 15:59:14  tio_set_flow_control( HARD XON_IN XON_OUT )
10/14 15:59:14  voice_command: send 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:59:14  voice_wait_for(VCON|OK)
10/14 15:59:14  voice_wait_for: string 'ATS39.7=0 S39.6=1 +VIT=60þ'
10/14 15:59:14  voice_wait_for: string 'OK'** found **
10/14 15:59:14  voice_command: send 'AT+VDH=12 +VDD=6'
10/14 15:59:14  voice_wait_for(VCON|OK)
10/14 15:59:14  voice_wait_for: string 'AT+VDH=12 +VDD=6þ'
10/14 15:59:14  voice_wait_for: string 'OK'** found **
10/14 15:59:14  voice_command: send 'AT+FLO=2'
10/14 15:59:14  voice_wait_for(VCON|OK)
10/14 15:59:14  voice_wait_for: string 'AT+FLO=2þ'
10/14 15:59:14  voice_wait_for: string 'OK'** found **
10/14 15:59:14  voice_command: send 'ATL7'
10/14 15:59:14  voice_wait_for(VCON|OK)
10/14 15:59:14  voice_wait_for: string 'ATL7þ'
10/14 15:59:14  voice_wait_for: string 'OK'** found **
10/14 15:59:14  voice_command: send 'AT+VSM=3 +VLS=2'
10/14 15:59:14  voice_wait_for(VCON|OK)
10/14 15:59:14  voice_wait_for: string 'AT+VSM=3 +VLS=2þ'
10/14 15:59:14  voice_wait_for: string 'VCON'** found **
10/14 15:59:14  voice_command: send 'AT+VTX'
10/14 15:59:15  voice_wait_for(CONNECT)
10/14 15:59:15  voice_wait_for: string 'AT+VTXþ'
10/14 15:59:15  voice_wait_for: string 'CONNECT'** found **
10/14 15:59:16  sending DLE ETX...
10/14 15:59:16  voice_wait_for(VCON|OK)
10/14 15:59:16  voice_wait_for: string 'VCON'** found **
10/14 15:59:16  voice_send_file("/usr/voice/synth/messages.pcm") started...
10/14 15:59:16  sending /usr/voice/synth/messages.pcm...
10/14 15:59:16  reading zfax header, compression is ADPCM-3
10/14 15:59:16  tio_set_flow_control( HARD XON_IN XON_OUT )
10/14 15:59:16  voice_command: send 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:59:16  voice_wait_for(VCON|OK)
10/14 15:59:16  voice_wait_for: string 'ATS39.7=0 S39.6=1 +VIT=60þ'
10/14 15:59:16  voice_wait_for: string 'OK'** found **
10/14 15:59:16  voice_command: send 'AT+VDH=12 +VDD=6'
10/14 15:59:16  voice_wait_for(VCON|OK)
10/14 15:59:16  voice_wait_for: string 'AT+VDH=12 +VDD=6þ'
10/14 15:59:16  voice_wait_for: string 'OK'** found **
10/14 15:59:16  voice_command: send 'AT+FLO=2'
10/14 15:59:16  voice_wait_for(VCON|OK)
10/14 15:59:16  voice_wait_for: string 'AT+FLO=2þ'
10/14 15:59:16  voice_wait_for: string 'OK'** found **
10/14 15:59:16  voice_command: send 'ATL7'
10/14 15:59:16  voice_wait_for(VCON|OK)
10/14 15:59:16  voice_wait_for: string 'ATL7þ'
10/14 15:59:16  voice_wait_for: string 'OK'** found **
10/14 15:59:16  voice_command: send 'AT+VSM=3 +VLS=2'
10/14 15:59:16  voice_wait_for(VCON|OK)
10/14 15:59:16  voice_wait_for: string 'AT+VSM=3 +VLS=2þ'
10/14 15:59:16  voice_wait_for: string 'VCON'** found **
10/14 15:59:16  voice_command: send 'AT+VTX'
10/14 15:59:16  voice_wait_for(CONNECT)
10/14 15:59:16  voice_wait_for: string 'AT+VTXþ'
10/14 15:59:16  voice_wait_for: string 'CONNECT'** found **
10/14 15:59:18  sending DLE ETX...
10/14 15:59:18  voice_wait_for(VCON|OK)
10/14 15:59:18  voice_wait_for: string 'VCON'** found **
10/14 15:59:18  voice_send_file("/usr/voice/synth/are.pcm") started...
10/14 15:59:18  sending /usr/voice/synth/are.pcm...
10/14 15:59:18  reading zfax header, compression is ADPCM-3
10/14 15:59:18  tio_set_flow_control( HARD XON_IN XON_OUT )
10/14 15:59:18  voice_command: send 'ATS39.7=0 S39.6=1 +VIT=60'
10/14 15:59:18  voice_wait_for(VCON|OK)
10/14 15:59:18  voice_wait_for: string 'ATS39.7=0 S39.6=1 +VIT=60þ'
10/14 15:59:18  voice_wait_for: string 'OK'** found **
10/14 15:59:18  voice_command: send 'AT+VDH=12 +VDD=6'
10/14 15:59:18  voice_wait_for(VCON|OK)
10/14 15:59:18  voice_wait_for: string 'AT+VDH=12 +VDD=6þ'
10/14 15:59:18  voice_wait_for: string 'OK'** found **
10/14 15:59:18  voice_command: send 'AT+FLO=2'
10/14 15:59:18  voice_wait_for(VCON|OK)
10/14 15:59:18  voice_wait_for: string 'AT+FLO=2þ'
10/14 15:59:18  voice_wait_for: string 'OK'** found **
10/14 15:59:18  voice_command: send 'ATL7'
10/14 15:59:18  voice_wait_for(VCON|OK)
10/14 15:59:18  voice_wait_for: string 'ATL7þ'