Vgetty problem

Hans Dumbrajs (hans@clinet.fi)
Thu, 19 Feb 1998 18:56:40 +0100
Messages sorted by:[ date ][ thread ][ subject ][ author ]
>>There is a problem, but your log level is too low to show what.
>>Please use the highest debug level and mail me the logfile again.
Here is the logfile.. :


02/19 19:33:49 yI0 Linux ISDN detected
02/19 19:33:49 yI0 initializing ISDN4Linux voice mode
02/19 19:33:49 yI0 vgetty: queued event RESET_WATCHDOG at position
0000
02/19 19:33:49 yI0 voice command: 'ATS18?' -> ''
02/19 19:33:49 yI0 vgetty: ATS18?
02/19 19:33:49 yI0 Linux ISDN: 4
02/19 19:33:49 yI0 voice command: '' -> 'OK'
02/19 19:33:49 yI0 Linux ISDN: OK
02/19 19:33:49 yI0 voice command: 'ATS18=5' -> 'OK'
02/19 19:33:49 yI0 vgetty: ATS18=5
02/19 19:33:49 yI0 Linux ISDN: OK
02/19 19:33:49 yI0 voice command: 'ATS13?' -> ''
02/19 19:33:50 yI0 vgetty: ATS13?
02/19 19:33:50 yI0 Linux ISDN: 4
02/19 19:33:50 yI0 voice command: '' -> 'OK'
02/19 19:33:50 yI0 Linux ISDN: OK
02/19 19:33:50 yI0 voice command: 'ATS13=20' -> 'OK'
02/19 19:33:50 yI0 vgetty: ATS13=20
02/19 19:33:50 yI0 Linux ISDN: OK
02/19 19:33:50 yI0 waiting for line to clear (VTIME), read:
02/19 19:33:50 yI0 removing lock file
02/19 19:33:50 yI0 waiting...
02/19 19:34:05 yI0 select returned 1
02/19 19:34:05 yI0 checking lockfiles, locking the line
02/19 19:34:05 yI0 makelock(ttyI0) called
02/19 19:34:05 yI0 do_makelock: lock='/var/lock/LCK..ttyI0'
02/19 19:34:05 yI0 lock made
02/19 19:34:05 yI0 vgetty: read number of rings (5) from file
/etc/rings.ttyI0
02/19 19:34:05 yI0 waiting for ``RING_''
02/19 19:34:05 yI0 got: [0d]
02/19 19:34:05 yI0 CND: OK[0a]RING[0d]
02/19 19:34:05 yI0 CND: RING ** found **
02/19 19:34:05 yI0 waiting for ``RING_''
02/19 19:34:05 yI0 got: [0a]CALLER NUMBER: 0[0d]
02/19 19:34:05 yI0 CND: CALLER NUMBER: 0
02/19 19:34:05 yI0 CND: found: 0[0a][0d][0a]RING[0d]
02/19 19:34:09 yI0 CND: RING ** found **
02/19 19:34:09 yI0 waiting for ``RING_''
02/19 19:34:09 yI0 got: [0a][0d][0a]RING[0d]
02/19 19:34:14 yI0 CND: RING ** found **
02/19 19:34:14 yI0 waiting for ``RING_''
02/19 19:34:14 yI0 got: [0a][0d][0a]RING[0d]
02/19 19:34:18 yI0 CND: RING ** found **
02/19 19:34:18 yI0 waiting for ``RING_''
02/19 19:34:18 yI0 got: [0a][0d][0a]RING[0d]
02/19 19:34:23 yI0 CND: RING ** found **
02/19 19:34:23 yI0 reading ring_type ring configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
02/19 19:34:23 yI0 reading /usr/local/etc/mgetty+sendfax/voice.conf...

