Multitech 5600ZDXV, DTMF, vgetty

Brian Vincent (brian@uniqsys.com)
Wed, 24 Feb 1999 17:46:17 -0500


This is a multi-part message in MIME format.
--------------3FA07A77826F29E0760017C2
Content-Type: text/plain; charset=us-ascii
Content-Transfer-Encoding: 7bit


I am extremely new to the mgetty/vgetty stuff, please forgive me.
I downloaded the binaries about 4 hours ago and started banging on
some of the source about 3 hours ago.  I was having trouble getting
my Multitech ZDXV 56K modem working with vgetty.  I noticed in the
logs it wasn't recognizing the response from the ATI command.  So...

	- I edited the mgetty-1.1.8/voice/libvoice/detect.c to include 
	  this line:
		     {"MT5600ZDXV", &Rockwell},
	  in the modem database part.
	
	- Amazingly, that worked (hell of a guess it works with Rockwell
	  commands).

So that made me happy vgetty would at least respawn.

In fact, when I dial up my modem I get rmd files in 
/var/spool/voice/incoming so that makes me even happier.  I converted
them to .wav and discovered I really was capturing the incoming sounds.
(Whee!)  So hats off to all the developers!  At the very least I'll
have something fun to play with.

However...what I really want to do is decode the DTMF tones.  I know,
I know, RTFM.  I tried.  As near as I can tell I can change the line
in /etc/mgetty+sendfax/voice.conf to something like this:
	dtmf_program /tmp/dtmf.sh 

I tried making my /tmp/dtmf.sh script the  "$Id: dtmf.sh,v 1.7 1997/04/12
14:43:27 marc Exp $" script that came with the mgetty source, but that 
doesn't seem to be doing anything (how can I even tell it's firing up 
the script?  The log file doesn't say anything about that) I also tried 
making a script that did this:

	#!/bin/sh
	cat - > /tmp/dtmf.out

Since in voice.conf it says "The argument is the string of digits received
(without '*' and '#')" me thinks that should work.  Am I wrong?

I think I am really, really close to having this work, can anyone
help me get the rest of the way?  There's a lot of pieces to this
puzzle and I think I'm missing a bunch of them.  Here's a log file 
of me dialing my modem and punching in tones from my phone.

Thanx!

-- 
___________________________________________________________________
  Brian Vincent   \                               /    Montani
  (419)861-3331    \   "A witty saying proves    /     Semper
brian@uniqsys.com  /     nothing"  - Voltaire    \     Liberi 
__________________/_______________________________\________________
--------------3FA07A77826F29E0760017C2
Content-Type: text/plain; charset=us-ascii;
 name="v.out"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline;
 filename="v.out"

