HylaFAX The world's most advanced open source fax server

[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]

Re: [hylafax-users] Hylafax and egetty



	Hello George,

This is complete log from one session of vgetty with Log level 6. I saw
here that before AT#VLS=4, vgetty sends an ATA to modem for answering.
There is no ATA command in vm.log, so maybe, that's the problem. 

06/08 09:25:53 SL0  waiting...
06/08 09:26:44 SL0    select returned 1
06/08 09:26:44 SL0   checking lockfiles, locking the line
06/08 09:26:44 SL0   makelock(ttySL0) called
06/08 09:26:44 SL0   do_makelock: lock='/var/lock/LCK..ttySL0'
06/08 09:26:44 SL0   lock made
06/08 09:26:44 SL0    vgetty: number of rings (1) was set directly
06/08 09:26:44 SL0    vgetty: decremented number of rings (1) by 0
06/08 09:26:44 SL0  vgetty: number of rings (1) too small, reseting to 2
06/08 09:26:44 SL0  wfr: waiting for ``RING''
06/08 09:26:44 SL0   got: [0a][0d][0a]RING[0d]
06/08 09:26:44 SL0    CND: RING
06/08 09:26:44 SL0   wfr: rc=0, drn=0
06/08 09:26:44 SL0  wfr: waiting for ``RING''
06/08 09:26:44 SL0   got: [0a][0d][0a]RING[0d]
06/08 09:26:49 SL0    CND: RING
06/08 09:26:49 SL0   wfr: rc=0, drn=0
06/08 09:26:49 SL0   setenv: 'CALLER_ID=none'
06/08 09:26:49 SL0   setenv: 'CONNECT='
06/08 09:26:49 SL0   setenv: 'DEVICE=ttySL0'
06/08 09:26:49 SL0  reading ring_type ring configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
06/08 09:26:49 SL0   reading /usr/local/etc/mgetty+sendfax/voice.conf...
06/08 09:26:49 SL0   conf lib: read: 'part generic'
06/08 09:26:49 SL0   found CT_KEYWORD part generic
06/08 09:26:49 SL0   conf lib: read: 'voice_log_level 6'
06/08 09:26:49 SL0   conf lib: read:
'voice_shell_log /var/log/vgetty_voice_shell.%s'
06/08 09:26:49 SL0   conf lib: read: 'voice_dir /var/spool/voice'
06/08 09:26:49 SL0   conf lib: read: 'phone_owner root'
06/08 09:26:49 SL0   conf lib: read: 'phone_group phone'
06/08 09:26:49 SL0   conf lib: read: 'phone_mode 0777'
06/08 09:26:49 SL0   conf lib: read: 'message_flag_file .flag'
06/08 09:26:49 SL0   conf lib: read: 'receive_dir incoming'
06/08 09:26:49 SL0   conf lib: read: 'message_dir messages'
06/08 09:26:49 SL0   conf lib: read: 'message_list Index'
06/08 09:26:49 SL0   conf lib: read: 'backup_message standard.rmd'
06/08 09:26:49 SL0   conf lib: read: 'port_speed 115200'
06/08 09:26:49 SL0   conf lib: read: 'voice_shell /bin/sh'
06/08 09:26:49 SL0   conf lib: read: 'port_timeout 5'
06/08 09:26:49 SL0   conf lib: read: 'dial_timeout 90'
06/08 09:26:49 SL0   conf lib: read: 'command_delay 50'
06/08 09:26:49 SL0   conf lib: read: 'dtmf_len 30'
06/08 09:26:49 SL0   conf lib: read: 'dtmf_threshold 40'
06/08 09:26:49 SL0   conf lib: read: 'dtmf_wait 0'
06/08 09:26:49 SL0   conf lib: read: 'ignore_fax_dle false'
06/08 09:26:49 SL0   conf lib: read: 'raw_data false'
06/08 09:26:49 SL0   conf lib: read: 'rec_compression 4'
06/08 09:26:49 SL0   conf lib: read: 'rec_speed 0'
06/08 09:26:49 SL0   conf lib: read: 'rec_silence_len 70'
06/08 09:26:49 SL0   conf lib: read: 'rec_silence_threshold 65'
06/08 09:26:49 SL0   conf lib: read: 'rec_remove_silence false'
06/08 09:26:49 SL0   conf lib: read: 'rec_max_len 300'
06/08 09:26:49 SL0   conf lib: read: 'rec_min_len 0'
06/08 09:26:49 SL0   conf lib: read: 'do_hard_flow true'
06/08 09:26:49 SL0   conf lib: read: 'beep_frequency 933'
06/08 09:26:49 SL0   conf lib: read: 'beep_length 1500'
06/08 09:26:49 SL0   conf lib: read: 'max_tries 3'
06/08 09:26:49 SL0   conf lib: read: 'retry_delay 5'
06/08 09:26:49 SL0   conf lib: read: 'watchdog_timeout 60'
06/08 09:26:49 SL0   conf lib: read: 'receive_gain -1'
06/08 09:26:49 SL0   conf lib: read: 'transmit_gain -1'
06/08 09:26:49 SL0   conf lib: read: 'enable_command_echo false'
06/08 09:26:49 SL0   conf lib: read: 'poll_interval 5'
06/08 09:26:49 SL0   conf lib: read: 'program vgetty'
06/08 09:26:49 SL0   conf lib: read: 'rings 1'
06/08 09:26:49 SL0   conf lib: read: 'answer_mode voice:fax'
06/08 09:26:49 SL0   conf lib: read: 'force_autodetect false'
06/08 09:26:49 SL0   conf lib: read: 'toll_saver_rings 0'
06/08 09:26:49 SL0   conf lib: read: 'rec_always_keep true'
06/08 09:26:49 SL0   conf lib: read: 'button_program '
06/08 09:26:49 SL0   conf lib: read: 'call_program '
06/08 09:26:49 SL0   conf lib: read: 'dtmf_program /home/rezac/dtmf.sh'
06/08 09:26:49 SL0   conf lib: read:
'message_program /home/rezac/sendskripta'
06/08 09:26:49 SL0   conf lib: read: 'do_message_light false'
06/08 09:26:49 SL0   conf lib: read: 'ring_report_delay -1'
06/08 09:26:49 SL0   conf lib: read: 'program vm'
06/08 09:26:49 SL0   conf lib: read: 'voice_devices ttySL0'
06/08 09:26:49 SL0   conf lib: read: 'dialout_timeout 90'
06/08 09:26:49 SL0   conf lib: read: 'ringback_goes_away 70'
06/08 09:26:49 SL0   conf lib: read: 'ringback_never_came 100'
06/08 09:26:49 SL0   conf lib: read: 'program pvf'
06/08 09:26:49 SL0   conf lib: read: 'port ttySL0'
06/08 09:26:49 SL0   conf lib: read: 'rings 1'
06/08 09:26:49 SL0   conf lib: read: 'answer_mode voice:fax'
06/08 09:26:49 SL0   conf lib: read: 'ring_type virtual'
06/08 09:26:49 SL0   section: ring_type virtual, ignore
06/08 09:26:49 SL0   conf lib: read: 'answer_mode voice:fax'
06/08 09:26:49 SL0   key: 'part', type=6, flags=4, data=(ignored)
06/08 09:26:49 SL0   key: 'program', type=6, flags=4, data=(ignored)
06/08 09:26:49 SL0   key: 'port', type=6, flags=4, data=(ignored)
06/08 09:26:49 SL0   key: 'ring_type', type=6, flags=4, data=(ignored)
06/08 09:26:49 SL0   key: 'voice_log_level', type=0, flags=3, data=6
06/08 09:26:49 SL0   key: 'voice_shell_log', type=1, flags=3,
data=/var/log/vgetty_voice_shell.%s
06/08 09:26:49 SL0   key: 'voice_shell', type=1, flags=3, data=/bin/sh
06/08 09:26:49 SL0   key: 'port_speed', type=0, flags=3, data=115200
06/08 09:26:49 SL0   key: 'port_timeout', type=0, flags=3, data=5
06/08 09:26:49 SL0   key: 'dial_timeout', type=0, flags=3, data=90
06/08 09:26:49 SL0   key: 'command_delay', type=0, flags=3, data=50
06/08 09:26:49 SL0   key: 'dtmf_len', type=0, flags=3, data=30
06/08 09:26:49 SL0   key: 'dtmf_threshold', type=0, flags=3, data=40
06/08 09:26:49 SL0   key: 'dtmf_wait', type=0, flags=3, data=0
06/08 09:26:49 SL0   key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
06/08 09:26:49 SL0   key: 'raw_data', type=3, flags=3, data=FALSE
06/08 09:26:49 SL0   key: 'rec_compression', type=0, flags=3, data=4
06/08 09:26:49 SL0   key: 'rec_speed', type=0, flags=3, data=0
06/08 09:26:49 SL0   key: 'rec_silence_len', type=0, flags=3, data=70
06/08 09:26:49 SL0   key: 'rec_silence_threshold', type=0, flags=3,
data=65
06/08 09:26:49 SL0   key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
06/08 09:26:49 SL0   key: 'rec_max_len', type=0, flags=3, data=300
06/08 09:26:49 SL0   key: 'rec_min_len', type=0, flags=3, data=0
06/08 09:26:49 SL0   key: 'do_hard_flow', type=3, flags=3, data=TRUE
06/08 09:26:49 SL0   key: 'force_autodetect', type=3, flags=3,
data=FALSE
06/08 09:26:49 SL0   key: 'watchdog_timeout', type=0, flags=3, data=60
06/08 09:26:49 SL0   key: 'receive_gain', type=0, flags=3, data=50
06/08 09:26:49 SL0   key: 'transmit_gain', type=0, flags=3, data=50
06/08 09:26:49 SL0   key: 'enable_command_echo', type=3, flags=3,
data=FALSE
06/08 09:26:49 SL0   key: 'poll_interval', type=0, flags=3, data=5
06/08 09:26:49 SL0   key: 'forceV253', type=3, flags=1, data=FALSE
06/08 09:26:49 SL0   key: 'forceV253subset', type=3, flags=1, data=FALSE
06/08 09:26:49 SL0   key: 'enable_compression_mapping_querry', type=3,
flags=1, data=TRUE
06/08 09:26:49 SL0   key: 'compression_8bit_linear_signed', type=0,
flags=1, data=0
06/08 09:26:49 SL0   key: 'compression_16bit_linear_signed', type=0,
flags=1, data=0
06/08 09:26:49 SL0   key: 'compression_8bit_linear_unsigned', type=0,
flags=1, data=1
06/08 09:26:49 SL0   key: 'compression_8bit_ulaw', type=0, flags=1,
data=4
06/08 09:26:49 SL0   key: 'compression_8bit_alaw', type=0, flags=1,
data=5
06/08 09:26:49 SL0   key: 'compression_2bit_adpcm', type=0, flags=1,
data=140
06/08 09:26:49 SL0   key: 'compression_4bit_adpcm', type=0, flags=1,
data=141
06/08 09:26:49 SL0   key: 'compression_4bit_ima_adpcm', type=0, flags=1,
data=129
06/08 09:26:49 SL0   key: 'rings', type=1, flags=3, data=1
06/08 09:26:49 SL0   key: 'answer_mode', type=1, flags=3, data=voice:fax
06/08 09:26:49 SL0   key: 'toll_saver_rings', type=0, flags=3, data=0
06/08 09:26:49 SL0   key: 'rec_always_keep', type=3, flags=3, data=TRUE
06/08 09:26:49 SL0   key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
06/08 09:26:49 SL0   key: 'phone_owner', type=1, flags=3, data=root
06/08 09:26:49 SL0   key: 'phone_group', type=1, flags=3, data=phone
06/08 09:26:49 SL0   key: 'phone_mode', type=0, flags=3, data=511
06/08 09:26:49 SL0   key: 'message_flag_file', type=1, flags=3,
data=.flag
06/08 09:26:49 SL0   key: 'receive_dir', type=1, flags=3, data=incoming
06/08 09:26:49 SL0   key: 'message_dir', type=1, flags=3, data=messages
06/08 09:26:49 SL0   key: 'message_list', type=1, flags=3, data=Index
06/08 09:26:49 SL0   key: 'backup_message', type=1, flags=3,
data=standard.rmd
06/08 09:26:49 SL0   key: 'button_program', type=1, flags=3, data=
06/08 09:26:49 SL0   key: 'call_program', type=1, flags=3, data=
06/08 09:26:49 SL0   key: 'dtmf_program', type=1, flags=3,
data=/home/rezac/dtmf.sh
06/08 09:26:49 SL0   key: 'message_program', type=1, flags=3,
data=/home/rezac/sendskripta
06/08 09:26:49 SL0   key: 'do_message_light', type=3, flags=3,
data=FALSE
06/08 09:26:49 SL0   key: 'pre_message', type=1, flags=1, data=
06/08 09:26:49 SL0   key: 'beepsound', type=1, flags=1, data=
06/08 09:26:49 SL0   key: 'beep_frequency', type=0, flags=3, data=933
06/08 09:26:49 SL0   key: 'beep_length', type=0, flags=3, data=1500
06/08 09:26:49 SL0   key: 'max_tries', type=0, flags=3, data=3
06/08 09:26:49 SL0   key: 'retry_delay', type=0, flags=3, data=5
06/08 09:26:49 SL0   key: 'dialout_timeout', type=0, flags=1, data=90
06/08 09:26:49 SL0   key: 'ringback_goes_away', type=0, flags=1, data=70
06/08 09:26:49 SL0   key: 'ringback_never_came', type=0, flags=1,
data=100
06/08 09:26:49 SL0   key: 'ring_report_delay', type=0, flags=3, data=-1
06/08 09:26:49 SL0   key: 'voice_devices', type=1, flags=1, data=
06/08 09:26:49 SL0    vgetty: answer mode was set directly
06/08 09:26:49 SL0    vgetty: answer mode is [fax|voice]
06/08 09:26:49 SL0    vgetty: opening list
file /var/spool/voice/messages/Index
06/08 09:26:49 SL0    vgetty: message name
is /var/spool/voice/messages/standard.rmd
06/08 09:26:49 SL0   vgetty: entering voice mode
06/08 09:26:49 SL0   vgetty: Installing signal handlers
06/08 09:26:49 SL0   voice command: 'AT#CLS?' -> ''
06/08 09:26:49 SL0    vgetty: AT#CLS?
06/08 09:26:49 SL0    Rockwell: 0
06/08 09:26:49 SL0   voice command: '' -> 'OK'
06/08 09:26:49 SL0    Rockwell: OK
06/08 09:26:49 SL0   voice command: 'AT#CLS=8' -> 'OK'
06/08 09:26:50 SL0    vgetty: AT#CLS=8
06/08 09:26:50 SL0    Rockwell: OK
06/08 09:26:50 SL0   voice command: 'AT' -> 'OK'
06/08 09:26:50 SL0    vgetty: AT
06/08 09:26:50 SL0    Rockwell: OK
06/08 09:26:50 SL0   voice command: 'ATA' -> 'VCON'
06/08 09:26:50 SL0    vgetty: ATA
06/08 09:26:50 SL0    Rockwell: VCON
06/08 09:26:50 SL0    vgetty: queued event RESET_WATCHDOG at position
0001
06/08 09:26:50 SL0   voice command: 'AT#VLS=4' -> 'OK'
06/08 09:26:50 SL0    vgetty: AT#VLS=4
06/08 09:26:50 SL0    vgetty: unqueued event RESET_WATCHDOG at position
0001
06/08 09:26:50 SL0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <4>
06/08 09:26:50 SL0    Rockwell: OK
06/08 09:26:50 SL0  playing voice
file /var/spool/voice/messages/standard.rmd
06/08 09:26:50 SL0   vgetty: raw modem data header found
06/08 09:26:50 SL0   vgetty: modem type Rockwell found
06/08 09:26:50 SL0   vgetty: compression method 0x0004, speed 7200, bits
4
06/08 09:26:50 SL0    vgetty: queued event RESET_WATCHDOG at position
0002
06/08 09:26:50 SL0   voice command: 'AT#VBS=4' -> 'OK'
06/08 09:26:50 SL0    vgetty: AT#VBS=4
06/08 09:26:50 SL0    vgetty: unqueued event RESET_WATCHDOG at position
0002
06/08 09:26:50 SL0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <4>
06/08 09:26:50 SL0    Rockwell: OK
06/08 09:26:50 SL0    vgetty: queued event RESET_WATCHDOG at position
0003
06/08 09:26:50 SL0    vgetty: unqueued event RESET_WATCHDOG at position
0003
06/08 09:26:50 SL0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <4>
06/08 09:26:50 SL0   voice command: 'AT&K3' -> 'OK'
06/08 09:26:50 SL0    vgetty: AT&K3
06/08 09:26:50 SL0    Rockwell: OK
06/08 09:26:50 SL0   tio_set_flow_control( HARD XON_OUT )
06/08 09:26:50 SL0   voice command: 'AT#VTX' -> 'CONNECT'
06/08 09:26:50 SL0    vgetty: AT#VTX
06/08 09:26:50 SL0    Rockwell: CONNECT
06/08 09:26:51 SL0    vgetty: <VOICE DATA 4536 bytes>
06/08 09:26:51 SL0    vgetty: queued event RESET_WATCHDOG at position
0004
06/08 09:26:51 SL0    vgetty: unqueued event RESET_WATCHDOG at position
0004
06/08 09:26:51 SL0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <4>
06/08 09:26:51 SL0    vgetty: <STOP PLAY>
06/08 09:26:51 SL0   voice command: '' -> 'OK|VCON'
06/08 09:26:51 SL0    Rockwell: VCON
06/08 09:26:52 SL0    vgetty: queued event RESET_WATCHDOG at position
0005
06/08 09:26:52 SL0   voice command: 'AT#VTS=[933,0,15]' -> ''
06/08 09:26:52 SL0    vgetty: AT#VTS=[933,0,15]
06/08 09:26:52 SL0    vgetty: unqueued event RESET_WATCHDOG at position
0005
06/08 09:26:52 SL0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <4>
06/08 09:26:53 SL0   voice command: '' -> 'OK'
06/08 09:26:53 SL0    Rockwell: OK
06/08 09:26:54 SL0  recording voice
file /var/spool/voice/incoming/v-2726-1118215610.rmd
06/08 09:26:54 SL0    vgetty: queued event RESET_WATCHDOG at position
0006
06/08 09:26:54 SL0   voice command: 'AT#VBS=4' -> 'OK'
06/08 09:26:54 SL0    vgetty: AT#VBS=4
06/08 09:26:54 SL0    vgetty: unqueued event RESET_WATCHDOG at position
0006
06/08 09:26:54 SL0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <4>
06/08 09:26:54 SL0    Rockwell: OK
06/08 09:26:54 SL0    vgetty: queued event RESET_WATCHDOG at position
0007
06/08 09:26:54 SL0    vgetty: unqueued event RESET_WATCHDOG at position
0007
06/08 09:26:54 SL0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <4>
06/08 09:26:54 SL0   voice command: 'AT&K3' -> 'OK'
06/08 09:26:54 SL0    vgetty: AT&K3
06/08 09:26:54 SL0    Rockwell: OK
06/08 09:26:54 SL0   tio_set_flow_control( HARD XON_IN )
06/08 09:26:54 SL0   voice command: 'AT#VRX' -> 'CONNECT'
06/08 09:26:54 SL0    vgetty: AT#VRX
06/08 09:26:54 SL0    Rockwell: CONNECT
06/08 09:27:12 SL0    Rockwell: <DLE> <d>
06/08 09:27:12 SL0    vgetty: queued event DIAL_TONE at position 0008
06/08 09:27:12 SL0    vgetty: unqueued event DIAL_TONE at position 0008
06/08 09:27:12 SL0    vgetty: voice_handle_event got event DIAL_TONE
with data <4>
06/08 09:27:12 SL0    vgetty: <STOP RECORDING>
06/08 09:27:12 SL0    Rockwell: <VOICE DATA 63651 bytes>
06/08 09:27:12 SL0    Rockwell: <DLE> <ETX>
06/08 09:27:12 SL0  vgetty: <DLE> <ETX> received
06/08 09:27:12 SL0   voice command: '' -> 'OK|VCON'
06/08 09:27:12 SL0    Rockwell: VCON
06/08 09:27:12 SL0    vgetty: queued event RESET_WATCHDOG at position
0009
06/08 09:27:12 SL0   voice command: 'ATH0' -> 'VCON|OK'
06/08 09:27:12 SL0    vgetty: ATH0
06/08 09:27:12 SL0    vgetty: unqueued event RESET_WATCHDOG at position
0009
06/08 09:27:12 SL0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <4>
06/08 09:27:12 SL0    Rockwell: OK
06/08 09:27:12 SL0   voice command: 'AT#CLS=8' -> 'OK'
06/08 09:27:12 SL0    vgetty: AT#CLS=8
06/08 09:27:12 SL0    Rockwell: OK
06/08 09:27:12 SL0   voice command: 'AT#VLS=0' -> 'OK'
06/08 09:27:12 SL0    vgetty: AT#VLS=0
06/08 09:27:12 SL0    Rockwell: OK
06/08 09:27:12 SL0   vgetty: leaving voice mode
06/08 09:27:12 SL0   voice command: 'AT#CLS=0' -> 'OK'
06/08 09:27:12 SL0    vgetty: AT#CLS=0
06/08 09:27:12 SL0    Rockwell: OK
06/08 09:27:12 SL0   voice command: 'AT' -> 'OK'
06/08 09:27:12 SL0    vgetty: AT
06/08 09:27:12 SL0    Rockwell: OK
06/08 09:27:12 SL0   vgetty: Restoring signal handlers
06/08 09:27:12 SL0  closing voice modem device
06/08 09:27:12 SL0   removing lock file
06/08 09:27:12 ##### message keep, length=00:00:18, name='',
caller=none, dev=ttySL0, pid=2726
06/08 09:27:12 SL0    vgetty: creating message flag
file /var/spool/voice/.flag
06/08 09:27:12 SL0   executing message program, dev=ttySL0, pid=2726
06/08 09:27:12 SL0  vgetty: Executing shell
script /home/rezac/sendskripta with shell /bin/sh
06/08 09:27:12 SL0    vgetty: opening pipes
06/08 09:27:12 SL0    vgetty: forking shell
06/08 09:27:12 SL0   vgetty(0): HELLO SHELL
06/08 09:27:15 SL0   vgetty: shell exited normally with status 0x0000
--
06/08 09:27:15 SL0  vgetty: experimental test release 0.9.32 / with
duplex patch
06/08 09:27:15 SL0  mgetty: interim release 1.1.33-Apr10
06/08 09:27:15 SL0  reading generic configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
06/08 09:27:15 SL0  reading program vgetty configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
06/08 09:27:15 SL0   reading /usr/local/etc/mgetty+sendfax/voice.conf...
06/08 09:27:15 SL0   conf lib: read: 'part generic'
06/08 09:27:15 SL0   found CT_KEYWORD part generic
06/08 09:27:15 SL0   conf lib: read: 'voice_log_level 6'
06/08 09:27:15 SL0   conf lib: read:
'voice_shell_log /var/log/vgetty_voice_shell.%s'
06/08 09:27:15 SL0   conf lib: read: 'voice_dir /var/spool/voice'
06/08 09:27:15 SL0   conf lib: read: 'phone_owner root'
06/08 09:27:15 SL0   conf lib: read: 'phone_group phone'
06/08 09:27:15 SL0   conf lib: read: 'phone_mode 0777'
06/08 09:27:15 SL0   conf lib: read: 'message_flag_file .flag'
06/08 09:27:15 SL0   conf lib: read: 'receive_dir incoming'
06/08 09:27:15 SL0   conf lib: read: 'message_dir messages'
06/08 09:27:15 SL0   conf lib: read: 'message_list Index'
06/08 09:27:15 SL0   conf lib: read: 'backup_message standard.rmd'
06/08 09:27:15 SL0   conf lib: read: 'port_speed 115200'
06/08 09:27:15 SL0   conf lib: read: 'voice_shell /bin/sh'
06/08 09:27:15 SL0   conf lib: read: 'port_timeout 5'
06/08 09:27:15 SL0   conf lib: read: 'dial_timeout 90'
06/08 09:27:15 SL0   conf lib: read: 'command_delay 50'
06/08 09:27:15 SL0   conf lib: read: 'dtmf_len 30'
06/08 09:27:15 SL0   conf lib: read: 'dtmf_threshold 40'
06/08 09:27:15 SL0   conf lib: read: 'dtmf_wait 0'
06/08 09:27:15 SL0   conf lib: read: 'ignore_fax_dle false'
06/08 09:27:15 SL0   conf lib: read: 'raw_data false'
06/08 09:27:15 SL0   conf lib: read: 'rec_compression 4'
06/08 09:27:15 SL0   conf lib: read: 'rec_speed 0'
06/08 09:27:15 SL0   conf lib: read: 'rec_silence_len 70'
06/08 09:27:15 SL0   conf lib: read: 'rec_silence_threshold 65'
06/08 09:27:15 SL0   conf lib: read: 'rec_remove_silence false'
06/08 09:27:15 SL0   conf lib: read: 'rec_max_len 300'
06/08 09:27:15 SL0   conf lib: read: 'rec_min_len 0'
06/08 09:27:15 SL0   conf lib: read: 'do_hard_flow true'
06/08 09:27:15 SL0   conf lib: read: 'beep_frequency 933'
06/08 09:27:15 SL0   conf lib: read: 'beep_length 1500'
06/08 09:27:15 SL0   conf lib: read: 'max_tries 3'
06/08 09:27:15 SL0   conf lib: read: 'retry_delay 5'
06/08 09:27:15 SL0   conf lib: read: 'watchdog_timeout 60'
06/08 09:27:15 SL0   conf lib: read: 'receive_gain -1'
06/08 09:27:15 SL0   conf lib: read: 'transmit_gain -1'
06/08 09:27:15 SL0   conf lib: read: 'enable_command_echo false'
06/08 09:27:15 SL0   conf lib: read: 'poll_interval 5'
06/08 09:27:15 SL0   conf lib: read: 'program vgetty'
06/08 09:27:15 SL0   section: program vgetty, **found**
06/08 09:27:15 SL0   conf lib: read: 'rings 1'
06/08 09:27:15 SL0   conf lib: read: 'answer_mode voice:fax'
06/08 09:27:15 SL0   conf lib: read: 'force_autodetect false'
06/08 09:27:15 SL0   conf lib: read: 'toll_saver_rings 0'
06/08 09:27:15 SL0   conf lib: read: 'rec_always_keep true'
06/08 09:27:15 SL0   conf lib: read: 'button_program '
06/08 09:27:15 SL0   conf lib: read: 'call_program '
06/08 09:27:15 SL0   conf lib: read: 'dtmf_program /home/rezac/dtmf.sh'
06/08 09:27:15 SL0   conf lib: read:
'message_program /home/rezac/sendskripta'
06/08 09:27:15 SL0   conf lib: read: 'do_message_light false'
06/08 09:27:15 SL0   conf lib: read: 'ring_report_delay -1'
06/08 09:27:15 SL0   conf lib: read: 'program vm'
06/08 09:27:15 SL0   section: program vm, ignore
06/08 09:27:15 SL0   conf lib: read: 'voice_devices ttySL0'
06/08 09:27:15 SL0   conf lib: read: 'dialout_timeout 90'
06/08 09:27:15 SL0   conf lib: read: 'ringback_goes_away 70'
06/08 09:27:15 SL0   conf lib: read: 'ringback_never_came 100'
06/08 09:27:15 SL0   conf lib: read: 'program pvf'
06/08 09:27:15 SL0   section: program pvf, ignore
06/08 09:27:15 SL0   conf lib: read: 'port ttySL0'
06/08 09:27:15 SL0   conf lib: read: 'rings 1'
06/08 09:27:15 SL0   conf lib: read: 'answer_mode voice:fax'
06/08 09:27:15 SL0   conf lib: read: 'ring_type virtual'
06/08 09:27:15 SL0   conf lib: read: 'answer_mode voice:fax'
06/08 09:27:15 SL0   key: 'part', type=6, flags=4, data=(ignored)
06/08 09:27:15 SL0   key: 'program', type=6, flags=4, data=(ignored)
06/08 09:27:15 SL0   key: 'port', type=6, flags=4, data=(ignored)
06/08 09:27:15 SL0   key: 'ring_type', type=6, flags=4, data=(ignored)
06/08 09:27:15 SL0   key: 'voice_log_level', type=0, flags=3, data=6
06/08 09:27:15 SL0   key: 'voice_shell_log', type=1, flags=3,
data=/var/log/vgetty_voice_shell.%s
06/08 09:27:15 SL0   key: 'voice_shell', type=1, flags=3, data=/bin/sh
06/08 09:27:15 SL0   key: 'port_speed', type=0, flags=3, data=115200
06/08 09:27:15 SL0   key: 'port_timeout', type=0, flags=3, data=5
06/08 09:27:15 SL0   key: 'dial_timeout', type=0, flags=3, data=90
06/08 09:27:15 SL0   key: 'command_delay', type=0, flags=3, data=50
06/08 09:27:15 SL0   key: 'dtmf_len', type=0, flags=3, data=30
06/08 09:27:15 SL0   key: 'dtmf_threshold', type=0, flags=3, data=40
06/08 09:27:15 SL0   key: 'dtmf_wait', type=0, flags=3, data=0
06/08 09:27:15 SL0   key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
06/08 09:27:15 SL0   key: 'raw_data', type=3, flags=3, data=FALSE
06/08 09:27:15 SL0   key: 'rec_compression', type=0, flags=3, data=4
06/08 09:27:15 SL0   key: 'rec_speed', type=0, flags=3, data=0
06/08 09:27:15 SL0   key: 'rec_silence_len', type=0, flags=3, data=70
06/08 09:27:15 SL0   key: 'rec_silence_threshold', type=0, flags=3,
data=65
06/08 09:27:15 SL0   key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
06/08 09:27:15 SL0   key: 'rec_max_len', type=0, flags=3, data=300
06/08 09:27:15 SL0   key: 'rec_min_len', type=0, flags=3, data=0
06/08 09:27:15 SL0   key: 'do_hard_flow', type=3, flags=3, data=TRUE
06/08 09:27:15 SL0   key: 'force_autodetect', type=3, flags=3,
data=FALSE
06/08 09:27:15 SL0   key: 'watchdog_timeout', type=0, flags=3, data=60
06/08 09:27:15 SL0   key: 'receive_gain', type=0, flags=3, data=-1
06/08 09:27:15 SL0   key: 'transmit_gain', type=0, flags=3, data=-1
06/08 09:27:15 SL0   key: 'enable_command_echo', type=3, flags=3,
data=FALSE
06/08 09:27:15 SL0   key: 'poll_interval', type=0, flags=3, data=5
06/08 09:27:15 SL0   key: 'forceV253', type=3, flags=1, data=FALSE
06/08 09:27:15 SL0   key: 'forceV253subset', type=3, flags=1, data=FALSE
06/08 09:27:15 SL0   key: 'enable_compression_mapping_querry', type=3,
flags=1, data=TRUE
06/08 09:27:15 SL0   key: 'compression_8bit_linear_signed', type=0,
flags=1, data=0
06/08 09:27:15 SL0   key: 'compression_16bit_linear_signed', type=0,
flags=1, data=0
06/08 09:27:15 SL0   key: 'compression_8bit_linear_unsigned', type=0,
flags=1, data=1
06/08 09:27:15 SL0   key: 'compression_8bit_ulaw', type=0, flags=1,
data=4
06/08 09:27:15 SL0   key: 'compression_8bit_alaw', type=0, flags=1,
data=5
06/08 09:27:15 SL0   key: 'compression_2bit_adpcm', type=0, flags=1,
data=140
06/08 09:27:15 SL0   key: 'compression_4bit_adpcm', type=0, flags=1,
data=141
06/08 09:27:15 SL0   key: 'compression_4bit_ima_adpcm', type=0, flags=1,
data=129
06/08 09:27:15 SL0   key: 'rings', type=1, flags=3, data=1
06/08 09:27:15 SL0   key: 'answer_mode', type=1, flags=3, data=voice:fax
06/08 09:27:15 SL0   key: 'toll_saver_rings', type=0, flags=3, data=0
06/08 09:27:15 SL0   key: 'rec_always_keep', type=3, flags=3, data=TRUE
06/08 09:27:15 SL0   key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
06/08 09:27:15 SL0   key: 'phone_owner', type=1, flags=3, data=root
06/08 09:27:15 SL0   key: 'phone_group', type=1, flags=3, data=phone
06/08 09:27:15 SL0   key: 'phone_mode', type=0, flags=3, data=511
06/08 09:27:15 SL0   key: 'message_flag_file', type=1, flags=3,
data=.flag
06/08 09:27:15 SL0   key: 'receive_dir', type=1, flags=3, data=incoming
06/08 09:27:15 SL0   key: 'message_dir', type=1, flags=3, data=messages
06/08 09:27:15 SL0   key: 'message_list', type=1, flags=3, data=Index
06/08 09:27:15 SL0   key: 'backup_message', type=1, flags=3,
data=standard.rmd
06/08 09:27:15 SL0   key: 'button_program', type=1, flags=3, data=
06/08 09:27:15 SL0   key: 'call_program', type=1, flags=3, data=
06/08 09:27:15 SL0   key: 'dtmf_program', type=1, flags=3,
data=/home/rezac/dtmf.sh
06/08 09:27:15 SL0   key: 'message_program', type=1, flags=3,
data=/home/rezac/sendskripta
06/08 09:27:15 SL0   key: 'do_message_light', type=3, flags=3,
data=FALSE
06/08 09:27:15 SL0   key: 'pre_message', type=1, flags=1, data=
06/08 09:27:15 SL0   key: 'beepsound', type=1, flags=1, data=
06/08 09:27:15 SL0   key: 'beep_frequency', type=0, flags=3, data=933
06/08 09:27:15 SL0   key: 'beep_length', type=0, flags=3, data=1500
06/08 09:27:15 SL0   key: 'max_tries', type=0, flags=3, data=3
06/08 09:27:15 SL0   key: 'retry_delay', type=0, flags=3, data=5
06/08 09:27:15 SL0   key: 'dialout_timeout', type=0, flags=1, data=90
06/08 09:27:15 SL0   key: 'ringback_goes_away', type=0, flags=1, data=70
06/08 09:27:15 SL0   key: 'ringback_never_came', type=0, flags=1,
data=100
06/08 09:27:15 SL0   key: 'ring_report_delay', type=0, flags=3, data=-1
06/08 09:27:15 SL0   key: 'voice_devices', type=1, flags=1, data=
06/08 09:27:15 SL0  reading port ttySL0 configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
06/08 09:27:15 SL0   reading /usr/local/etc/mgetty+sendfax/voice.conf...
06/08 09:27:15 SL0   conf lib: read: 'part generic'
06/08 09:27:15 SL0   found CT_KEYWORD part generic
06/08 09:27:15 SL0   conf lib: read: 'voice_log_level 6'
06/08 09:27:15 SL0   conf lib: read:
'voice_shell_log /var/log/vgetty_voice_shell.%s'
06/08 09:27:15 SL0   conf lib: read: 'voice_dir /var/spool/voice'
06/08 09:27:15 SL0   conf lib: read: 'phone_owner root'
06/08 09:27:15 SL0   conf lib: read: 'phone_group phone'
06/08 09:27:15 SL0   conf lib: read: 'phone_mode 0777'
06/08 09:27:15 SL0   conf lib: read: 'message_flag_file .flag'
06/08 09:27:15 SL0   conf lib: read: 'receive_dir incoming'
06/08 09:27:15 SL0   conf lib: read: 'message_dir messages'
06/08 09:27:15 SL0   conf lib: read: 'message_list Index'
06/08 09:27:15 SL0   conf lib: read: 'backup_message standard.rmd'
06/08 09:27:15 SL0   conf lib: read: 'port_speed 115200'
06/08 09:27:15 SL0   conf lib: read: 'voice_shell /bin/sh'
06/08 09:27:15 SL0   conf lib: read: 'port_timeout 5'
06/08 09:27:15 SL0   conf lib: read: 'dial_timeout 90'
06/08 09:27:15 SL0   conf lib: read: 'command_delay 50'
06/08 09:27:15 SL0   conf lib: read: 'dtmf_len 30'
06/08 09:27:15 SL0   conf lib: read: 'dtmf_threshold 40'
06/08 09:27:15 SL0   conf lib: read: 'dtmf_wait 0'
06/08 09:27:15 SL0   conf lib: read: 'ignore_fax_dle false'
06/08 09:27:15 SL0   conf lib: read: 'raw_data false'
06/08 09:27:15 SL0   conf lib: read: 'rec_compression 4'
06/08 09:27:15 SL0   conf lib: read: 'rec_speed 0'
06/08 09:27:15 SL0   conf lib: read: 'rec_silence_len 70'
06/08 09:27:15 SL0   conf lib: read: 'rec_silence_threshold 65'
06/08 09:27:15 SL0   conf lib: read: 'rec_remove_silence false'
06/08 09:27:15 SL0   conf lib: read: 'rec_max_len 300'
06/08 09:27:15 SL0   conf lib: read: 'rec_min_len 0'
06/08 09:27:15 SL0   conf lib: read: 'do_hard_flow true'
06/08 09:27:15 SL0   conf lib: read: 'beep_frequency 933'
06/08 09:27:15 SL0   conf lib: read: 'beep_length 1500'
06/08 09:27:15 SL0   conf lib: read: 'max_tries 3'
06/08 09:27:15 SL0   conf lib: read: 'retry_delay 5'
06/08 09:27:15 SL0   conf lib: read: 'watchdog_timeout 60'
06/08 09:27:15 SL0   conf lib: read: 'receive_gain -1'
06/08 09:27:15 SL0   conf lib: read: 'transmit_gain -1'
06/08 09:27:15 SL0   conf lib: read: 'enable_command_echo false'
06/08 09:27:15 SL0   conf lib: read: 'poll_interval 5'
06/08 09:27:15 SL0   conf lib: read: 'program vgetty'
06/08 09:27:15 SL0   conf lib: read: 'rings 1'
06/08 09:27:15 SL0   conf lib: read: 'answer_mode voice:fax'
06/08 09:27:15 SL0   conf lib: read: 'force_autodetect false'
06/08 09:27:15 SL0   conf lib: read: 'toll_saver_rings 0'
06/08 09:27:15 SL0   conf lib: read: 'rec_always_keep true'
06/08 09:27:15 SL0   conf lib: read: 'button_program '
06/08 09:27:15 SL0   conf lib: read: 'call_program '
06/08 09:27:15 SL0   conf lib: read: 'dtmf_program /home/rezac/dtmf.sh'
06/08 09:27:15 SL0   conf lib: read:
'message_program /home/rezac/sendskripta'
06/08 09:27:15 SL0   conf lib: read: 'do_message_light false'
06/08 09:27:15 SL0   conf lib: read: 'ring_report_delay -1'
06/08 09:27:15 SL0   conf lib: read: 'program vm'
06/08 09:27:15 SL0   conf lib: read: 'voice_devices ttySL0'
06/08 09:27:15 SL0   conf lib: read: 'dialout_timeout 90'
06/08 09:27:15 SL0   conf lib: read: 'ringback_goes_away 70'
06/08 09:27:15 SL0   conf lib: read: 'ringback_never_came 100'
06/08 09:27:15 SL0   conf lib: read: 'program pvf'
06/08 09:27:15 SL0   conf lib: read: 'port ttySL0'
06/08 09:27:15 SL0   section: port ttySL0, **found**
06/08 09:27:15 SL0   conf lib: read: 'rings 1'
06/08 09:27:15 SL0   conf lib: read: 'answer_mode voice:fax'
06/08 09:27:15 SL0   conf lib: read: 'ring_type virtual'
06/08 09:27:15 SL0   found CT_KEYWORD ring_type virtual
06/08 09:27:15 SL0   conf lib: read: 'answer_mode voice:fax'
06/08 09:27:15 SL0   key: 'part', type=6, flags=4, data=(ignored)
06/08 09:27:15 SL0   key: 'program', type=6, flags=4, data=(ignored)
06/08 09:27:15 SL0   key: 'port', type=6, flags=4, data=(ignored)
06/08 09:27:15 SL0   key: 'ring_type', type=6, flags=4, data=(ignored)
06/08 09:27:15 SL0   key: 'voice_log_level', type=0, flags=3, data=6
06/08 09:27:15 SL0   key: 'voice_shell_log', type=1, flags=3,
data=/var/log/vgetty_voice_shell.%s
06/08 09:27:15 SL0   key: 'voice_shell', type=1, flags=3, data=/bin/sh
06/08 09:27:15 SL0   key: 'port_speed', type=0, flags=3, data=115200
06/08 09:27:15 SL0   key: 'port_timeout', type=0, flags=3, data=5
06/08 09:27:15 SL0   key: 'dial_timeout', type=0, flags=3, data=90
06/08 09:27:15 SL0   key: 'command_delay', type=0, flags=3, data=50
06/08 09:27:15 SL0   key: 'dtmf_len', type=0, flags=3, data=30
06/08 09:27:15 SL0   key: 'dtmf_threshold', type=0, flags=3, data=40
06/08 09:27:15 SL0   key: 'dtmf_wait', type=0, flags=3, data=0
06/08 09:27:15 SL0   key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
06/08 09:27:15 SL0   key: 'raw_data', type=3, flags=3, data=FALSE
06/08 09:27:15 SL0   key: 'rec_compression', type=0, flags=3, data=4
06/08 09:27:15 SL0   key: 'rec_speed', type=0, flags=3, data=0
06/08 09:27:15 SL0   key: 'rec_silence_len', type=0, flags=3, data=70
06/08 09:27:15 SL0   key: 'rec_silence_threshold', type=0, flags=3,
data=65
06/08 09:27:15 SL0   key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
06/08 09:27:15 SL0   key: 'rec_max_len', type=0, flags=3, data=300
06/08 09:27:15 SL0   key: 'rec_min_len', type=0, flags=3, data=0
06/08 09:27:15 SL0   key: 'do_hard_flow', type=3, flags=3, data=TRUE
06/08 09:27:15 SL0   key: 'force_autodetect', type=3, flags=3,
data=FALSE
06/08 09:27:15 SL0   key: 'watchdog_timeout', type=0, flags=3, data=60
06/08 09:27:15 SL0   key: 'receive_gain', type=0, flags=3, data=-1
06/08 09:27:15 SL0   key: 'transmit_gain', type=0, flags=3, data=-1
06/08 09:27:15 SL0   key: 'enable_command_echo', type=3, flags=3,
data=FALSE
06/08 09:27:15 SL0   key: 'poll_interval', type=0, flags=3, data=5
06/08 09:27:15 SL0   key: 'forceV253', type=3, flags=1, data=FALSE
06/08 09:27:15 SL0   key: 'forceV253subset', type=3, flags=1, data=FALSE
06/08 09:27:15 SL0   key: 'enable_compression_mapping_querry', type=3,
flags=1, data=TRUE
06/08 09:27:15 SL0   key: 'compression_8bit_linear_signed', type=0,
flags=1, data=0
06/08 09:27:15 SL0   key: 'compression_16bit_linear_signed', type=0,
flags=1, data=0
06/08 09:27:15 SL0   key: 'compression_8bit_linear_unsigned', type=0,
flags=1, data=1
06/08 09:27:15 SL0   key: 'compression_8bit_ulaw', type=0, flags=1,
data=4
06/08 09:27:15 SL0   key: 'compression_8bit_alaw', type=0, flags=1,
data=5
06/08 09:27:15 SL0   key: 'compression_2bit_adpcm', type=0, flags=1,
data=140
06/08 09:27:15 SL0   key: 'compression_4bit_adpcm', type=0, flags=1,
data=141
06/08 09:27:15 SL0   key: 'compression_4bit_ima_adpcm', type=0, flags=1,
data=129
06/08 09:27:15 SL0   key: 'rings', type=1, flags=3, data=1
06/08 09:27:15 SL0   key: 'answer_mode', type=1, flags=3, data=voice:fax
06/08 09:27:15 SL0   key: 'toll_saver_rings', type=0, flags=3, data=0
06/08 09:27:15 SL0   key: 'rec_always_keep', type=3, flags=3, data=TRUE
06/08 09:27:15 SL0   key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
06/08 09:27:15 SL0   key: 'phone_owner', type=1, flags=3, data=root
06/08 09:27:15 SL0   key: 'phone_group', type=1, flags=3, data=phone
06/08 09:27:15 SL0   key: 'phone_mode', type=0, flags=3, data=511
06/08 09:27:15 SL0   key: 'message_flag_file', type=1, flags=3,
data=.flag
06/08 09:27:15 SL0   key: 'receive_dir', type=1, flags=3, data=incoming
06/08 09:27:15 SL0   key: 'message_dir', type=1, flags=3, data=messages
06/08 09:27:15 SL0   key: 'message_list', type=1, flags=3, data=Index
06/08 09:27:15 SL0   key: 'backup_message', type=1, flags=3,
data=standard.rmd
06/08 09:27:15 SL0   key: 'button_program', type=1, flags=3, data=
06/08 09:27:15 SL0   key: 'call_program', type=1, flags=3, data=
06/08 09:27:15 SL0   key: 'dtmf_program', type=1, flags=3,
data=/home/rezac/dtmf.sh
06/08 09:27:15 SL0   key: 'message_program', type=1, flags=3,
data=/home/rezac/sendskripta
06/08 09:27:15 SL0   key: 'do_message_light', type=3, flags=3,
data=FALSE
06/08 09:27:15 SL0   key: 'pre_message', type=1, flags=1, data=
06/08 09:27:15 SL0   key: 'beepsound', type=1, flags=1, data=
06/08 09:27:15 SL0   key: 'beep_frequency', type=0, flags=3, data=933
06/08 09:27:15 SL0   key: 'beep_length', type=0, flags=3, data=1500
06/08 09:27:15 SL0   key: 'max_tries', type=0, flags=3, data=3
06/08 09:27:15 SL0   key: 'retry_delay', type=0, flags=3, data=5
06/08 09:27:15 SL0   key: 'dialout_timeout', type=0, flags=1, data=90
06/08 09:27:15 SL0   key: 'ringback_goes_away', type=0, flags=1, data=70
06/08 09:27:15 SL0   key: 'ringback_never_came', type=0, flags=1,
data=100
06/08 09:27:15 SL0   key: 'ring_report_delay', type=0, flags=3, data=-1
06/08 09:27:15 SL0   key: 'voice_devices', type=1, flags=1, data=
06/08 09:27:15 SL0  check for lockfiles
06/08 09:27:15 SL0   checklock: stat failed, no file
06/08 09:27:15 SL0  locking the line
06/08 09:27:15 SL0   makelock(ttySL0) called
06/08 09:27:15 SL0   do_makelock: lock='/var/lock/LCK..ttySL0'
06/08 09:27:15 SL0   lock made
06/08 09:27:16 SL0   tio_get_rs232_lines: status: CTS DSR
06/08 09:27:16 SL0  lowering DTR to reset Modem
06/08 09:27:16 SL0   tss: set speed to 38400 (017)
06/08 09:27:16 SL0   tio_set_flow_control( HARD )
06/08 09:27:16 SL0   waiting for line to clear (VTIME=1), read: 
06/08 09:27:17 SL0  send: \dATQ0V1H0[0d]
06/08 09:27:17 SL0  waiting for ``OK''
06/08 09:27:17 SL0   got: ATQ0V1H0[0d]
06/08 09:27:17 SL0    CND: ATQ0V1H0[0d][0a]OK ** found **
06/08 09:27:17 SL0  send: ATS0=0X3Q0&D3&C1[0d]
06/08 09:27:17 SL0  waiting for ``OK''
06/08 09:27:17 SL0   got: [0d]
06/08 09:27:17 SL0    CND: OK[0a]ATS0=0X3Q0&D3&C1[0d]
06/08 09:27:17 SL0    CND: ATS0=0X3Q0&D3&C1[0d][0a]OK ** found **
06/08 09:27:17 SL0  mdm_send: 'ATI'
06/08 09:27:17 SL0    got:[0d][0a]ATI[0d]
06/08 09:27:17 SL0    got:[0d][0a]56000[0d]
06/08 09:27:17 SL0   mdm_gis: string 1: '56000'
06/08 09:27:17 SL0    got:[0a][0d][0a]OK[0d]
06/08 09:27:17 SL0   mdm_identify: string '56000'
06/08 09:27:17 SL0  Generic Rockwell modem (56000)
06/08 09:27:17 SL0  mdm_send: 'ATI3'
06/08 09:27:17 SL0    got:[0a]ATI3[0d]
06/08 09:27:17 SL0    got:[0d][0a]unknown[0d]
06/08 09:27:17 SL0   mdm_gis: string 1: 'unknown'
06/08 09:27:17 SL0    got:[0a]Smart Link compatible modem[0d]
06/08 09:27:17 SL0   mdm_gis: string 2: 'Smart Link compatible modem'
06/08 09:27:17 SL0    got:[0a][0d][0a]OK[0d]
06/08 09:27:17 SL0  mdm_send: 'ATI4'
06/08 09:27:17 SL0    got:[0a]ATI4[0d]
06/08 09:27:17 SL0    got:[0d][0a]Active Profile:[0d]
06/08 09:27:17 SL0   mdm_gis: string 1: 'Active Profile:'
06/08 09:27:17 SL0    got:[0a]S00=000 S01=000 S02=043 S03=013 S04=010
S05=008 S06=002 S07=060[0d]
06/08 09:27:17 SL0   mdm_gis: string 2: 'S00=000 S01=000 S02=043 S03=013
S04=010 S05=008 S06=002 S07=060'
06/08 09:27:17 SL0    got:[0a]S08=002 S09=006 S10=128 S11=100 S12=050
S13=001 S14=000 S15=001[0d]
06/08 09:27:17 SL0   mdm_gis: string 3: 'S08=002 S09=006 S10=128 S11=100
S12=050 S13=001 S14=000 S15=001'
06/08 09:27:17 SL0    got:[0a]S16=001 S17=000 S18=000 S19=000 S20=000
S21=000 S22=000 S23=000[0d]
06/08 09:27:17 SL0   mdm_gis: string 4: 'S16=001 S17=000 S18=000 S19=000
S20=000 S21=000 S22=000 S23=000'
06/08 09:27:17 SL0    got:[0a]S24=050 S25=000 S26=000 S27=008 S28=000
S29=001 S30=002 S31=001[0d]
06/08 09:27:17 SL0   mdm_gis: string 5: 'S24=050 S25=000 S26=000 S27=008
S28=000 S29=001 S30=002 S31=001'
06/08 09:27:17 SL0    got:[0a][0d][0a][0d][0a]OK[0d]
06/08 09:27:17 SL0  additional info: 'Active Profile:'
06/08 09:27:17 SL0  modem quirks: 0004
06/08 09:27:17 SL0  mdm_send: 'AT+FCLASS=2'
06/08 09:27:17 SL0    got:[0a]AT+FCLASS=2[0d]
06/08 09:27:17 SL0   mdm_command: string 'AT+FCLASS=2'
06/08 09:27:17 SL0    got:[0d][0a]ERROR[0d]
06/08 09:27:17 SL0   mdm_command: string 'ERROR' -> ERROR
06/08 09:27:18 SL0  mdm_send: 'AT+FCLASS=2.0'
06/08 09:27:18 SL0    got:[0a]AT+FCLASS=2.0[0d]
06/08 09:27:18 SL0   mdm_command: string 'AT+FCLASS=2.0'
06/08 09:27:18 SL0    got:[0d][0a]ERROR[0d]
06/08 09:27:18 SL0   mdm_command: string 'ERROR' -> ERROR
06/08 09:27:18 SL0  mdm_send: 'AT+FCLASS=2'
06/08 09:27:18 SL0    got:[0a]AT+FCLASS=2[0d]
06/08 09:27:18 SL0   mdm_command: string 'AT+FCLASS=2'
06/08 09:27:18 SL0    got:[0d][0a]ERROR[0d]
06/08 09:27:18 SL0   mdm_command: string 'ERROR' -> ERROR
06/08 09:27:18 SL0   no class 2/2.0 faxmodem, no faxing available
06/08 09:27:18 SL0   tss: set speed to 115200 (10002)
06/08 09:27:18 SL0   tss: set speed to 115200 (10002)
06/08 09:27:18 SL0   tio_set_flow_control( HARD )
06/08 09:27:18 SL0  detecting voice modem type
06/08 09:27:18 SL0    vgetty: ATE0
06/08 09:27:18 SL0    serial port: ATE0
06/08 09:27:18 SL0    serial port: OK
06/08 09:27:18 SL0   voice command: 'ATI9' -> ''
06/08 09:27:18 SL0    vgetty: ATI9
06/08 09:27:18 SL0    serial port: ERROR
06/08 09:27:19 SL0   voice command: 'ATI3' -> ''
06/08 09:27:19 SL0    vgetty: ATI3
06/08 09:27:19 SL0    serial port: unknown
06/08 09:27:19 SL0    serial port: Smart Link compatible modem
06/08 09:27:19 SL0    serial port: OK
06/08 09:27:19 SL0   voice command: 'ATI' -> ''
06/08 09:27:19 SL0    vgetty: ATI
06/08 09:27:19 SL0    serial port: 56000
06/08 09:27:19 SL0    Rockwell: OK
06/08 09:27:19 SL0  Rockwell detected
06/08 09:27:19 SL0   vgetty: entering voice mode
06/08 09:27:19 SL0   vgetty: Installing signal handlers
06/08 09:27:19 SL0   voice command: 'AT#CLS?' -> ''
06/08 09:27:19 SL0    vgetty: AT#CLS?
06/08 09:27:19 SL0    Rockwell: 0
06/08 09:27:19 SL0   voice command: '' -> 'OK'
06/08 09:27:19 SL0    Rockwell: OK
06/08 09:27:19 SL0   voice command: 'AT#CLS=8' -> 'OK'
06/08 09:27:19 SL0    vgetty: AT#CLS=8
06/08 09:27:19 SL0    Rockwell: OK
06/08 09:27:19 SL0   voice command: 'AT' -> 'OK'
06/08 09:27:19 SL0    vgetty: AT
06/08 09:27:19 SL0    Rockwell: OK
06/08 09:27:19 SL0    vgetty: queued event RESET_WATCHDOG at position
0000
06/08 09:27:19 SL0  initializing ROCKWELL voice modem
06/08 09:27:19 SL0   voice command: 'AT#VSP=70' -> 'OK'
06/08 09:27:20 SL0    vgetty: AT#VSP=70
06/08 09:27:20 SL0    vgetty: unqueued event RESET_WATCHDOG at position
0000
06/08 09:27:20 SL0    vgetty: voice_handle_event got event
RESET_WATCHDOG with data <NUL>
06/08 09:27:20 SL0    Rockwell: OK
06/08 09:27:20 SL0   voice command: 'AT#TL=8000' -> 'OK'
06/08 09:27:20 SL0    vgetty: AT#TL=8000
06/08 09:27:20 SL0    Rockwell: OK
06/08 09:27:20 SL0   voice command: 'AT#RG=8000' -> 'OK'
06/08 09:27:20 SL0    vgetty: AT#RG=8000
06/08 09:27:20 SL0    Rockwell: OK
06/08 09:27:20 SL0   voice command: 'AT#VSD=0' -> 'OK'
06/08 09:27:20 SL0    vgetty: AT#VSD=0
06/08 09:27:20 SL0    Rockwell: OK
06/08 09:27:20 SL0   voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
06/08 09:27:20 SL0    vgetty: AT#VTD=3F,3F,3F
06/08 09:27:20 SL0    Rockwell: OK
06/08 09:27:20 SL0   voice command: 'AT#VSS=1' -> 'OK'
06/08 09:27:20 SL0    vgetty: AT#VSS=1
06/08 09:27:20 SL0    Rockwell: OK
06/08 09:27:20 SL0   voice command: 'AT&K3' -> 'OK'
06/08 09:27:20 SL0    vgetty: AT&K3
06/08 09:27:20 SL0    Rockwell: OK
06/08 09:27:20 SL0   tio_set_flow_control( HARD )
06/08 09:27:20 SL0   vgetty: leaving voice mode
06/08 09:27:20 SL0   voice command: 'AT#CLS=0' -> 'OK'
06/08 09:27:20 SL0    vgetty: AT#CLS=0
06/08 09:27:20 SL0    Rockwell: OK
06/08 09:27:20 SL0   voice command: 'AT' -> 'OK'
06/08 09:27:20 SL0    vgetty: AT
06/08 09:27:20 SL0    Rockwell: OK
06/08 09:27:20 SL0   vgetty: Restoring signal handlers
06/08 09:27:20 SL0   waiting for line to clear (VTIME=3), read: 
06/08 09:27:21 SL0   removing lock file
06/08 09:27:21 SL0  waiting...