02/19 19:34:23 yI0 conf lib: read: 'part generic'
02/19 19:34:23 yI0 found CT_KEYWORD part generic
02/19 19:34:23 yI0 conf lib: read: 'voice_log_level 6'
02/19 19:34:23 yI0 conf lib: read: 'voice_dir /var/spool/voice'
02/19 19:34:23 yI0 conf lib: read: 'phone_owner root'
02/19 19:34:23 yI0 conf lib: read: 'phone_group users'
02/19 19:34:23 yI0 conf lib: read: 'phone_mode 0660'
02/19 19:34:23 yI0 conf lib: read: 'message_flag_file .flag'
02/19 19:34:23 yI0 conf lib: read: 'receive_dir incoming'
02/19 19:34:23 yI0 conf lib: read: 'message_dir messages'
02/19 19:34:23 yI0 conf lib: read: 'message_list Index'
02/19 19:34:23 yI0 conf lib: read: 'backup_message message.rmd'
02/19 19:34:23 yI0 conf lib: read: 'port_speed 57600'
02/19 19:34:23 yI0 conf lib: read: 'voice_shell /bin/sh'
02/19 19:34:23 yI0 conf lib: read: 'port_timeout 10'
02/19 19:34:23 yI0 conf lib: read: 'dial_timeout 90'
02/19 19:34:23 yI0 conf lib: read: 'command_delay 100'
02/19 19:34:23 yI0 conf lib: read: 'dtmf_len 30'
02/19 19:34:23 yI0 conf lib: read: 'dtmf_threshold 40'
02/19 19:34:23 yI0 conf lib: read: 'dtmf_wait 7'
02/19 19:34:23 yI0 conf lib: read: 'ignore_fax_dle false'
02/19 19:34:23 yI0 conf lib: read: 'raw_data false'
02/19 19:34:23 yI0 conf lib: read: 'rec_compression 0'
02/19 19:34:23 yI0 conf lib: read: 'rec_speed 0'
02/19 19:34:23 yI0 conf lib: read: 'rec_silence_len 70'
02/19 19:34:23 yI0 conf lib: read: 'rec_silence_threshold 40'
02/19 19:34:23 yI0 conf lib: read: 'rec_remove_silence false'
02/19 19:34:23 yI0 conf lib: read: 'rec_max_len 300'
02/19 19:34:23 yI0 conf lib: read: 'rec_min_len 0'
02/19 19:34:23 yI0 conf lib: read: 'do_hard_flow true'
02/19 19:34:23 yI0 conf lib: read: 'beep_frequency 933'
02/19 19:34:23 yI0 conf lib: read: 'beep_length 1500'
02/19 19:34:23 yI0 conf lib: read: 'max_tries 3'
02/19 19:34:23 yI0 conf lib: read: 'retry_delay 5'
02/19 19:34:23 yI0 conf lib: read: 'watchdog_timeout 60'
02/19 19:34:23 yI0 conf lib: read: 'receive_gain -1'
02/19 19:34:23 yI0 conf lib: read: 'transmit_gain -1'
02/19 19:34:23 yI0 conf lib: read: 'enable_command_echo false'
02/19 19:34:23 yI0 conf lib: read: 'poll_interval 10'
02/19 19:34:23 yI0 conf lib: read: 'program vgetty'
02/19 19:34:23 yI0 conf lib: read: 'rings /etc/rings'
02/19 19:34:23 yI0 conf lib: read: 'answer_mode /etc/answer'
02/19 19:34:23 yI0 conf lib: read: 'force_autodetect false'
02/19 19:34:23 yI0 conf lib: read: 'toll_saver_rings 0'
02/19 19:34:23 yI0 conf lib: read: 'rec_always_keep true'
02/19 19:34:23 yI0 conf lib: read: 'button_program '
02/19 19:34:23 yI0 conf lib: read: 'call_program '
02/19 19:34:23 yI0 conf lib: read: 'dtmf_program dtmf.sh'
02/19 19:34:23 yI0 conf lib: read: 'message_program '
02/19 19:34:23 yI0 conf lib: read: 'do_message_light false'
02/19 19:34:23 yI0 conf lib: read: 'program vm'
02/19 19:34:23 yI0 conf lib: read: 'voice_devices ttyI0'
02/19 19:34:23 yI0 conf lib: read: 'dialout_timeout 90'
02/19 19:34:23 yI0 conf lib: read: 'program pvf'
02/19 19:34:23 yI0 conf lib: read: 'port ttyS0'
02/19 19:34:23 yI0 conf lib: read: 'rings 6'
02/19 19:34:23 yI0 conf lib: read: 'answer_mode fax:data'
02/19 19:34:23 yI0 conf lib: read: 'i '
02/19 19:34:23 yI0 conf lib: read: 'ring_type virtual'
02/19 19:34:23 yI0 section: ring_type virtual, ignore
02/19 19:34:23 yI0 conf lib: read: 'answer_mode fax:data'
02/19 19:34:23 yI0 conf lib: read: 'ring_type ring'
02/19 19:34:23 yI0 section: ring_type ring, **found**
02/19 19:34:23 yI0 conf lib: read: 'rings 5'
02/19 19:34:23 yI0 conf lib: read: 'answer_mode voice'
02/19 19:34:23 yI0 key: 'part', type=6, flags=4, data=(ignored)
02/19 19:34:23 yI0 key: 'program', type=6, flags=4, data=(ignored)
02/19 19:34:23 yI0 key: 'port', type=6, flags=4, data=(ignored)
02/19 19:34:23 yI0 key: 'ring_type', type=6, flags=4, data=(ignored)
02/19 19:34:23 yI0 key: 'voice_log_level', type=0, flags=3, data=6
02/19 19:34:23 yI0 key: 'voice_devices', type=1, flags=0, data=(empty)