02/24 16:17:39 yS0   voice command: 'AT#CLS=8' -> 'OK'
02/24 17:35:21 yS0    select returned 1
02/24 17:35:21 yS0   checking lockfiles, locking the line
02/24 17:35:21 yS0   makelock(ttyS0) called
02/24 17:35:21 yS0   do_makelock: lock='/var/lock/LCK..ttyS0'
02/24 17:35:21 yS0   lock made
02/24 17:35:21 yS0    vgetty: number of rings (1) was set directly
02/24 17:35:21 yS0  vgetty: number of rings (1) too small, reseting to 2
02/24 17:35:21 yS0  waiting for ``RING_''
02/24 17:35:21 yS0   got: [0d]
02/24 17:35:21 yS0    CND: OK[0a]RING[0d]
02/24 17:35:21 yS0    CND: RING ** found **
02/24 17:35:21 yS0  waiting for ``RING_''
02/24 17:35:21 yS0   got: [0a][0d][0a]RING[0d]
02/24 17:35:27 yS0    CND: RING ** found **
02/24 17:35:27 yS0  reading ring_type ring configuration from config file /etc/mgetty+sendfax/voice.conf
02/24 17:35:27 yS0   reading /etc/mgetty+sendfax/voice.conf...
02/24 17:35:27 yS0   conf lib: read: 'part generic'
02/24 17:35:27 yS0   found CT_KEYWORD part generic
02/24 17:35:27 yS0   conf lib: read: 'voice_log_level 6'
02/24 17:35:27 yS0   conf lib: read: 'voice_dir /var/spool/voice'
02/24 17:35:27 yS0   conf lib: read: 'message_flag_file .flag'
02/24 17:35:27 yS0   conf lib: read: 'receive_dir incoming'
02/24 17:35:27 yS0   conf lib: read: 'message_dir messages'
02/24 17:35:27 yS0   conf lib: read: 'message_list Index'
02/24 17:35:27 yS0   conf lib: read: 'backup_message standard.rmd'
02/24 17:35:27 yS0   conf lib: read: 'port_speed 38400'
02/24 17:35:27 yS0   conf lib: read: 'voice_shell /bin/sh'
02/24 17:35:27 yS0   conf lib: read: 'port_timeout 10'
02/24 17:35:27 yS0   conf lib: read: 'dial_timeout 90'
02/24 17:35:27 yS0   conf lib: read: 'command_delay 100'
02/24 17:35:27 yS0   conf lib: read: 'dtmf_len 10'
02/24 17:35:27 yS0   conf lib: read: 'dtmf_threshold 40'
02/24 17:35:27 yS0   conf lib: read: 'dtmf_wait 7'
02/24 17:35:27 yS0   conf lib: read: 'ignore_fax_dle false'
02/24 17:35:27 yS0   conf lib: read: 'raw_data false'
02/24 17:35:27 yS0   conf lib: read: 'rec_compression 0'
02/24 17:35:27 yS0   conf lib: read: 'rec_speed 0'
02/24 17:35:27 yS0   conf lib: read: 'rec_silence_len 70'
02/24 17:35:27 yS0   conf lib: read: 'rec_silence_threshold 40'
02/24 17:35:27 yS0   conf lib: read: 'rec_remove_silence false'
02/24 17:35:27 yS0   conf lib: read: 'rec_max_len 300'
02/24 17:35:27 yS0   conf lib: read: 'rec_min_len 0'
02/24 17:35:27 yS0   conf lib: read: 'do_hard_flow true'
02/24 17:35:27 yS0   conf lib: read: 'beep_frequency 933'
02/24 17:35:27 yS0   conf lib: read: 'beep_length 150'
02/24 17:35:27 yS0   conf lib: read: 'max_tries 3'
02/24 17:35:27 yS0   conf lib: read: 'retry_delay 5'
02/24 17:35:27 yS0   conf lib: read: 'watchdog_timeout 60'
02/24 17:35:27 yS0   conf lib: read: 'receive_gain -1'
02/24 17:35:27 yS0   conf lib: read: 'transmit_gain -1'
02/24 17:35:27 yS0   conf lib: read: 'enable_command_echo true'
02/24 17:35:27 yS0   conf lib: read: 'program vgetty'
02/24 17:35:27 yS0   conf lib: read: 'rings 1'
02/24 17:35:27 yS0   conf lib: read: 'answer_mode voice:fax:data'
02/24 17:35:27 yS0   conf lib: read: 'force_autodetect false'
02/24 17:35:27 yS0   conf lib: read: 'toll_saver_rings 0'
02/24 17:35:27 yS0   conf lib: read: 'rec_always_keep true'
02/24 17:35:27 yS0   conf lib: read: 'button_program '
02/24 17:35:27 yS0   conf lib: read: 'call_program '
02/24 17:35:27 yS0   conf lib: read: 'dtmf_program /tmp/dtmf.sh'
02/24 17:35:27 yS0   conf lib: read: 'message_program '
02/24 17:35:27 yS0   conf lib: read: 'do_message_light false'
02/24 17:35:27 yS0   conf lib: read: 'program vm'
02/24 17:35:27 yS0   conf lib: read: 'voice_devices '
02/24 17:35:27 yS0   conf lib: read: 'dialout_timeout 90'
02/24 17:35:27 yS0   conf lib: read: 'program pvf'
02/24 17:35:27 yS0   conf lib: read: 'port ttyS0'
02/24 17:35:27 yS0   conf lib: read: 'port ttyS5'
02/24 17:35:27 yS0   conf lib: read: 'ring_type virtual'
02/24 17:35:27 yS0   section: ring_type virtual, ignore
02/24 17:35:27 yS0   conf lib: read: 'answer_mode fax:data'
02/24 17:35:27 yS0   conf lib: read: 'ring_type ring'
02/24 17:35:27 yS0   section: ring_type ring, **found**
02/24 17:35:27 yS0   conf lib: read: 'ring_type ring1'
02/24 17:35:27 yS0   section: ring_type ring1, ignore
02/24 17:35:27 yS0   key: 'part', type=6, flags=4, data=(ignored)
02/24 17:35:27 yS0   key: 'program', type=6, flags=4, data=(ignored)
02/24 17:35:27 yS0   key: 'port', type=6, flags=4, data=(ignored)
02/24 17:35:27 yS0   key: 'ring_type', type=6, flags=4, data=(ignored)
02/24 17:35:27 yS0   key: 'voice_log_level', type=0, flags=3, data=6
02/24 17:35:27 yS0   key: 'voice_devices', type=1, flags=0, data=(empty)
02/24 17:35:27 yS0   key: 'port_speed', type=0, flags=3, data=38400
02/24 17:35:27 yS0   key: 'port_timeout', type=0, flags=3, data=10
02/24 17:35:27 yS0   key: 'dtmf_len', type=0, flags=3, data=10
02/24 17:35:27 yS0   key: 'dtmf_threshold', type=0, flags=3, data=40
02/24 17:35:27 yS0   key: 'dtmf_wait', type=0, flags=3, data=7
02/24 17:35:27 yS0   key: 'rec_compression', type=0, flags=3, data=0
02/24 17:35:27 yS0   key: 'rec_speed', type=0, flags=3, data=0
02/24 17:35:27 yS0   key: 'rec_silence_len', type=0, flags=3, data=70
02/24 17:35:27 yS0   key: 'rec_silence_threshold', type=0, flags=3, data=40
02/24 17:35:27 yS0   key: 'rec_remove_silence', type=3, flags=3, data=FALSE
02/24 17:35:27 yS0   key: 'rec_max_len', type=0, flags=3, data=300
02/24 17:35:27 yS0   key: 'receive_gain', type=0, flags=3, data=-1
02/24 17:35:27 yS0   key: 'transmit_gain', type=0, flags=3, data=-1
02/24 17:35:27 yS0   key: 'rings', type=1, flags=3, data=1
02/24 17:35:27 yS0   key: 'answer_mode', type=1, flags=3, data=voice:fax:data
02/24 17:35:27 yS0   key: 'toll_saver_rings', type=0, flags=3, data=0
02/24 17:35:27 yS0   key: 'rec_always_keep', type=3, flags=3, data=TRUE
02/24 17:35:27 yS0   key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
02/24 17:35:27 yS0   key: 'message_flag_file', type=1, flags=3, data=.flag
02/24 17:35:27 yS0   key: 'receive_dir', type=1, flags=3, data=incoming
02/24 17:35:27 yS0   key: 'message_dir', type=1, flags=3, data=messages
02/24 17:35:27 yS0   key: 'message_list', type=1, flags=3, data=Index
02/24 17:35:27 yS0   key: 'backup_message', type=1, flags=3, data=standard.rmd
02/24 17:35:27 yS0   key: 'dialout_timeout', type=0, flags=1, data=90
02/24 17:35:27 yS0   key: 'beep_frequency', type=0, flags=3, data=933
02/24 17:35:27 yS0   key: 'beep_length', type=0, flags=3, data=150
02/24 17:35:27 yS0   key: 'raw_data', type=3, flags=3, data=FALSE
02/24 17:35:27 yS0   key: 'max_tries', type=0, flags=3, data=3
02/24 17:35:27 yS0   key: 'retry_delay', type=0, flags=3, data=5
02/24 17:35:27 yS0   key: 'voice_shell', type=1, flags=3, data=/bin/sh
02/24 17:35:27 yS0   key: 'button_program', type=1, flags=3, data=
02/24 17:35:27 yS0   key: 'call_program', type=1, flags=3, data=
02/24 17:35:27 yS0   key: 'dtmf_program', type=1, flags=3, data=/tmp/dtmf.sh
02/24 17:35:27 yS0   key: 'message_program', type=1, flags=3, data=
02/24 17:35:27 yS0   key: 'do_message_light', type=3, flags=3, data=FALSE
02/24 17:35:27 yS0   key: 'do_hard_flow', type=3, flags=3, data=TRUE
02/24 17:35:27 yS0   key: 'force_autodetect', type=3, flags=3, data=FALSE
02/24 17:35:27 yS0   key: 'watchdog_timeout', type=0, flags=3, data=60
02/24 17:35:27 yS0   key: 'rec_min_len', type=0, flags=3, data=0
02/24 17:35:27 yS0   key: 'command_delay', type=0, flags=3, data=100
02/24 17:35:27 yS0   key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
02/24 17:35:27 yS0   key: 'dial_timeout', type=0, flags=3, data=90
02/24 17:35:27 yS0   key: 'enable_command_echo', type=3, flags=3, data=TRUE
02/24 17:35:27 yS0    vgetty: answer mode was set directly
02/24 17:35:27 yS0    vgetty: answer mode is [data|fax|voice]
02/24 17:35:27 yS0    vgetty: opening list file /var/spool/voice/messages/Index
02/24 17:35:27 yS0    vgetty: message name is /var/spool/voice/messages/standard.rmd
02/24 17:35:27 yS0   vgetty: entering voice mode
02/24 17:35:27 yS0   vgetty: Installing signal handlers
02/24 17:35:27 yS0    vgetty: queued event 0x4000 at position 0004
02/24 17:35:27 yS0   voice command: 'AT#CLS?' -> ''
02/24 17:35:27 yS0    vgetty: AT#CLS?
02/24 17:35:27 yS0    Rockwell: AT#CLS?
02/24 17:35:27 yS0    Rockwell: 0
02/24 17:35:27 yS0   voice command: '' -> 'OK'
02/24 17:35:27 yS0    Rockwell: OK
02/24 17:35:27 yS0   voice command: 'AT#CLS=8' -> 'OK'
02/24 17:35:27 yS0    vgetty: AT#CLS=8
02/24 17:35:27 yS0    Rockwell: AT#CLS=8
02/24 17:35:27 yS0    Rockwell: OK
02/24 17:35:27 yS0    vgetty: queued event 0x4000 at position 0005
02/24 17:35:27 yS0   voice command: 'ATA' -> 'VCON'
02/24 17:35:27 yS0    vgetty: ATA
02/24 17:35:27 yS0    Rockwell: <DLE> <h>
02/24 17:35:27 yS0    vgetty: queued event 0x2007 at position 0006
02/24 17:35:27 yS0    Rockwell: 
02/24 17:35:27 yS0    Rockwell: ATA
02/24 17:35:29 yS0    Rockwell: VCON
02/24 17:35:29 yS0  playing voice file /var/spool/voice/messages/standard.rmd
02/24 17:35:29 yS0  vgetty: Could not open voice file: No such file or directory
02/24 17:35:29 yS0  vgetty: Could not play greeting message: No such file or directory
02/24 17:35:29 yS0    vgetty: queued event 0x4000 at position 0007
02/24 17:35:29 yS0   voice command: 'AT#VTS=[933,0,15]' -> 'OK'
02/24 17:35:29 yS0    vgetty: AT#VTS=[933,0,15]
02/24 17:35:29 yS0    Rockwell: AT#VTS=[933,0,15]
02/24 17:35:31 yS0    Rockwell: OK
02/24 17:35:31 yS0  recording voice file /var/spool/voice/incoming/va16949.rmd
02/24 17:35:31 yS0    vgetty: queued event 0x4000 at position 0008
02/24 17:35:31 yS0   voice command: 'AT#VBS=2' -> 'OK'
02/24 17:35:31 yS0    vgetty: AT#VBS=2
02/24 17:35:31 yS0    Rockwell: AT#VBS=2
02/24 17:35:31 yS0    Rockwell: OK
02/24 17:35:31 yS0    vgetty: queued event 0x4000 at position 0009
02/24 17:35:31 yS0    vgetty: unqueued event 0x4000 at position 0000
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:31 yS0    vgetty: unqueued event 0x4000 at position 0001
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:31 yS0    vgetty: unqueued event 0x2007 at position 0002
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x2007 with data <<>
02/24 17:35:31 yS0    vgetty: unqueued event 0x4000 at position 0003
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:31 yS0    vgetty: unqueued event 0x4000 at position 0004
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:31 yS0    vgetty: unqueued event 0x4000 at position 0005
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:31 yS0    vgetty: unqueued event 0x2007 at position 0006
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x2007 with data <_>
02/24 17:35:31 yS0    vgetty: unqueued event 0x4000 at position 0007
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x4000 with data <
02/24 17:35:31 yS0    vgetty: unqueued event 0x4000 at position 0008
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x4000 with data <
02/24 17:35:31 yS0    vgetty: unqueued event 0x4000 at position 0009
02/24 17:35:31 yS0    vgetty: voice_handle_event got event 0x4000 with data <
02/24 17:35:31 yS0   voice command: 'AT&K3' -> 'OK'
02/24 17:35:31 yS0    vgetty: AT&K3
02/24 17:35:31 yS0    Rockwell: AT&K3
02/24 17:35:31 yS0    Rockwell: OK
02/24 17:35:31 yS0   tio_set_flow_control( HARD XON_OUT )
02/24 17:35:31 yS0   voice command: 'AT#VRX' -> 'CONNECT'
02/24 17:35:31 yS0    vgetty: AT#VRX
02/24 17:35:31 yS0    Rockwell: AT#VRX
02/24 17:35:31 yS0    Rockwell: CONNECT
02/24 17:35:31 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:31 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    vgetty: queued event 0x4000 at position 0010
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:32 yS0    vgetty: unqueued event 0x4000 at position 0010
02/24 17:35:32 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:32 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    vgetty: queued event 0x4000 at position 0011
02/24 17:35:33 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:33 yS0    vgetty: unqueued event 0x4000 at position 0011
02/24 17:35:33 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:34 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:34 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:34 yS0    Rockwell: <DLE> <*>
02/24 17:35:34 yS0    vgetty: queued event 0x2011 at position 0012
02/24 17:35:34 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:34 yS0    vgetty: unqueued event 0x2011 at position 0012
02/24 17:35:34 yS0    vgetty: voice_handle_event got event 0x2011 with data <*>
02/24 17:35:34 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:34 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:34 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:34 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:34 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:34 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:35 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:35 yS0    vgetty: queued event 0x4000 at position 0013
02/24 17:35:35 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:35 yS0    vgetty: unqueued event 0x4000 at position 0013
02/24 17:35:35 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:35 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:35 yS0    Rockwell: <DLE> <1>
02/24 17:35:35 yS0    vgetty: queued event 0x2011 at position 0014
02/24 17:35:35 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:35 yS0    vgetty: unqueued event 0x2011 at position 0014
02/24 17:35:35 yS0    vgetty: voice_handle_event got event 0x2011 with data <1>
02/24 17:35:35 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:35 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:35 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:35 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:35 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:36 yS0    Rockwell: <DLE> <1>
02/24 17:35:36 yS0    vgetty: queued event 0x2011 at position 0015
02/24 17:35:36 yS0    Rockwell: <DATA 177 bytes>
02/24 17:35:36 yS0    vgetty: unqueued event 0x2011 at position 0015
02/24 17:35:36 yS0    vgetty: voice_handle_event got event 0x2011 with data <1>
02/24 17:35:36 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:36 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:36 yS0    vgetty: queued event 0x4000 at position 0016
02/24 17:35:36 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:36 yS0    vgetty: unqueued event 0x4000 at position 0016
02/24 17:35:36 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:36 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:36 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:36 yS0    Rockwell: <DLE> <1>
02/24 17:35:36 yS0    vgetty: queued event 0x2011 at position 0017
02/24 17:35:36 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:36 yS0    vgetty: unqueued event 0x2011 at position 0017
02/24 17:35:36 yS0    vgetty: voice_handle_event got event 0x2011 with data <1>
02/24 17:35:36 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:36 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:37 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:37 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:37 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:37 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:37 yS0    Rockwell: <DLE> <1>
02/24 17:35:37 yS0    vgetty: queued event 0x2011 at position 0018
02/24 17:35:37 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:37 yS0    vgetty: unqueued event 0x2011 at position 0018
02/24 17:35:37 yS0    vgetty: voice_handle_event got event 0x2011 with data <1>
02/24 17:35:37 yS0    vgetty: queued event 0x4000 at position 0019
02/24 17:35:37 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:37 yS0    vgetty: unqueued event 0x4000 at position 0019
02/24 17:35:37 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:37 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:37 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:37 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:38 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:38 yS0    Rockwell: <DLE> <1>
02/24 17:35:38 yS0    vgetty: queued event 0x2011 at position 0020
02/24 17:35:38 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:38 yS0    vgetty: unqueued event 0x2011 at position 0020
02/24 17:35:38 yS0    vgetty: voice_handle_event got event 0x2011 with data <1>
02/24 17:35:38 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:38 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:38 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:38 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:38 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:38 yS0    vgetty: queued event 0x4000 at position 0021
02/24 17:35:38 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:38 yS0    vgetty: unqueued event 0x4000 at position 0021
02/24 17:35:38 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:39 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:39 yS0    Rockwell: <DLE> <1>
02/24 17:35:39 yS0    vgetty: queued event 0x2011 at position 0022
02/24 17:35:39 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:39 yS0    vgetty: unqueued event 0x2011 at position 0022
02/24 17:35:39 yS0    vgetty: voice_handle_event got event 0x2011 with data <1>
02/24 17:35:39 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:39 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:39 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:39 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:39 yS0    Rockwell: <DLE> <1>
02/24 17:35:39 yS0    vgetty: queued event 0x2011 at position 0023
02/24 17:35:39 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:39 yS0    vgetty: unqueued event 0x2011 at position 0023
02/24 17:35:39 yS0    vgetty: voice_handle_event got event 0x2011 with data <1>
02/24 17:35:39 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:39 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:40 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:40 yS0    vgetty: queued event 0x4000 at position 0024
02/24 17:35:40 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:40 yS0    vgetty: unqueued event 0x4000 at position 0024
02/24 17:35:40 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:40 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:40 yS0    Rockwell: <DLE> <2>
02/24 17:35:40 yS0    vgetty: queued event 0x2011 at position 0025
02/24 17:35:40 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:40 yS0    vgetty: unqueued event 0x2011 at position 0025
02/24 17:35:40 yS0    vgetty: voice_handle_event got event 0x2011 with data <2>
02/24 17:35:40 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:40 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:40 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:40 yS0    Rockwell: <DLE> <3>
02/24 17:35:40 yS0    vgetty: queued event 0x2011 at position 0026
02/24 17:35:40 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:40 yS0    vgetty: unqueued event 0x2011 at position 0026
02/24 17:35:40 yS0    vgetty: voice_handle_event got event 0x2011 with data <3>
02/24 17:35:40 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:41 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:41 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:41 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:41 yS0    vgetty: queued event 0x4000 at position 0027
02/24 17:35:41 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:41 yS0    vgetty: unqueued event 0x4000 at position 0027
02/24 17:35:41 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:41 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:41 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:41 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:41 yS0    Rockwell: <DLE> <#>
02/24 17:35:41 yS0    vgetty: queued event 0x2011 at position 0028
02/24 17:35:41 yS0    Rockwell: <DATA 178 bytes>
02/24 17:35:41 yS0    vgetty: unqueued event 0x2011 at position 0028
02/24 17:35:41 yS0    vgetty: voice_handle_event got event 0x2011 with data <#>
02/24 17:35:41 yS0    vgetty: !
02/24 17:35:41 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    vgetty: queued event 0x4000 at position 0029
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    vgetty: unqueued event 0x4000 at position 0029
02/24 17:35:42 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:42 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    vgetty: queued event 0x4000 at position 0030
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    vgetty: unqueued event 0x4000 at position 0030
02/24 17:35:43 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:43 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    vgetty: queued event 0x4000 at position 0031
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    vgetty: unqueued event 0x4000 at position 0031
02/24 17:35:44 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:44 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    vgetty: queued event 0x4000 at position 0032
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:45 yS0    vgetty: unqueued event 0x4000 at position 0032
02/24 17:35:45 yS0    vgetty: voice_handle_event got event 0x4000 with data <_>
02/24 17:35:45 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:46 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:46 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:46 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:46 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:46 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:46 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:46 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:46 yS0    Rockwell: <DATA 180 bytes>
02/24 17:35:46 yS0    Rockwell: <DATA 0 bytes>
02/24 17:35:46 yS0    Rockwell: <DLE> <ETX>
02/24 17:35:46 yS0    Rockwell: data left in buffer: 
02/24 17:35:46 yS0   voice command: 'AT' -> 'OK'
02/24 17:35:47 yS0    vgetty: AT
02/24 17:35:47 yS0    Rockwell: VCON
02/24 17:35:47 yS0    Rockwell: 
02/24 17:35:58 yS0  vgetty: timeout while reading character from voice modem: No such file or directory
02/24 17:35:58 yS0  vgetty: Could not record a message: No such file or directory
02/24 17:35:58 yS0  vgetty: experimental test release 0.7.2 / 12Apr97
02/24 17:35:59 yS0  mgetty: experimental test release 1.1.8-Jul27
02/24 17:35:59 yS0  reading generic configuration from config file /etc/mgetty+sendfax/voice.conf
02/24 17:35:59 yS0  reading program vgetty configuration from config file /etc/mgetty+sendfax/voice.conf
02/24 17:35:59 yS0  reading port ttyS0 configuration from config file /etc/mgetty+sendfax/voice.conf
02/24 17:35:59 yS0   reading /etc/mgetty+sendfax/voice.conf...
02/24 17:35:59 yS0   conf lib: read: 'part generic'
02/24 17:35:59 yS0   found CT_KEYWORD part generic
02/24 17:35:59 yS0   conf lib: read: 'voice_log_level 6'
02/24 17:35:59 yS0   conf lib: read: 'voice_dir /var/spool/voice'
02/24 17:35:59 yS0   conf lib: read: 'message_flag_file .flag'
02/24 17:35:59 yS0   conf lib: read: 'receive_dir incoming'
02/24 17:35:59 yS0   conf lib: read: 'message_dir messages'
02/24 17:35:59 yS0   conf lib: read: 'message_list Index'
02/24 17:35:59 yS0   conf lib: read: 'backup_message standard.rmd'
02/24 17:35:59 yS0   conf lib: read: 'port_speed 38400'
02/24 17:35:59 yS0   conf lib: read: 'voice_shell /bin/sh'
02/24 17:35:59 yS0   conf lib: read: 'port_timeout 10'
02/24 17:35:59 yS0   conf lib: read: 'dial_timeout 90'
02/24 17:35:59 yS0   conf lib: read: 'command_delay 100'
02/24 17:35:59 yS0   conf lib: read: 'dtmf_len 10'
02/24 17:35:59 yS0   conf lib: read: 'dtmf_threshold 40'
02/24 17:35:59 yS0   conf lib: read: 'dtmf_wait 7'
02/24 17:35:59 yS0   conf lib: read: 'ignore_fax_dle false'
02/24 17:35:59 yS0   conf lib: read: 'raw_data false'
02/24 17:35:59 yS0   conf lib: read: 'rec_compression 0'
02/24 17:35:59 yS0   conf lib: read: 'rec_speed 0'
02/24 17:35:59 yS0   conf lib: read: 'rec_silence_len 70'
02/24 17:35:59 yS0   conf lib: read: 'rec_silence_threshold 40'
02/24 17:35:59 yS0   conf lib: read: 'rec_remove_silence false'
02/24 17:35:59 yS0   conf lib: read: 'rec_max_len 300'
02/24 17:35:59 yS0   conf lib: read: 'rec_min_len 0'
02/24 17:35:59 yS0   conf lib: read: 'do_hard_flow true'
02/24 17:35:59 yS0   conf lib: read: 'beep_frequency 933'
02/24 17:35:59 yS0   conf lib: read: 'beep_length 150'
02/24 17:35:59 yS0   conf lib: read: 'max_tries 3'
02/24 17:35:59 yS0   conf lib: read: 'retry_delay 5'
02/24 17:35:59 yS0   conf lib: read: 'watchdog_timeout 60'
02/24 17:35:59 yS0   conf lib: read: 'receive_gain -1'
02/24 17:35:59 yS0   conf lib: read: 'transmit_gain -1'
02/24 17:35:59 yS0   conf lib: read: 'enable_command_echo true'
02/24 17:35:59 yS0   conf lib: read: 'program vgetty'
02/24 17:35:59 yS0   conf lib: read: 'rings 1'
02/24 17:35:59 yS0   conf lib: read: 'answer_mode voice:fax:data'
02/24 17:35:59 yS0   conf lib: read: 'force_autodetect false'
02/24 17:35:59 yS0   conf lib: read: 'toll_saver_rings 0'
02/24 17:35:59 yS0   conf lib: read: 'rec_always_keep true'
02/24 17:35:59 yS0   conf lib: read: 'button_program '
02/24 17:35:59 yS0   conf lib: read: 'call_program '
02/24 17:35:59 yS0   conf lib: read: 'dtmf_program /tmp/dtmf.sh'
02/24 17:35:59 yS0   conf lib: read: 'message_program '
02/24 17:35:59 yS0   conf lib: read: 'do_message_light false'
02/24 17:35:59 yS0   conf lib: read: 'program vm'
02/24 17:35:59 yS0   conf lib: read: 'voice_devices '
02/24 17:35:59 yS0   conf lib: read: 'dialout_timeout 90'
02/24 17:35:59 yS0   conf lib: read: 'program pvf'
02/24 17:35:59 yS0   conf lib: read: 'port ttyS0'
02/24 17:35:59 yS0   section: port ttyS0, **found**
02/24 17:35:59 yS0   conf lib: read: 'port ttyS5'
02/24 17:35:59 yS0   section: port ttyS5, ignore
02/24 17:35:59 yS0   conf lib: read: 'ring_type virtual'
02/24 17:35:59 yS0   conf lib: read: 'answer_mode fax:data'
02/24 17:35:59 yS0   conf lib: read: 'ring_type ring'
02/24 17:35:59 yS0   conf lib: read: 'ring_type ring1'
02/24 17:35:59 yS0   key: 'part', type=6, flags=4, data=(ignored)
02/24 17:35:59 yS0   key: 'program', type=6, flags=4, data=(ignored)
02/24 17:35:59 yS0   key: 'port', type=6, flags=4, data=(ignored)
02/24 17:35:59 yS0   key: 'ring_type', type=6, flags=4, data=(ignored)
02/24 17:35:59 yS0   key: 'voice_log_level', type=0, flags=3, data=6
02/24 17:35:59 yS0   key: 'voice_devices', type=1, flags=0, data=(empty)
02/24 17:35:59 yS0   key: 'port_speed', type=0, flags=3, data=38400
02/24 17:35:59 yS0   key: 'port_timeout', type=0, flags=3, data=10
02/24 17:35:59 yS0   key: 'dtmf_len', type=0, flags=3, data=10
02/24 17:35:59 yS0   key: 'dtmf_threshold', type=0, flags=3, data=40
02/24 17:35:59 yS0   key: 'dtmf_wait', type=0, flags=3, data=7
02/24 17:35:59 yS0   key: 'rec_compression', type=0, flags=3, data=0
02/24 17:35:59 yS0   key: 'rec_speed', type=0, flags=3, data=0
02/24 17:35:59 yS0   key: 'rec_silence_len', type=0, flags=3, data=70
02/24 17:35:59 yS0   key: 'rec_silence_threshold', type=0, flags=3, data=40
02/24 17:35:59 yS0   key: 'rec_remove_silence', type=3, flags=3, data=FALSE
02/24 17:35:59 yS0   key: 'rec_max_len', type=0, flags=3, data=300
02/24 17:35:59 yS0   key: 'receive_gain', type=0, flags=3, data=-1
02/24 17:35:59 yS0   key: 'transmit_gain', type=0, flags=3, data=-1
02/24 17:35:59 yS0   key: 'rings', type=1, flags=3, data=1
02/24 17:35:59 yS0   key: 'answer_mode', type=1, flags=3, data=voice:fax:data
02/24 17:35:59 yS0   key: 'toll_saver_rings', type=0, flags=3, data=0
02/24 17:35:59 yS0   key: 'rec_always_keep', type=3, flags=3, data=TRUE
02/24 17:35:59 yS0   key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
02/24 17:35:59 yS0   key: 'message_flag_file', type=1, flags=3, data=.flag
02/24 17:35:59 yS0   key: 'receive_dir', type=1, flags=3, data=incoming
02/24 17:35:59 yS0   key: 'message_dir', type=1, flags=3, data=messages
02/24 17:35:59 yS0   key: 'message_list', type=1, flags=3, data=Index
02/24 17:35:59 yS0   key: 'backup_message', type=1, flags=3, data=standard.rmd
02/24 17:35:59 yS0   key: 'dialout_timeout', type=0, flags=1, data=90
02/24 17:35:59 yS0   key: 'beep_frequency', type=0, flags=3, data=933
02/24 17:35:59 yS0   key: 'beep_length', type=0, flags=3, data=150
02/24 17:35:59 yS0   key: 'raw_data', type=3, flags=3, data=FALSE
02/24 17:35:59 yS0   key: 'max_tries', type=0, flags=3, data=3
02/24 17:35:59 yS0   key: 'retry_delay', type=0, flags=3, data=5
02/24 17:35:59 yS0   key: 'voice_shell', type=1, flags=3, data=/bin/sh
02/24 17:35:59 yS0   key: 'button_program', type=1, flags=3, data=
02/24 17:35:59 yS0   key: 'call_program', type=1, flags=3, data=
02/24 17:35:59 yS0   key: 'dtmf_program', type=1, flags=3, data=/tmp/dtmf.sh
02/24 17:35:59 yS0   key: 'message_program', type=1, flags=3, data=
02/24 17:35:59 yS0   key: 'do_message_light', type=3, flags=3, data=FALSE
02/24 17:35:59 yS0   key: 'do_hard_flow', type=3, flags=3, data=TRUE
02/24 17:35:59 yS0   key: 'force_autodetect', type=3, flags=3, data=FALSE
02/24 17:35:59 yS0   key: 'watchdog_timeout', type=0, flags=3, data=60
02/24 17:35:59 yS0   key: 'rec_min_len', type=0, flags=3, data=0
02/24 17:35:59 yS0   key: 'command_delay', type=0, flags=3, data=100
02/24 17:35:59 yS0   key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
02/24 17:35:59 yS0   key: 'dial_timeout', type=0, flags=3, data=90
02/24 17:35:59 yS0   key: 'enable_command_echo', type=3, flags=3, data=TRUE
02/24 17:35:59 yS0  check for lockfiles
02/24 17:35:59 yS0   checklock: no active process has lock, will remove
02/24 17:35:59 yS0  locking the line
02/24 17:35:59 yS0   makelock(ttyS0) called
02/24 17:35:59 yS0   do_makelock: lock='/var/lock/LCK..ttyS0'
02/24 17:35:59 yS0   lock made
02/24 17:35:59 yS0  can't get group 'modem': No such file or directory
02/24 17:35:59 yS0   tio_get_rs232_lines: status: RTS CTS DSR DTR
02/24 17:35:59 yS0  lowering DTR to reset Modem
02/24 17:36:00 yS0   tss: set speed to 38400 (017)
02/24 17:36:00 yS0   tio_set_flow_control( HARD )
02/24 17:36:00 yS0   waiting for line to clear (VTIME), read: [0d][0a]OK[0d][0a]
02/24 17:36:00 yS0  send: \dATQ0V1H0[0d]
02/24 17:36:00 yS0  waiting for ``OK''
02/24 17:36:00 yS0   got: ATQ0V1H0[0d]
02/24 17:36:00 yS0    CND: ATQ0V1H0[0d][0a]OK ** found **
02/24 17:36:01 yS0  send: ATS0=0Q0&D3&C1[0d]
02/24 17:36:01 yS0  waiting for ``OK''
02/24 17:36:01 yS0   got: [0d]
02/24 17:36:01 yS0    CND: OK[0a]ATS0=0Q0&D3&C1[0d]
02/24 17:36:01 yS0    CND: ATS0=0Q0&D3&C1[0d][0a]OK ** found **
02/24 17:36:01 yS0  mdm_send: 'ATI'
02/24 17:36:01 yS0    got:[0d][0a]ATI[0d]
02/24 17:36:01 yS0    got:[0d][0a]MT5600ZDXV[0d]
02/24 17:36:01 yS0   mdm_gis: string 1: 'MT5600ZDXV'
02/24 17:36:01 yS0    got:[0a][0d][0a]OK[0d]
02/24 17:36:01 yS0   mdm_identify: string 'MT5600ZDXV'
02/24 17:36:01 yS0  non-numeric ID string: 'MT5600ZDXV'
02/24 17:36:01 yS0  mdm_send: 'AT+FCLASS=2.0'
02/24 17:36:01 yS0    got:[0a]AT+FCLASS=2.0[0d]
02/24 17:36:01 yS0   mdm_command: string 'AT+FCLASS=2.0'
02/24 17:36:01 yS0    got:[0d][0a]ERROR[0d]
02/24 17:36:01 yS0   mdm_command: string 'ERROR' -> ERROR
02/24 17:36:01 yS0  mdm_send: 'AT+FCLASS=2'
02/24 17:36:01 yS0    got:[0a]AT+FCLASS=2[0d]
02/24 17:36:01 yS0   mdm_command: string 'AT+FCLASS=2'
02/24 17:36:01 yS0    got:[0d][0a]OK[0d]
02/24 17:36:01 yS0   mdm_command: string 'OK' -> OK
02/24 17:36:01 yS0  mdm_send: 'AT+FCLASS=0'
02/24 17:36:01 yS0    got:[0a]AT+FCLASS=0[0d]
02/24 17:36:01 yS0   mdm_command: string 'AT+FCLASS=0'
02/24 17:36:01 yS0    got:[0d][0a]OK[0d]
02/24 17:36:01 yS0   mdm_command: string 'OK' -> OK
02/24 17:36:01 yS0  mdm_send: 'AT+FAA=1;+FCR=1'
02/24 17:36:01 yS0    got:[0a]AT+FAA=1;+FCR=1[0d]
02/24 17:36:01 yS0   mdm_command: string 'AT+FAA=1;+FCR=1'
02/24 17:36:01 yS0    got:[0d][0a]OK[0d]
02/24 17:36:01 yS0   mdm_command: string 'OK' -> OK
02/24 17:36:01 yS0  mdm_send: 'AT+FBOR=0'
02/24 17:36:01 yS0    got:[0a]AT+FBOR=0[0d]
02/24 17:36:01 yS0   mdm_command: string 'AT+FBOR=0'
02/24 17:36:01 yS0    got:[0d][0a]OK[0d]
02/24 17:36:01 yS0   mdm_command: string 'OK' -> OK
02/24 17:36:01 yS0  mdm_send: 'AT+FLID="49 9131 00000000"'
02/24 17:36:01 yS0    got:[0a]AT+FLID="49 9131 00000000"[0d]
02/24 17:36:01 yS0   mdm_command: string 'AT+FLID="49 9131 00000000"'
02/24 17:36:01 yS0    got:[0d][0a]OK[0d]
02/24 17:36:01 yS0   mdm_command: string 'OK' -> OK
02/24 17:36:01 yS0  mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0'
02/24 17:36:01 yS0    got:[0a]AT+FDCC=1,5,0,2,0,0,0,0[0d]
02/24 17:36:01 yS0   mdm_command: string 'AT+FDCC=1,5,0,2,0,0,0,0'
02/24 17:36:01 yS0    got:[0d][0a]OK[0d]
02/24 17:36:01 yS0   mdm_command: string 'OK' -> OK
02/24 17:36:01 yS0   tss: set speed to 38400 (017)
02/24 17:36:01 yS0   tio_set_flow_control( HARD )
02/24 17:36:01 yS0  detecting voice modem type
02/24 17:36:01 yS0    vgetty: ATE1
02/24 17:36:01 yS0   voice command: '' -> 'OK|ATE1'
02/24 17:36:01 yS0    serial port: ATE1
02/24 17:36:01 yS0    serial port: OK
02/24 17:36:01 yS0   voice command: 'ATI' -> ''
02/24 17:36:01 yS0    vgetty: ATI
02/24 17:36:01 yS0    serial port: ATI
02/24 17:36:01 yS0    serial port: MT5600ZDXV
02/24 17:36:01 yS0    Rockwell: OK
02/24 17:36:02 yS0  Rockwell detected
02/24 17:36:02 yS0    vgetty: queued event 0x4000 at position 0000
02/24 17:36:02 yS0  initializing ROCKWELL voice modem
02/24 17:36:02 yS0    vgetty: queued event 0x4000 at position 0001
02/24 17:36:02 yS0   voice command: 'AT#CLS?' -> ''
02/24 17:36:02 yS0    vgetty: AT#CLS?
02/24 17:36:02 yS0    Rockwell: AT#CLS?
02/24 17:36:02 yS0    Rockwell: 0
02/24 17:36:02 yS0   voice command: '' -> 'OK'
02/24 17:36:02 yS0    Rockwell: OK
02/24 17:36:02 yS0   voice command: 'AT#CLS=8' -> 'OK'
02/24 17:36:02 yS0    vgetty: AT#CLS=8
02/24 17:36:02 yS0    Rockwell: AT#CLS=8
02/24 17:36:02 yS0    Rockwell: OK
02/24 17:36:02 yS0   voice command: 'AT#VSP=70' -> 'OK'
02/24 17:36:02 yS0    vgetty: AT#VSP=70
02/24 17:36:02 yS0    Rockwell: <DLE> <h>
02/24 17:36:02 yS0    vgetty: queued event 0x2007 at position 0002
02/24 17:36:02 yS0    Rockwell: 
02/24 17:36:02 yS0    Rockwell: AT#VSP=70
02/24 17:36:02 yS0    Rockwell: OK
02/24 17:36:02 yS0   voice command: 'AT#VSD=0' -> 'OK'
02/24 17:36:02 yS0    vgetty: AT#VSD=0
02/24 17:36:02 yS0    Rockwell: AT#VSD=0
02/24 17:36:02 yS0    Rockwell: OK
02/24 17:36:02 yS0   voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
02/24 17:36:02 yS0    vgetty: AT#VTD=3F,3F,3F
02/24 17:36:02 yS0    Rockwell: AT#VTD=3F,3F,3F
02/24 17:36:02 yS0    Rockwell: OK
02/24 17:36:02 yS0   voice command: 'AT#VSS=1' -> 'OK'
02/24 17:36:02 yS0    vgetty: AT#VSS=1
02/24 17:36:02 yS0    Rockwell: AT#VSS=1
02/24 17:36:02 yS0    Rockwell: OK
02/24 17:36:02 yS0    vgetty: queued event 0x4000 at position 0003
02/24 17:36:02 yS0   voice command: 'AT#CLS=0' -> 'OK'
02/24 17:36:03 yS0    vgetty: AT#CLS=0
02/24 17:36:03 yS0    Rockwell: AT#CLS=0
02/24 17:36:03 yS0    Rockwell: OK
02/24 17:36:03 yS0   voice command: 'AT&K3' -> 'OK'
02/24 17:36:03 yS0    vgetty: AT&K3
02/24 17:36:03 yS0    Rockwell: AT&K3
02/24 17:36:03 yS0    Rockwell: OK
02/24 17:36:03 yS0   tio_set_flow_control( HARD )
02/24 17:36:03 yS0   waiting for line to clear (VTIME), read: 
02/24 17:36:04 yS0   removing lock file
02/24 17:36:04 yS0  waiting...

--------------3FA07A77826F29E0760017C2--