On Tue, 2005-06-07 at 12:40 -0400, George Bell wrote:
> I'm e-mailing my reply off-list as it looks like your problem is
> specifically related to voice only and therefore inappropriate for the
> hylafax mailing list.   Looking at your log again you can see there is
> a definite problem:
> 
> 06/03 08:18:23   shell(1): DEVICE DIALUP_LINE
> 06/03 08:18:23    vm: queued event RESET_WATCHDOG at position 0003
> 06/03 08:18:23   voice command: 'AT#VLS=4' -> 'OK'
> 06/03 08:18:23    vm: Got pipe signal
> 06/03 08:18:23    vm: queued event SIGNAL_SIGPIPE at position 0004
> 06/03 08:18:23    vm: AT#VLS=4
> 06/03 08:18:23    vm: unqueued event RESET_WATCHDOG at position 0003
> 06/03 08:18:23    vm: voice_handle_event got event RESET_WATCHDOG with
> data <_>
> 06/03 08:18:23    vm: unqueued event SIGNAL_SIGPIPE at position 0004
> 06/03 08:18:23    vm: voice_handle_event got event SIGNAL_SIGPIPE with
> data <NUL>
> 06/03 08:18:23   shell(2): ENABLE EVENTS
> 06/03 08:18:23  vm: Nested command in shell script
> 06/03 08:18:23   vm(2): ERROR
> 06/03 08:18:23    Rockwell: RING
> 06/03 08:18:23    Rockwell:
> 06/03 08:18:23    vm: Got pipe signal
> 06/03 08:18:23    vm: queued event SIGNAL_SIGPIPE at position 0005OK
> 06/03 08:18:23   vm(1): READY
> 06/03 08:18:23    vm: unqueued event SIGNAL_SIGPIPE at position 0005
> 06/03 08:18:23    vm: voice_handle_event got event SIGNAL_SIGPIPE with
> data <NUL>
> 06/03 08:18:23   shell(1): PLAY /var/spool/voice/messages/welcome.rmd
> 06/03 08:18:23   vm(1): PLAYING
> 06/03 08:18:23  playing voice file /var/spool/voice/messages/welcome.rmd
> 06/03 08:18:23   vm: raw modem data header found
> 06/03 08:18:23   vm: modem type Rockwell found
> 06/03 08:18:23   vm: compression method 0x0004, speed 7200, bits 4
> 06/03 08:18:23    vm: queued event RESET_WATCHDOG at position 0006
> 06/03 08:18:23   voice command: 'AT#VBS=4' -> 'OK'
> 06/03 08:18:23    vm: AT#VBS=4
> 06/03 08:18:23    vm: unqueued event RESET_WATCHDOG at position 0006
> 06/03 08:18:23    vm: voice_handle_event got event RESET_WATCHDOG with
> data <NUL>
> 06/03 08:18:23    Rockwell: OK
> 06/03 08:18:23    vm: queued event RESET_WATCHDOG at position 0007
> 06/03 08:18:23    vm: unqueued event RESET_WATCHDOG at position 0007
> 06/03 08:18:23    vm: voice_handle_event got event RESET_WATCHDOG with
> data <NUL>
> 06/03 08:18:23   voice command: 'AT&K3' -> 'OK'
> 06/03 08:18:23    vm: AT&K3
> 06/03 08:18:23    Rockwell: OK
> 06/03 08:18:23   tio_set_flow_control( HARD XON_OUT )
> 06/03 08:18:23   voice command: 'AT#VTX' -> 'CONNECT'
> 06/03 08:18:23    vm: AT#VTX
> 06/03 08:18:23    Rockwell: RING
> 06/03 08:18:28    Rockwell: RING
> 06/03 08:18:33    Rockwell: RING
> 06/03 08:18:38    Rockwell: RING
> 06/03 08:18:44    Rockwell: RING
> 06/03 08:18:49    Rockwell: RING
> 06/03 08:18:54    Rockwell: RING
> 
> 
> The modem has definitely ignored our command to take it off-hook, as the
> RINGS continue to come in and the failure of this command caused all the
> following commands to fail as well.   You mentioned that it works with
> vgetty?  Send me the log with vgetty successfully answering the call
> with log level 6 and your {hylafax-root}/etc/config.ttySL0 and I'll take
> one more stab at it.  If I can't find out what's going on here you can
> post the log on the mgetty list, mgetty@xxxxxxx
> 
> George
-- 
---------------
Branko Markovic
bramar@xxxxxxxx
ICQ#: 132044291



____________________ HylaFAX(tm) Users Mailing List _______________________
  To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
 On UNIX: mail -s unsubscribe hylafax-users-request@xxxxxxxxxxx < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@xxxxxxxxx*




Project hosted by iFAX Solutions