02/19 19:34:23 yI0 key: 'port_speed', type=0, flags=3, data=57600
02/19 19:34:23 yI0 key: 'port_timeout', type=0, flags=3, data=10
02/19 19:34:23 yI0 key: 'dtmf_len', type=0, flags=3, data=30
02/19 19:34:23 yI0 key: 'dtmf_threshold', type=0, flags=3, data=40
02/19 19:34:23 yI0 key: 'dtmf_wait', type=0, flags=3, data=7
02/19 19:34:23 yI0 key: 'rec_compression', type=0, flags=3, data=0
02/19 19:34:23 yI0 key: 'rec_speed', type=0, flags=3, data=0
02/19 19:34:23 yI0 key: 'rec_silence_len', type=0, flags=3, data=70
02/19 19:34:23 yI0 key: 'rec_silence_threshold', type=0, flags=3,
data=40
02/19 19:34:23 yI0 key: 'rec_remove_silence', type=3, flags=3,
data=FALSE
02/19 19:34:23 yI0 key: 'rec_max_len', type=0, flags=3, data=300
02/19 19:34:23 yI0 key: 'receive_gain', type=0, flags=3, data=-1
02/19 19:34:23 yI0 key: 'transmit_gain', type=0, flags=3, data=-1
02/19 19:34:23 yI0 key: 'rings', type=1, flags=3, data=5
02/19 19:34:23 yI0 key: 'answer_mode', type=1, flags=3, data=voice
02/19 19:34:23 yI0 key: 'toll_saver_rings', type=0, flags=3, data=0
02/19 19:34:23 yI0 key: 'rec_always_keep', type=3, flags=3, data=TRUE
02/19 19:34:23 yI0 key: 'voice_dir', type=1, flags=3,
data=/var/spool/voice
02/19 19:34:23 yI0 key: 'phone_owner', type=1, flags=3, data=root
02/19 19:34:23 yI0 key: 'phone_group', type=1, flags=3, data=users
02/19 19:34:23 yI0 key: 'phone_mode', type=0, flags=3, data=432
02/19 19:34:23 yI0 key: 'message_flag_file', type=1, flags=3,
data=.flag
02/19 19:34:23 yI0 key: 'receive_dir', type=1, flags=3, data=incoming
02/19 19:34:23 yI0 key: 'message_dir', type=1, flags=3, data=messages
02/19 19:34:23 yI0 key: 'message_list', type=1, flags=3, data=Index
02/19 19:34:23 yI0 key: 'backup_message', type=1, flags=3,
data=message.rmd
02/19 19:34:23 yI0 key: 'dialout_timeout', type=0, flags=1, data=90
02/19 19:34:23 yI0 key: 'beep_frequency', type=0, flags=3, data=933
02/19 19:34:23 yI0 key: 'beep_length', type=0, flags=3, data=1500
02/19 19:34:23 yI0 key: 'raw_data', type=3, flags=3, data=FALSE
02/19 19:34:23 yI0 key: 'max_tries', type=0, flags=3, data=3
02/19 19:34:23 yI0 key: 'retry_delay', type=0, flags=3, data=5
02/19 19:34:23 yI0 key: 'voice_shell', type=1, flags=3, data=/bin/sh
02/19 19:34:23 yI0 key: 'button_program', type=1, flags=3, data=
02/19 19:34:23 yI0 key: 'call_program', type=1, flags=3, data=
02/19 19:34:23 yI0 key: 'dtmf_program', type=1, flags=3, data=dtmf.sh
02/19 19:34:23 yI0 key: 'message_program', type=1, flags=3, data=
02/19 19:34:23 yI0 key: 'do_message_light', type=3, flags=3,
data=FALSE
02/19 19:34:23 yI0 key: 'do_hard_flow', type=3, flags=3, data=TRUE
02/19 19:34:23 yI0 key: 'force_autodetect', type=3, flags=3,
data=FALSE
02/19 19:34:23 yI0 key: 'watchdog_timeout', type=0, flags=3, data=60
02/19 19:34:23 yI0 key: 'rec_min_len', type=0, flags=3, data=0
02/19 19:34:23 yI0 key: 'command_delay', type=0, flags=3, data=100
02/19 19:34:23 yI0 key: 'ignore_fax_dle', type=3, flags=3, data=FALSE
02/19 19:34:23 yI0 key: 'dial_timeout', type=0, flags=3, data=90
02/19 19:34:23 yI0 key: 'enable_command_echo', type=3, flags=3,
data=FALSE
02/19 19:34:23 yI0 key: 'poll_interval', type=0, flags=3, data=10
02/19 19:34:23 yI0 vgetty: answer mode was set directly
02/19 19:34:23 yI0 vgetty: answer mode is [voice]
02/19 19:34:23 yI0 vgetty: opening list file
/var/spool/voice/messages/Index
02/19 19:34:23 yI0 vgetty: found 1 messages, picked message number 1
02/19 19:34:23 yI0 vgetty: message name is
/var/spool/voice/messages/message.rmd
02/19 19:34:23 yI0 vgetty: entering voice mode
02/19 19:34:23 yI0 vgetty: Installing signal handlers
02/19 19:34:23 yI0 voice command: 'AT+FCLASS?' -> ''
02/19 19:34:23 yI0 vgetty: AT+FCLASS?
02/19 19:34:23 yI0 Linux ISDN: 8
02/19 19:34:23 yI0 voice command: '' -> 'OK'
02/19 19:34:23 yI0 Linux ISDN: OK
02/19 19:34:23 yI0 voice command: 'AT+FCLASS=8' -> 'OK'
02/19 19:34:24 yI0 vgetty: AT+FCLASS=8
02/19 19:34:24 yI0 Linux ISDN: OK
02/19 19:34:24 yI0 vgetty: queued event RESET_WATCHDOG at position
0001
02/19 19:34:24 yI0 voice command: 'ATS20?' -> '0|1|2|3|4'
02/19 19:34:24 yI0 vgetty: ATS20?
02/19 19:34:24 yI0 Linux ISDN: 1
02/19 19:34:24 yI0 voice command: 'ATA' -> 'VCON'
02/19 19:34:24 yI0 vgetty: ATA
02/19 19:34:24 yI0 Linux ISDN: OK
02/19 19:34:24 yI0 Linux ISDN: VCON
02/19 19:34:24 yI0 playing voice file
/var/spool/voice/messages/message.rmd
02/19 19:34:24 yI0 vgetty: raw modem data header found
02/19 19:34:24 yI0 vgetty: modem type ISDN4Linux found
02/19 19:34:24 yI0 vgetty: compression method 0x0004, speed 8012, bits
4
02/19 19:34:24 yI0 vgetty: queued event RESET_WATCHDOG at position
0002
02/19 19:34:24 yI0 voice command: 'AT+VSM=4' -> 'OK'
02/19 19:34:24 yI0 vgetty: AT+VSM=4
02/19 19:34:24 yI0 Linux ISDN: OK
02/19 19:34:24 yI0 vgetty: queued event RESET_WATCHDOG at position
0003
02/19 19:34:24 yI0 vgetty: unqueued event RESET_WATCHDOG at position
0000
02/19 19:34:24 yI0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
02/19 19:34:24 yI0 vgetty: unqueued event RESET_WATCHDOG at position
0001
02/19 19:34:24 yI0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data < >
02/19 19:34:24 yI0 vgetty: unqueued event RESET_WATCHDOG at position
0002
02/19 19:34:24 yI0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
02/19 19:34:24 yI0 vgetty: unqueued event RESET_WATCHDOG at position
0003
02/19 19:34:24 yI0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
02/19 19:34:24 yI0 voice command: 'AT' -> 'OK'
02/19 19:34:24 yI0 vgetty: AT
02/19 19:34:24 yI0 Linux ISDN: OK
02/19 19:34:24 yI0 tio_set_flow_control( HARD XON_OUT )
02/19 19:34:24 yI0 voice command: 'AT+VTX' -> 'CONNECT|NO ANSWER'
02/19 19:34:24 yI0 vgetty: AT+VTX
02/19 19:34:24 yI0 Linux ISDN: CONNECT
02/19 19:34:30 yI0 vgetty: <VOICE DATA 28062 bytes>
02/19 19:34:30 yI0 vgetty: queued event RESET_WATCHDOG at position
0004
02/19 19:34:30 yI0 vgetty: <STOP PLAY>
02/19 19:34:30 yI0 voice command: '' -> 'OK|VCON'
02/19 19:34:31 yI0 Linux ISDN: VCON
02/19 19:34:31 yI0 vgetty: unqueued event RESET_WATCHDOG at position
0004
02/19 19:34:31 yI0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
02/19 19:34:31 yI0 recording voice file
/var/spool/voice/incoming/va00099.rmd
02/19 19:34:31 yI0 vgetty: queued event RESET_WATCHDOG at position
0005
02/19 19:34:31 yI0 voice command: 'AT+VSM=2' -> 'OK'
02/19 19:34:31 yI0 vgetty: AT+VSM=2
02/19 19:34:31 yI0 Linux ISDN: OK
02/19 19:34:31 yI0 vgetty: queued event RESET_WATCHDOG at position
0006
02/19 19:34:31 yI0 vgetty: unqueued event RESET_WATCHDOG at position
0005
02/19 19:34:31 yI0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
02/19 19:34:31 yI0 vgetty: unqueued event RESET_WATCHDOG at position
0006
02/19 19:34:31 yI0 vgetty: voice_handle_event got event
RESET_WATCHDOG with data <_>
02/19 19:34:31 yI0 voice command: 'AT' -> 'OK'
02/19 19:34:31 yI0 vgetty: AT
02/19 19:34:31 yI0 Linux ISDN: OK
02/19 19:34:31 yI0 tio_set_flow_control( HARD XON_IN )
02/19 19:34:31 yI0 voice command: 'AT+VRX' -> 'CONNECT|NO ANSWER'
02/19 19:34:31 yI0 vgetty: AT+VRX
02/19 19:34:31 yI0 Linux ISDN: CONNECT
02/19 19:34:41 yI0 Linux ISDN: <VOICE DATA 20501 bytes>
02/19 19:34:41 yI0 Linux ISDN: <DLE> <ETX>
02/19 19:34:41 yI0 voice command: '' -> 'OK|VCON'
02/19 19:34:41 yI0 Linux ISDN: NO CARRIER
02/19 19:34:41 yI0 Linux ISDN:
02/19 19:34:52 yI0 vgetty: timeout while reading character from voice
modem: No such file or directory
02/19 19:34:52 yI0 vgetty: Could not record a message: Success

Thanx for the help!

--
- Hans Dumbrajs / hans@clinet.fi



.