vgetty -> voice answer/fax receive problem.
"xy3@email.com" (xy3@email.com)
Tue, 21 Sep 1999 01:03:09 -0700
I've previously posted to the newsgroup, but later noticed the FAQ suggesting
this instead. The Web site listed as having the archived list doesn't work.
I have a Cardinal MVP288i, ROCKWELL chipset modem.
Linux 2.0.35.
I am trying to have vgetty answer in voice mode, but switch to fax mode when
faxes arrive.
ATI -> 33600
ATI1 -> 176
ATI2 -> OK
ATI3 -> V1.510V-V34_DP Data/Fax/Voice/VV/CID
ATI4 -> Cardinal, 020-0476, 30-Jul-96
ATI5 -> 022
ATI6 -> RCV288DPi Rev 05BA
ATI7 -> 255
(I've posted about how vgetty doesn't see this as a voice modem by default)
Part of the log when a fax arrives:
09/21 00:34:17 yS2 vgetty: answer mode is [data|fax|voice]
09/21 00:34:17 yS2 vgetty: opening list file /var/spool/voice/messages/Index
09/21 00:34:17 yS2 vgetty: found 1 messages, picked message number 1
09/21 00:34:17 yS2 vgetty: message name is
/var/spool/voice/messages/standard.rmd
09/21 00:34:17 yS2 vgetty: entering voice mode
09/21 00:34:17 yS2 vgetty: Installing signal handlers
09/21 00:34:17 yS2 voice command: 'AT#CLS?' -> ''
09/21 00:34:17 yS2 vgetty: AT#CLS?
09/21 00:34:17 yS2 Rockwell: N ECO
09/21 00:34:17 yS2 voice command: '' -> 'OK'
09/21 00:34:17 yS2 Rockwell: 0
09/21 00:34:17 yS2 vgetty: Invalid modem answer
09/21 00:34:17 yS2 Rockwell: OK
09/21 00:34:18 yS2 voice command: 'ATA' -> 'VCON'
09/21 00:34:18 yS2 vgetty: ATA
09/21 00:34:18 yS2 Rockwell: FAX
09/21 00:34:21 yS2 Rockwell: +FCON
09/21 00:34:27 yS2 Rockwell: +FTSI: " CBCBSC"
09/21 00:34:29 yS2 vgetty: Invalid modem answer
09/21 00:34:29 yS2 vgetty: queued event RESET_WATCHDOG at position 0002
09/21 00:34:29 yS2 voice command: 'AT#VLS=4' -> 'OK'
09/21 00:34:29 yS2 vgetty: AT#VLS=4
09/21 00:34:29 yS2 vgetty: unqueued event RESET_WATCHDOG at position 0002
09/21 00:34:29 yS2 vgetty: voice_handle_event got event RESET_WATCHDOG with
data <_>
09/21 00:34:29 yS2 Rockwell: +FDCS: 1,5,0,2,0,0,0,0
09/21 00:34:29 yS2 vgetty: Invalid modem answer
09/21 00:34:29 yS2 Rockwell: OK
09/21 00:34:29 yS2 vgetty: Could not answer the phone. Strange...
===============
The Rockwell: return messages don't seem to be correct. Why is this? Is it
related to FLOW CONTROL or something else? Rockwell.c has AT&K3 hardcoded in
it, and my log seems to indicate it is using hardware flow control. (although
the online Rockwell HTML page suggests AT&K6 for Fax and Voice mode but I don't
know if this is at all related.).
I've tried FAXREC_FLOW FLOW_BOTH in mgetty/policy.h but I still have the same
problem.
I've also tried 'do_hard_flow false', but that didn't make any difference.
It seems to do the tio_set_flow_control( HARD ) automatically anyway, so I
don't even know if I'm on the right track. A modem quirk of some kind or
something else?
Here is the full log if it helps. I appreciate any help.
09/21 00:29:14 yS2 vgetty: experimental test release 0.9.7 / 16Sep99
09/21 00:29:14 yS2 mgetty: experimental test release 1.1.21-Jul24
09/21 00:29:14 yS2 reading generic configuration from config file
/usr/local/mgetty/voice.conf
09/21 00:29:14 yS2 reading program vgetty configuration from config file
/usr/local/mgetty/voice.conf
09/21 00:29:14 yS2 reading /usr/local/mgetty/voice.conf...
09/21 00:29:14 yS2 conf lib: read: 'part generic'
09/21 00:29:14 yS2 found CT_KEYWORD part generic
09/21 00:29:14 yS2 conf lib: read: 'voice_log_level 6'
09/21 00:29:14 yS2 conf lib: read: 'voice_dir /var/spool/voice'
09/21 00:29:14 yS2 conf lib: read: 'phone_owner root'
09/21 00:29:14 yS2 conf lib: read: 'phone_group phone'
09/21 00:29:14 yS2 conf lib: read: 'phone_mode 0660'
09/21 00:29:14 yS2 conf lib: read: 'message_flag_file .flag'
09/21 00:29:14 yS2 conf lib: read: 'receive_dir incoming'
09/21 00:29:14 yS2 conf lib: read: 'message_dir messages'
09/21 00:29:14 yS2 conf lib: read: 'message_list Index'
09/21 00:29:14 yS2 conf lib: read: 'backup_message standard.rmd'
09/21 00:29:14 yS2 conf lib: read: 'port_speed 38400'
09/21 00:29:14 yS2 conf lib: read: 'voice_shell /bin/sh'
09/21 00:29:14 yS2 conf lib: read: 'port_timeout 10'
09/21 00:29:14 yS2 conf lib: read: 'dial_timeout 90'
09/21 00:29:14 yS2 conf lib: read: 'command_delay 100'
09/21 00:29:14 yS2 conf lib: read: 'dtmf_len 30'
09/21 00:29:14 yS2 conf lib: read: 'dtmf_threshold 40'
09/21 00:29:14 yS2 conf lib: read: 'dtmf_wait 7'
09/21 00:29:14 yS2 conf lib: read: 'ignore_fax_dle false'
09/21 00:29:14 yS2 conf lib: read: 'raw_data false'
09/21 00:29:14 yS2 conf lib: read: 'rec_compression 0'
09/21 00:29:14 yS2 conf lib: read: 'rec_speed 0'
09/21 00:29:14 yS2 conf lib: read: 'rec_silence_len 70'
09/21 00:29:14 yS2 conf lib: read: 'rec_silence_threshold 40'
09/21 00:29:14 yS2 conf lib: read: 'rec_remove_silence false'
09/21 00:29:14 yS2 conf lib: read: 'rec_max_len 300'
09/21 00:29:14 yS2 conf lib: read: 'rec_min_len 0'
09/21 00:29:14 yS2 conf lib: read: 'do_hard_flow false'
09/21 00:29:14 yS2 conf lib: read: 'beep_frequency 933'
09/21 00:29:14 yS2 conf lib: read: 'beep_length 1500'
09/21 00:29:14 yS2 conf lib: read: 'max_tries 3'
09/21 00:29:14 yS2 conf lib: read: 'retry_delay 5'
09/21 00:29:14 yS2 conf lib: read: 'watchdog_timeout 60'
09/21 00:29:14 yS2 conf lib: read: 'receive_gain -1'
09/21 00:29:14 yS2 conf lib: read: 'transmit_gain -1'
09/21 00:29:14 yS2 conf lib: read: 'enable_command_echo false'
09/21 00:29:14 yS2 conf lib: read: 'poll_interval 10'
09/21 00:29:14 yS2 conf lib: read: 'program vgetty'
09/21 00:29:14 yS2 section: program vgetty, **found**
09/21 00:29:14 yS2 conf lib: read: 'rings /etc/rings'
09/21 00:29:14 yS2 conf lib: read: 'answer_mode voice:fax:data'
09/21 00:29:14 yS2 conf lib: read: 'force_autodetect false'
09/21 00:29:14 yS2 conf lib: read: 'toll_saver_rings 0'
09/21 00:29:14 yS2 conf lib: read: 'rec_always_keep true'
09/21 00:29:14 yS2 conf lib: read: 'button_program '
09/21 00:29:14 yS2 conf lib: read: 'call_program '
09/21 00:29:14 yS2 conf lib: read: 'dtmf_program dtmf.sh'
09/21 00:29:14 yS2 conf lib: read: 'message_program '
09/21 00:29:14 yS2 conf lib: read: 'do_message_light false'
09/21 00:29:14 yS2 conf lib: read: 'program vm'
09/21 00:29:14 yS2 section: program vm, ignore
09/21 00:29:14 yS2 conf lib: read: 'voice_devices ttyS2'
09/21 00:29:14 yS2 conf lib: read: 'dialout_timeout 90'
09/21 00:29:14 yS2 conf lib: read: 'ringback_goes_away 70'
09/21 00:29:14 yS2 conf lib: read: 'ringback_never_came 100'
09/21 00:29:14 yS2 conf lib: read: 'program pvf'
09/21 00:29:14 yS2 section: program pvf, ignore
09/21 00:29:14 yS2 key: 'part', type=6, flags=4, data=(ignored)
09/21 00:29:14 yS2 key: 'program', type=6, flags=4, data=(ignored)
09/21 00:29:14 yS2 key: 'port', type=6, flags=4, data=(ignored)
09/21 00:29:14 yS2 key: 'ring_type', type=6, flags=4, data=(ignored)
09/21 00:29:14 yS2 key: 'voice_log_level', type=0, flags=3, data=6
09/21 00:29:14 yS2 key: 'voice_shell', type=1, flags=3, data=/bin/sh
09/21 00:29:14 yS2 key: 'port_speed', type=0, flags=3, data=38400
09/21 00:29:14 yS2 key: 'port_timeout', type=0, flags=3, data=10
09/21 00:29:14 yS2 key: 'dial_timeout', type=0, flags=3, data=90
09/21 00:29:14 yS2 key: 'command_delay', type=0, flags=3, data=100
09/21 00:29:14 yS2 key: 'dtmf_len', type=0, flags=3, data=30
09/21 00:29:14 yS2 key: 'dtmf_threshold', type=0, flags=3, data=40
09/21 00:29:14 yS2 key: 'dtmf_wait', type=0, flags=3, data=7
09/21 00:29:14 yS2 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'raw_data', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'rec_compression', type=0, flags=3, data=0
09/21 00:29:14 yS2 key: 'rec_speed', type=0, flags=3, data=0
09/21 00:29:14 yS2 key: 'rec_silence_len', type=0, flags=3, data=70
09/21 00:29:14 yS2 key: 'rec_silence_threshold', type=0, flags=3, data=40
09/21 00:29:14 yS2 key: 'rec_remove_silence', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'rec_max_len', type=0, flags=3, data=300
09/21 00:29:14 yS2 key: 'rec_min_len', type=0, flags=3, data=0
09/21 00:29:14 yS2 key: 'do_hard_flow', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'force_autodetect', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'watchdog_timeout', type=0, flags=3, data=60
09/21 00:29:14 yS2 key: 'receive_gain', type=0, flags=3, data=-1
09/21 00:29:14 yS2 key: 'transmit_gain', type=0, flags=3, data=-1
09/21 00:29:14 yS2 key: 'enable_command_echo', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'poll_interval', type=0, flags=3, data=10
09/21 00:29:14 yS2 key: 'rings', type=1, flags=3, data=/etc/rings
09/21 00:29:14 yS2 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
09/21 00:29:14 yS2 key: 'toll_saver_rings', type=0, flags=3, data=0
09/21 00:29:14 yS2 key: 'rec_always_keep', type=3, flags=3, data=TRUE
09/21 00:29:14 yS2 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
09/21 00:29:14 yS2 key: 'phone_owner', type=1, flags=3, data=root
09/21 00:29:14 yS2 key: 'phone_group', type=1, flags=3, data=phone
09/21 00:29:14 yS2 key: 'phone_mode', type=0, flags=3, data=432
09/21 00:29:14 yS2 key: 'message_flag_file', type=1, flags=3, data=.flag
09/21 00:29:14 yS2 key: 'receive_dir', type=1, flags=3, data=incoming
09/21 00:29:14 yS2 key: 'message_dir', type=1, flags=3, data=messages
09/21 00:29:14 yS2 key: 'message_list', type=1, flags=3, data=Index
09/21 00:29:14 yS2 key: 'backup_message', type=1, flags=3, data=standard.rmd
09/21 00:29:14 yS2 key: 'button_program', type=1, flags=3, data=
09/21 00:29:14 yS2 key: 'call_program', type=1, flags=3, data=
09/21 00:29:14 yS2 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
09/21 00:29:14 yS2 key: 'message_program', type=1, flags=3, data=
09/21 00:29:14 yS2 key: 'do_message_light', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'beep_frequency', type=0, flags=3, data=933
09/21 00:29:14 yS2 key: 'beep_length', type=0, flags=3, data=1500
09/21 00:29:14 yS2 key: 'max_tries', type=0, flags=3, data=3
09/21 00:29:14 yS2 key: 'retry_delay', type=0, flags=3, data=5
09/21 00:29:14 yS2 key: 'dialout_timeout', type=0, flags=1, data=90
09/21 00:29:14 yS2 key: 'ringback_goes_away', type=0, flags=1, data=70
09/21 00:29:14 yS2 key: 'ringback_never_came', type=0, flags=1, data=100
09/21 00:29:14 yS2 key: 'voice_devices', type=1, flags=1, data=
09/21 00:29:14 yS2 reading port ttyS2 configuration from config file
/usr/local/mgetty/voice.conf
09/21 00:29:14 yS2 reading /usr/local/mgetty/voice.conf...
09/21 00:29:14 yS2 conf lib: read: 'part generic'
09/21 00:29:14 yS2 found CT_KEYWORD part generic
09/21 00:29:14 yS2 conf lib: read: 'voice_log_level 6'
09/21 00:29:14 yS2 conf lib: read: 'voice_dir /var/spool/voice'
09/21 00:29:14 yS2 conf lib: read: 'phone_owner root'
09/21 00:29:14 yS2 conf lib: read: 'phone_group phone'
09/21 00:29:14 yS2 conf lib: read: 'phone_mode 0660'
09/21 00:29:14 yS2 conf lib: read: 'message_flag_file .flag'
09/21 00:29:14 yS2 conf lib: read: 'receive_dir incoming'
09/21 00:29:14 yS2 conf lib: read: 'message_dir messages'
09/21 00:29:14 yS2 conf lib: read: 'message_list Index'
09/21 00:29:14 yS2 conf lib: read: 'backup_message standard.rmd'
09/21 00:29:14 yS2 conf lib: read: 'port_speed 38400'
09/21 00:29:14 yS2 conf lib: read: 'voice_shell /bin/sh'
09/21 00:29:14 yS2 conf lib: read: 'port_timeout 10'
09/21 00:29:14 yS2 conf lib: read: 'dial_timeout 90'
09/21 00:29:14 yS2 conf lib: read: 'command_delay 100'
09/21 00:29:14 yS2 conf lib: read: 'dtmf_len 30'
09/21 00:29:14 yS2 conf lib: read: 'dtmf_threshold 40'
09/21 00:29:14 yS2 conf lib: read: 'dtmf_wait 7'
09/21 00:29:14 yS2 conf lib: read: 'ignore_fax_dle false'
09/21 00:29:14 yS2 conf lib: read: 'raw_data false'
09/21 00:29:14 yS2 conf lib: read: 'rec_compression 0'
09/21 00:29:14 yS2 conf lib: read: 'rec_speed 0'
09/21 00:29:14 yS2 conf lib: read: 'rec_silence_len 70'
09/21 00:29:14 yS2 conf lib: read: 'rec_silence_threshold 40'
09/21 00:29:14 yS2 conf lib: read: 'rec_remove_silence false'
09/21 00:29:14 yS2 conf lib: read: 'rec_max_len 300'
09/21 00:29:14 yS2 conf lib: read: 'rec_min_len 0'
09/21 00:29:14 yS2 conf lib: read: 'do_hard_flow false'
09/21 00:29:14 yS2 conf lib: read: 'beep_frequency 933'
09/21 00:29:14 yS2 conf lib: read: 'beep_length 1500'
09/21 00:29:14 yS2 conf lib: read: 'max_tries 3'
09/21 00:29:14 yS2 conf lib: read: 'retry_delay 5'
09/21 00:29:14 yS2 conf lib: read: 'watchdog_timeout 60'
09/21 00:29:14 yS2 conf lib: read: 'receive_gain -1'
09/21 00:29:14 yS2 conf lib: read: 'transmit_gain -1'
09/21 00:29:14 yS2 conf lib: read: 'enable_command_echo false'
09/21 00:29:14 yS2 conf lib: read: 'poll_interval 10'
09/21 00:29:14 yS2 conf lib: read: 'program vgetty'
09/21 00:29:14 yS2 conf lib: read: 'rings /etc/rings'
09/21 00:29:14 yS2 conf lib: read: 'answer_mode voice:fax:data'
09/21 00:29:14 yS2 conf lib: read: 'force_autodetect false'
09/21 00:29:14 yS2 conf lib: read: 'toll_saver_rings 0'
09/21 00:29:14 yS2 conf lib: read: 'rec_always_keep true'
09/21 00:29:14 yS2 conf lib: read: 'button_program '
09/21 00:29:14 yS2 conf lib: read: 'call_program '
09/21 00:29:14 yS2 conf lib: read: 'dtmf_program dtmf.sh'
09/21 00:29:14 yS2 conf lib: read: 'message_program '
09/21 00:29:14 yS2 conf lib: read: 'do_message_light false'
09/21 00:29:14 yS2 conf lib: read: 'program vm'
09/21 00:29:14 yS2 conf lib: read: 'voice_devices ttyS2'
09/21 00:29:14 yS2 conf lib: read: 'dialout_timeout 90'
09/21 00:29:14 yS2 conf lib: read: 'ringback_goes_away 70'
09/21 00:29:14 yS2 conf lib: read: 'ringback_never_came 100'
09/21 00:29:14 yS2 conf lib: read: 'program pvf'
09/21 00:29:14 yS2 key: 'part', type=6, flags=4, data=(ignored)
09/21 00:29:14 yS2 key: 'program', type=6, flags=4, data=(ignored)
09/21 00:29:14 yS2 key: 'port', type=6, flags=4, data=(ignored)
09/21 00:29:14 yS2 key: 'ring_type', type=6, flags=4, data=(ignored)
09/21 00:29:14 yS2 key: 'voice_log_level', type=0, flags=3, data=6
09/21 00:29:14 yS2 key: 'voice_shell', type=1, flags=3, data=/bin/sh
09/21 00:29:14 yS2 key: 'port_speed', type=0, flags=3, data=38400
09/21 00:29:14 yS2 key: 'port_timeout', type=0, flags=3, data=10
09/21 00:29:14 yS2 key: 'dial_timeout', type=0, flags=3, data=90
09/21 00:29:14 yS2 key: 'command_delay', type=0, flags=3, data=100
09/21 00:29:14 yS2 key: 'dtmf_len', type=0, flags=3, data=30
09/21 00:29:14 yS2 key: 'dtmf_threshold', type=0, flags=3, data=40
09/21 00:29:14 yS2 key: 'dtmf_wait', type=0, flags=3, data=7
09/21 00:29:14 yS2 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'raw_data', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'rec_compression', type=0, flags=3, data=0
09/21 00:29:14 yS2 key: 'rec_speed', type=0, flags=3, data=0
09/21 00:29:14 yS2 key: 'rec_silence_len', type=0, flags=3, data=70
09/21 00:29:14 yS2 key: 'rec_silence_threshold', type=0, flags=3, data=40
09/21 00:29:14 yS2 key: 'rec_remove_silence', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'rec_max_len', type=0, flags=3, data=300
09/21 00:29:14 yS2 key: 'rec_min_len', type=0, flags=3, data=0
09/21 00:29:14 yS2 key: 'do_hard_flow', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'force_autodetect', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'watchdog_timeout', type=0, flags=3, data=60
09/21 00:29:14 yS2 key: 'receive_gain', type=0, flags=3, data=-1
09/21 00:29:14 yS2 key: 'transmit_gain', type=0, flags=3, data=-1
09/21 00:29:14 yS2 key: 'enable_command_echo', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'poll_interval', type=0, flags=3, data=10
09/21 00:29:14 yS2 key: 'rings', type=1, flags=3, data=/etc/rings
09/21 00:29:14 yS2 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
09/21 00:29:14 yS2 key: 'toll_saver_rings', type=0, flags=3, data=0
09/21 00:29:14 yS2 key: 'rec_always_keep', type=3, flags=3, data=TRUE
09/21 00:29:14 yS2 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
09/21 00:29:14 yS2 key: 'phone_owner', type=1, flags=3, data=root
09/21 00:29:14 yS2 key: 'phone_group', type=1, flags=3, data=phone
09/21 00:29:14 yS2 key: 'phone_mode', type=0, flags=3, data=432
09/21 00:29:14 yS2 key: 'message_flag_file', type=1, flags=3, data=.flag
09/21 00:29:14 yS2 key: 'receive_dir', type=1, flags=3, data=incoming
09/21 00:29:14 yS2 key: 'message_dir', type=1, flags=3, data=messages
09/21 00:29:14 yS2 key: 'message_list', type=1, flags=3, data=Index
09/21 00:29:14 yS2 key: 'backup_message', type=1, flags=3, data=standard.rmd
09/21 00:29:14 yS2 key: 'button_program', type=1, flags=3, data=
09/21 00:29:14 yS2 key: 'call_program', type=1, flags=3, data=
09/21 00:29:14 yS2 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
09/21 00:29:14 yS2 key: 'message_program', type=1, flags=3, data=
09/21 00:29:14 yS2 key: 'do_message_light', type=3, flags=3, data=FALSE
09/21 00:29:14 yS2 key: 'beep_frequency', type=0, flags=3, data=933
09/21 00:29:14 yS2 key: 'beep_length', type=0, flags=3, data=1500
09/21 00:29:14 yS2 key: 'max_tries', type=0, flags=3, data=3
09/21 00:29:14 yS2 key: 'retry_delay', type=0, flags=3, data=5
09/21 00:29:14 yS2 key: 'dialout_timeout', type=0, flags=1, data=90
09/21 00:29:14 yS2 key: 'ringback_goes_away', type=0, flags=1, data=70
09/21 00:29:14 yS2 key: 'ringback_never_came', type=0, flags=1, data=100
09/21 00:29:14 yS2 key: 'voice_devices', type=1, flags=1, data=
09/21 00:29:14 yS2 check for lockfiles
09/21 00:29:14 yS2 checklock: stat failed, no file
09/21 00:29:14 yS2 locking the line
09/21 00:29:14 yS2 makelock(ttyS2) called
09/21 00:29:14 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
09/21 00:29:14 yS2 lock made
09/21 00:29:14 yS2 tio_get_rs232_lines: status: RTS CTS DSR DTR
09/21 00:29:14 yS2 lowering DTR to reset Modem
09/21 00:29:15 yS2 tss: set speed to 38400 (017)
09/21 00:29:15 yS2 tio_set_flow_control( HARD )
09/21 00:29:15 yS2 waiting for line to clear (VTIME), read:
09/21 00:29:15 yS2 send: AT&F[0d]
09/21 00:29:15 yS2 waiting for ``OK''
09/21 00:29:15 yS2 got: AT&F[0d]
09/21 00:29:15 yS2 CND: AT&F[0d][0a]OK ** found **
09/21 00:29:15 yS2 send: AT-SDR=7S0=0M0Q0&D3&K6&C1#CID=1[0d]
09/21 00:29:15 yS2 waiting for ``OK''
09/21 00:29:15 yS2 got: [0d]
09/21 00:29:15 yS2 CND: OK[0a]AT-SDR=7S0=0M0Q0&D3&K6&C1#CID=1[0d]
09/21 00:29:15 yS2 CND: AT-SDR=7S0=0M0Q0&D3&K6&C1#CID=1[0d][0a]OK ** found
**
09/21 00:29:15 yS2 mdm_send: 'AT+FCLASS=2'
09/21 00:29:15 yS2 got:[0d][0a]AT+FCLASS=2[0d]
09/21 00:29:15 yS2 mdm_command: string 'AT+FCLASS=2'
09/21 00:29:15 yS2 got:[0d][0a]OK[0d]
09/21 00:29:15 yS2 mdm_command: string 'OK' -> OK
09/21 00:29:15 yS2 mdm_send: 'AT+FCLASS=0'
09/21 00:29:15 yS2 got:[0a]AT+FCLASS=0[0d]
09/21 00:29:15 yS2 mdm_command: string 'AT+FCLASS=0'
09/21 00:29:15 yS2 got:[0d][0a]OK[0d]
09/21 00:29:15 yS2 mdm_command: string 'OK' -> OK
09/21 00:29:15 yS2 mdm_send: 'AT+FAA=1;+FCR=1'
09/21 00:29:15 yS2 got:[0a]AT+FAA=1;+FCR=1[0d]
09/21 00:29:15 yS2 mdm_command: string 'AT+FAA=1;+FCR=1'
09/21 00:29:15 yS2 got:[0d][0a]OK[0d]
09/21 00:29:15 yS2 mdm_command: string 'OK' -> OK
09/21 00:29:15 yS2 mdm_send: 'AT+FBOR=0'
09/21 00:29:15 yS2 got:[0a]AT+FBOR=0[0d]
09/21 00:29:15 yS2 mdm_command: string 'AT+FBOR=0'
09/21 00:29:15 yS2 got:[0d][0a]OK[0d]
09/21 00:29:15 yS2 mdm_command: string 'OK' -> OK
09/21 00:29:16 yS2 mdm_send: 'AT+FLID="49 115 xxxxxxxx"'
09/21 00:29:16 yS2 got:[0a]AT+FLID="49 115 xxxxxxxx"[0d]
09/21 00:29:16 yS2 mdm_command: string 'AT+FLID="49 115 xxxxxxxx"'
09/21 00:29:16 yS2 got:[0d][0a]OK[0d]
09/21 00:29:16 yS2 mdm_command: string 'OK' -> OK
09/21 00:29:16 yS2 mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0'
09/21 00:29:16 yS2 got:[0a]AT+FDCC=1,5,0,2,0,0,0,0[0d]
09/21 00:29:16 yS2 mdm_command: string 'AT+FDCC=1,5,0,2,0,0,0,0'
09/21 00:29:16 yS2 got:[0d][0a]OK[0d]
09/21 00:29:16 yS2 mdm_command: string 'OK' -> OK
09/21 00:29:16 yS2 tss: set speed to 38400 (017)
09/21 00:29:16 yS2 tio_set_flow_control( HARD )
09/21 00:29:16 yS2 detecting voice modem type
09/21 00:29:16 yS2 vgetty: ATE0
09/21 00:29:16 yS2 serial port: ATE0
09/21 00:29:16 yS2 serial port: OK
09/21 00:29:16 yS2 voice command: 'ATI9' -> ''
09/21 00:29:16 yS2 vgetty: ATI9
09/21 00:29:16 yS2 serial port: ERROR
09/21 00:29:17 yS2 voice command: 'ATI' -> ''
09/21 00:29:17 yS2 vgetty: ATI
09/21 00:29:17 yS2 serial port: 33600
09/21 00:29:17 yS2 Rockwell: OK
09/21 00:29:17 yS2 Rockwell detected
09/21 00:29:17 yS2 vgetty: entering voice mode
09/21 00:29:17 yS2 vgetty: Installing signal handlers
09/21 00:29:17 yS2 voice command: 'AT#CLS?' -> ''
09/21 00:29:17 yS2 vgetty: AT#CLS?
09/21 00:29:17 yS2 Rockwell: 0
09/21 00:29:17 yS2 voice command: '' -> 'OK'
09/21 00:29:17 yS2 Rockwell: OK
09/21 00:29:17 yS2 voice command: 'AT#CLS=8' -> 'OK'
09/21 00:29:17 yS2 vgetty: AT#CLS=8
09/21 00:29:17 yS2 Rockwell: OK
09/21 00:29:17 yS2 voice command: 'AT' -> 'OK'
09/21 00:29:18 yS2 vgetty: AT
09/21 00:29:18 yS2 Rockwell: <DLE> <h>
09/21 00:29:18 yS2 vgetty: queued event HANDSET_ON_HOOK at position 0000
09/21 00:29:18 yS2 Rockwell:
09/21 00:29:18 yS2 Rockwell: OK
09/21 00:29:18 yS2 vgetty: queued event RESET_WATCHDOG at position 0001
09/21 00:29:18 yS2 initializing ROCKWELL voice modem
09/21 00:29:18 yS2 voice command: 'AT#VSP=70' -> 'OK'
09/21 00:29:18 yS2 vgetty: AT#VSP=70
09/21 00:29:18 yS2 vgetty: unqueued event HANDSET_ON_HOOK at position 0000
09/21 00:29:18 yS2 vgetty: voice_handle_event got event HANDSET_ON_HOOK with
data <NUL>
09/21 00:29:18 yS2 vgetty: unqueued event RESET_WATCHDOG at position 0001
09/21 00:29:18 yS2 vgetty: voice_handle_event got event RESET_WATCHDOG with
data <NUL>
09/21 00:29:18 yS2 Rockwell: OK
09/21 00:29:18 yS2 voice command: 'AT#VSD=0' -> 'OK'
09/21 00:29:18 yS2 vgetty: AT#VSD=0
09/21 00:29:18 yS2 Rockwell: OK
09/21 00:29:18 yS2 voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
09/21 00:29:18 yS2 vgetty: AT#VTD=3F,3F,3F
09/21 00:29:18 yS2 Rockwell: OK
09/21 00:29:18 yS2 voice command: 'AT#VSS=1' -> 'OK'
09/21 00:29:18 yS2 vgetty: AT#VSS=1
09/21 00:29:18 yS2 Rockwell: OK
09/21 00:29:18 yS2 voice command: 'AT&K3' -> 'OK'
09/21 00:29:18 yS2 vgetty: AT&K3
09/21 00:29:18 yS2 Rockwell: OK
09/21 00:29:18 yS2 tio_set_flow_control( HARD )
09/21 00:29:18 yS2 vgetty: leaving voice mode
09/21 00:29:18 yS2 voice command: 'AT#CLS=0' -> 'OK'
09/21 00:29:18 yS2 vgetty: AT#CLS=0
09/21 00:29:18 yS2 Rockwell: OK
09/21 00:29:18 yS2 voice command: 'AT' -> 'OK'
09/21 00:29:18 yS2 vgetty: AT
09/21 00:29:18 yS2 Rockwell: OK
09/21 00:29:18 yS2 vgetty: Restoring signal handlers
09/21 00:29:18 yS2 waiting for line to clear (VTIME), read:
09/21 00:29:19 yS2 removing lock file
09/21 00:29:19 yS2 waiting...
09/21 00:34:12 yS2 select returned 1
09/21 00:34:12 yS2 checking lockfiles, locking the line
09/21 00:34:12 yS2 makelock(ttyS2) called
09/21 00:34:12 yS2 do_makelock: lock='/var/lock/LCK..ttyS2'
09/21 00:34:12 yS2 lock made
09/21 00:34:13 yS2 vgetty: read number of rings (2) from file
/etc/rings.ttyS2
09/21 00:34:13 yS2 vgetty: decremented number of rings (2) by 0
09/21 00:34:13 yS2 wfr: waiting for ``RING''
09/21 00:34:13 yS2 got: [0a][0d][0a]RING3[0d]
09/21 00:34:13 yS2 CND: RING3
09/21 00:34:13 yS2 wfr: rc=0, drn=3
09/21 00:34:13 yS2 wfr: waiting for ``RING''
09/21 00:34:13 yS2 got: [0a][0d][0a]DATE = 0921[0d]
09/21 00:34:14 yS2 CND: DATE = 0921
09/21 00:34:14 yS2 CND: found: 0921
09/21 00:34:14 yS2 got: [0a]TIME = 0033[0d]
09/21 00:34:14 yS2 CND: TIME = 0033
09/21 00:34:14 yS2 CND: found: 0033
09/21 00:34:14 yS2 got: [0a]NMBR = 6047755519[0d]
09/21 00:34:14 yS2 CND: NMBR = 2247755519
09/21 00:34:14 yS2 CND: found: 2247755519
09/21 00:34:14 yS2 wfr: rc=0, drn=3
09/21 00:34:14 yS2 CND: check no: '2247755519'
09/21 00:34:14 yS2 CND: program "/usr/local/mgetty/cid ttyS2 '2247755519' ''
3 '' >/dev/console 2>&1 </dev/null"
09/21 00:34:17 yS2 CND: rc=0x0
09/21 00:34:17 yS2 cannot set controlling tty (ioctl): Operation not permitted
09/21 00:34:17 yS2 >>> this might be caused because you have run mgetty/vgetty
09/21 00:34:17 yS2 >>> from the command line. Don't do that, use
/etc/inittab!
09/21 00:34:17 yS2 setenv: 'CALLER_ID=2247755519'
09/21 00:34:17 yS2 setenv: 'CALLED_ID='
09/21 00:34:17 yS2 setenv: 'CONNECT='
09/21 00:34:17 yS2 reading ring_type ring3 configuration from config file
/usr/local/mgetty/voice.conf
09/21 00:34:17 yS2 reading /usr/local/mgetty/voice.conf...
09/21 00:34:17 yS2 conf lib: read: 'part generic'
09/21 00:34:17 yS2 found CT_KEYWORD part generic
09/21 00:34:17 yS2 conf lib: read: 'voice_log_level 6'
09/21 00:34:17 yS2 conf lib: read: 'voice_dir /var/spool/voice'
09/21 00:34:17 yS2 conf lib: read: 'phone_owner root'
09/21 00:34:17 yS2 conf lib: read: 'phone_group phone'
09/21 00:34:17 yS2 conf lib: read: 'phone_mode 0660'
09/21 00:34:17 yS2 conf lib: read: 'message_flag_file .flag'
09/21 00:34:17 yS2 conf lib: read: 'receive_dir incoming'
09/21 00:34:17 yS2 conf lib: read: 'message_dir messages'
09/21 00:34:17 yS2 conf lib: read: 'message_list Index'
09/21 00:34:17 yS2 conf lib: read: 'backup_message standard.rmd'
09/21 00:34:17 yS2 conf lib: read: 'port_speed 38400'
09/21 00:34:17 yS2 conf lib: read: 'voice_shell /bin/sh'
09/21 00:34:17 yS2 conf lib: read: 'port_timeout 10'
09/21 00:34:17 yS2 conf lib: read: 'dial_timeout 90'
09/21 00:34:17 yS2 conf lib: read: 'command_delay 100'
09/21 00:34:17 yS2 conf lib: read: 'dtmf_len 30'
09/21 00:34:17 yS2 conf lib: read: 'dtmf_threshold 40'
09/21 00:34:17 yS2 conf lib: read: 'dtmf_wait 7'
09/21 00:34:17 yS2 conf lib: read: 'ignore_fax_dle false'
09/21 00:34:17 yS2 conf lib: read: 'raw_data false'
09/21 00:34:17 yS2 conf lib: read: 'rec_compression 0'
09/21 00:34:17 yS2 conf lib: read: 'rec_speed 0'
09/21 00:34:17 yS2 conf lib: read: 'rec_silence_len 70'
09/21 00:34:17 yS2 conf lib: read: 'rec_silence_threshold 40'
09/21 00:34:17 yS2 conf lib: read: 'rec_remove_silence false'
09/21 00:34:17 yS2 conf lib: read: 'rec_max_len 300'
09/21 00:34:17 yS2 conf lib: read: 'rec_min_len 0'
09/21 00:34:17 yS2 conf lib: read: 'do_hard_flow false'
09/21 00:34:17 yS2 conf lib: read: 'beep_frequency 933'
09/21 00:34:17 yS2 conf lib: read: 'beep_length 1500'
09/21 00:34:17 yS2 conf lib: read: 'max_tries 3'
09/21 00:34:17 yS2 conf lib: read: 'retry_delay 5'
09/21 00:34:17 yS2 conf lib: read: 'watchdog_timeout 60'
09/21 00:34:17 yS2 conf lib: read: 'receive_gain -1'
09/21 00:34:17 yS2 conf lib: read: 'transmit_gain -1'
09/21 00:34:17 yS2 conf lib: read: 'enable_command_echo false'
09/21 00:34:17 yS2 conf lib: read: 'poll_interval 10'
09/21 00:34:17 yS2 conf lib: read: 'program vgetty'
09/21 00:34:17 yS2 conf lib: read: 'rings /etc/rings'
09/21 00:34:17 yS2 conf lib: read: 'answer_mode voice:fax:data'
09/21 00:34:17 yS2 conf lib: read: 'force_autodetect false'
09/21 00:34:17 yS2 conf lib: read: 'toll_saver_rings 0'
09/21 00:34:17 yS2 conf lib: read: 'rec_always_keep true'
09/21 00:34:17 yS2 conf lib: read: 'button_program '
09/21 00:34:17 yS2 conf lib: read: 'call_program '
09/21 00:34:17 yS2 conf lib: read: 'dtmf_program dtmf.sh'
09/21 00:34:17 yS2 conf lib: read: 'message_program '
09/21 00:34:17 yS2 conf lib: read: 'do_message_light false'
09/21 00:34:17 yS2 conf lib: read: 'program vm'
09/21 00:34:17 yS2 conf lib: read: 'voice_devices ttyS2'
09/21 00:34:17 yS2 conf lib: read: 'dialout_timeout 90'
09/21 00:34:17 yS2 conf lib: read: 'ringback_goes_away 70'
09/21 00:34:17 yS2 conf lib: read: 'ringback_never_came 100'
09/21 00:34:17 yS2 conf lib: read: 'program pvf'
09/21 00:34:17 yS2 key: 'part', type=6, flags=4, data=(ignored)
09/21 00:34:17 yS2 key: 'program', type=6, flags=4, data=(ignored)
09/21 00:34:17 yS2 key: 'port', type=6, flags=4, data=(ignored)
09/21 00:34:17 yS2 key: 'ring_type', type=6, flags=4, data=(ignored)
09/21 00:34:17 yS2 key: 'voice_log_level', type=0, flags=3, data=6
09/21 00:34:17 yS2 key: 'voice_shell', type=1, flags=3, data=/bin/sh
09/21 00:34:17 yS2 key: 'port_speed', type=0, flags=3, data=38400
09/21 00:34:17 yS2 key: 'port_timeout', type=0, flags=3, data=10
09/21 00:34:17 yS2 key: 'dial_timeout', type=0, flags=3, data=90
09/21 00:34:17 yS2 key: 'command_delay', type=0, flags=3, data=100
09/21 00:34:17 yS2 key: 'dtmf_len', type=0, flags=3, data=30
09/21 00:34:17 yS2 key: 'dtmf_threshold', type=0, flags=3, data=40
09/21 00:34:17 yS2 key: 'dtmf_wait', type=0, flags=3, data=7
09/21 00:34:17 yS2 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
09/21 00:34:17 yS2 key: 'raw_data', type=3, flags=3, data=FALSE
09/21 00:34:17 yS2 key: 'rec_compression', type=0, flags=3, data=0
09/21 00:34:17 yS2 key: 'rec_speed', type=0, flags=3, data=0
09/21 00:34:17 yS2 key: 'rec_silence_len', type=0, flags=3, data=70
09/21 00:34:17 yS2 key: 'rec_silence_threshold', type=0, flags=3, data=40
09/21 00:34:17 yS2 key: 'rec_remove_silence', type=3, flags=3, data=FALSE
09/21 00:34:17 yS2 key: 'rec_max_len', type=0, flags=3, data=300
09/21 00:34:17 yS2 key: 'rec_min_len', type=0, flags=3, data=0
09/21 00:34:17 yS2 key: 'do_hard_flow', type=3, flags=3, data=FALSE
09/21 00:34:17 yS2 key: 'force_autodetect', type=3, flags=3, data=FALSE
09/21 00:34:17 yS2 key: 'watchdog_timeout', type=0, flags=3, data=60
09/21 00:34:17 yS2 key: 'receive_gain', type=0, flags=3, data=-1
09/21 00:34:17 yS2 key: 'transmit_gain', type=0, flags=3, data=-1
09/21 00:34:17 yS2 key: 'enable_command_echo', type=3, flags=3, data=FALSE
09/21 00:34:17 yS2 key: 'poll_interval', type=0, flags=3, data=10
09/21 00:34:17 yS2 key: 'rings', type=1, flags=3, data=/etc/rings
09/21 00:34:17 yS2 key: 'answer_mode', type=1, flags=3, data=voice:fax:data
09/21 00:34:17 yS2 key: 'toll_saver_rings', type=0, flags=3, data=0
09/21 00:34:17 yS2 key: 'rec_always_keep', type=3, flags=3, data=TRUE
09/21 00:34:17 yS2 key: 'voice_dir', type=1, flags=3, data=/var/spool/voice
09/21 00:34:17 yS2 key: 'phone_owner', type=1, flags=3, data=root
09/21 00:34:17 yS2 key: 'phone_group', type=1, flags=3, data=phone
09/21 00:34:17 yS2 key: 'phone_mode', type=0, flags=3, data=432
09/21 00:34:17 yS2 key: 'message_flag_file', type=1, flags=3, data=.flag
09/21 00:34:17 yS2 key: 'receive_dir', type=1, flags=3, data=incoming
09/21 00:34:17 yS2 key: 'message_dir', type=1, flags=3, data=messages
09/21 00:34:17 yS2 key: 'message_list', type=1, flags=3, data=Index
09/21 00:34:17 yS2 key: 'backup_message', type=1, flags=3, data=standard.rmd
09/21 00:34:17 yS2 key: 'button_program', type=1, flags=3, data=
09/21 00:34:17 yS2 key: 'call_program', type=1, flags=3, data=
09/21 00:34:17 yS2 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
09/21 00:34:17 yS2 key: 'message_program', type=1, flags=3, data=
09/21 00:34:17 yS2 key: 'do_message_light', type=3, flags=3, data=FALSE
09/21 00:34:17 yS2 key: 'beep_frequency', type=0, flags=3, data=933
09/21 00:34:17 yS2 key: 'beep_length', type=0, flags=3, data=1500
09/21 00:34:17 yS2 key: 'max_tries', type=0, flags=3, data=3
09/21 00:34:17 yS2 key: 'retry_delay', type=0, flags=3, data=5
09/21 00:34:17 yS2 key: 'dialout_timeout', type=0, flags=1, data=90
09/21 00:34:17 yS2 key: 'ringback_goes_away', type=0, flags=1, data=70
09/21 00:34:17 yS2 key: 'ringback_never_came', type=0, flags=1, data=100
09/21 00:34:17 yS2 key: 'voice_devices', type=1, flags=1, data=
09/21 00:34:17 yS2 vgetty: answer mode was set directly
09/21 00:34:17 yS2 vgetty: answer mode is [data|fax|voice]
09/21 00:34:17 yS2 vgetty: opening list file /var/spool/voice/messages/Index
09/21 00:34:17 yS2 vgetty: found 1 messages, picked message number 1
09/21 00:34:17 yS2 vgetty: message name is
/var/spool/voice/messages/standard.rmd
09/21 00:34:17 yS2 vgetty: entering voice mode
09/21 00:34:17 yS2 vgetty: Installing signal handlers
09/21 00:34:17 yS2 voice command: 'AT#CLS?' -> ''
09/21 00:34:17 yS2 vgetty: AT#CLS?
09/21 00:34:17 yS2 Rockwell: N ECO
09/21 00:34:17 yS2 voice command: '' -> 'OK'
09/21 00:34:17 yS2 Rockwell: 0
09/21 00:34:17 yS2 vgetty: Invalid modem answer
09/21 00:34:17 yS2 Rockwell: OK
09/21 00:34:18 yS2 voice command: 'ATA' -> 'VCON'
09/21 00:34:18 yS2 vgetty: ATA
09/21 00:34:18 yS2 Rockwell: FAX
09/21 00:34:21 yS2 Rockwell: +FCON
09/21 00:34:27 yS2 Rockwell: +FTSI: " CBCBSC"
09/21 00:34:29 yS2 vgetty: Invalid modem answer
09/21 00:34:29 yS2 vgetty: queued event RESET_WATCHDOG at position 0002
09/21 00:34:29 yS2 voice command: 'AT#VLS=4' -> 'OK'
09/21 00:34:29 yS2 vgetty: AT#VLS=4
09/21 00:34:29 yS2 vgetty: unqueued event RESET_WATCHDOG at position 0002
09/21 00:34:29 yS2 vgetty: voice_handle_event got event RESET_WATCHDOG with
data <_>
09/21 00:34:29 yS2 Rockwell: +FDCS: 1,5,0,2,0,0,0,0
09/21 00:34:29 yS2 vgetty: Invalid modem answer
09/21 00:34:29 yS2 Rockwell: OK
09/21 00:34:29 yS2 vgetty: Could not answer the phone. Strange...
- john