strange timeout-problems with mgetty/vgetty
Roland Wagner (Roland.Wagner@datev.de)
Fri, 30 Jul 1999 11:11:24 GMT
Hi,
I've a strange problem with vgetty.
There are two modems connected to my linux-machine
(LST with kernel 2.2.5 on a 486 DX2-80, 32 MB RAM)
The modems are connected via ISDN-telephone-system
One modem (typ: ??? creatix ???) on ttyS1 is waiting
for incomming fax and is working perfectly (mgetty).
The other modem
07/07 23:17:51 yS0 mdm_gis: string 2: 'U1496EG V 6.19 P '
07/07 23:17:51 yS0 additional info: 'U1496EG V 6.19 P '
(Zyxel 1496 EG+) is waiting on ttyS0 for incomming voice and fax
(vgetty).
Everything works fine, except phone stops ringing before estimated
ring-count is reached.
As you can see in the log-file (following at the end of these message)
there is always a different timeout. Sometimes it's 9 minutes, somtimes
it's
more than an 70 minutes and somtimes there is no return and I have
to kill vgetty - last example in logfile (or timeout is > 3 hours).
Of course, vgetty is not answering any call if its waiting for timeout.
Additional info:
---------------------------------------------------------------------------------------------------------------------
07/28 23:54:10 yS0 vgetty: experimental test release 0.7.2 / 12Apr97
07/28 23:54:10 yS0 mgetty: experimental test release 1.1.8-Jul27
07/28 23:54:11 yS0 reading program vgetty configuration from config
file /etc/mgetty+sendfax/voice.conf
07/28 23:54:11 yS0 reading port ttyS0 configuration from config file
/etc/mgetty+sendfax/voice.conf
07/28 23:54:11 yS0 reading /etc/mgetty+sendfax/voice.conf...
---------------------------------------------------------------------------------------------------------------------
Should I use a newer version of vgetty?? (Is there a newer version?)
In voice.conf there are entries for timeout:
---------------------------------------------------------------------------------------------------------------------
...
#
# Default port timeout in seconds for a read or write operation. The
# default value is 10 seconds.
#
port_timeout 10
#
# Default timeout for a dialout in seconds. The default value is 90
seconds.
#
dial_timeout 90
#
# Delay before sending a new voice command to the modem in milliseconds.
# The default is 100 milliseconds.
#
command_delay 100
#
#
...
------------------------------------------------------------------------------------------------------------------------
Any Ideas?
Thanks for any suggestions
Roland
And here is the log-file:
---------------------------------------------------------------------------------------------------------------------
07/28 17:17:30 yS0 checking lockfiles, locking the line
07/28 17:17:30 yS0 makelock(ttyS0) called
07/28 17:17:30 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
07/28 17:17:30 yS0 lock made
07/28 17:17:30 yS0 waiting for ``RING_''
07/28 17:17:30 yS0 got: [0d][0a]RING[0d] ** found **
07/28 17:17:31 yS0 waiting for ``RING_''
07/28 17:17:31 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:17:35 yS0 waiting for ``RING_''
07/28 17:17:35 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:17:40 yS0 waiting for ``RING_''
07/28 17:17:40 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:17:45 yS0 waiting for ``RING_''
07/28 17:17:45 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:17:50 yS0 waiting for ``RING_''
07/28 17:17:50 yS0 got: [0a]
07/28 17:36:49 yS0 timeout in chat script, waiting for `RING_'
07/28 17:36:49 ##### phone stopped ringing (rings=5)
07/28 17:36:49 yS0 waiting for line to clear (VTIME), read:
[0a]RING[0d][0a]
07/28 17:36:50 yS0 removing lock file
07/28 17:36:50 yS0 waiting...
07/28 17:36:54 yS0 checking lockfiles, locking the line
07/28 17:36:54 yS0 makelock(ttyS0) called
07/28 17:36:54 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
07/28 17:36:54 yS0 lock made
07/28 17:36:54 yS0 waiting for ``RING_''
07/28 17:36:54 yS0 got: [0d][0a]RING[0d] ** found **
07/28 17:36:54 yS0 waiting for ``RING_''
07/28 17:36:54 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:36:59 yS0 waiting for ``RING_''
07/28 17:36:59 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:37:04 yS0 waiting for ``RING_''
07/28 17:37:04 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:37:09 yS0 waiting for ``RING_''
07/28 17:37:09 yS0 got: [0a]
07/28 17:46:29 yS0 timeout in chat script, waiting for `RING_'
07/28 17:46:29 ##### phone stopped ringing (rings=4)
07/28 17:46:29 yS0 waiting for line to clear (VTIME), read:
[0a]RING[0d][0a]
07/28 17:46:29 yS0 removing lock file
07/28 17:46:29 yS0 waiting...
07/28 17:46:34 yS0 checking lockfiles, locking the line
07/28 17:46:34 yS0 makelock(ttyS0) called
07/28 17:46:34 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
07/28 17:46:34 yS0 lock made
07/28 17:46:34 yS0 waiting for ``RING_''
07/28 17:46:34 yS0 got: [0d][0a]RING[0d] ** found **
07/28 17:46:34 yS0 waiting for ``RING_''
07/28 17:46:34 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:46:39 yS0 waiting for ``RING_''
07/28 17:46:39 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:46:43 yS0 waiting for ``RING_''
07/28 17:46:43 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:46:48 yS0 waiting for ``RING_''
07/28 17:46:48 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 17:46:53 yS0 waiting for ``RING_''
07/28 17:46:53 yS0 got: [0a]
07/28 19:02:31 yS0 timeout in chat script, waiting for `RING_'
07/28 19:02:31 ##### phone stopped ringing (rings=5)
07/28 19:02:31 yS0 waiting for line to clear (VTIME), read:
[0a]RING[0d][0a]
07/28 19:02:32 yS0 removing lock file
07/28 19:02:32 yS0 waiting...
07/28 19:02:36 yS0 checking lockfiles, locking the line
07/28 19:02:36 yS0 makelock(ttyS0) called
07/28 19:02:36 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
07/28 19:02:36 yS0 lock made
07/28 19:02:36 yS0 waiting for ``RING_''
07/28 19:02:36 yS0 got: [0d][0a]RING[0d] ** found **
07/28 19:02:36 yS0 waiting for ``RING_''
07/28 19:02:36 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 19:02:41 yS0 waiting for ``RING_''
07/28 19:02:41 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 19:02:46 yS0 waiting for ``RING_''
07/28 19:02:46 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 19:02:51 yS0 waiting for ``RING_''
07/28 19:02:51 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 19:02:55 yS0 waiting for ``RING_''
07/28 19:02:55 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 19:03:00 yS0 waiting for ``RING_''
07/28 19:03:00 yS0 got: [0a]
07/28 19:29:00 yS0 timeout in chat script, waiting for `RING_'
07/28 19:29:00 ##### phone stopped ringing (rings=6)
07/28 19:29:00 yS0 waiting for line to clear (VTIME), read:
[0a]RING[0d][0a]
07/28 19:29:01 yS0 removing lock file
07/28 19:29:01 yS0 waiting...
07/28 19:29:05 yS0 checking lockfiles, locking the line
07/28 19:29:05 yS0 makelock(ttyS0) called
07/28 19:29:05 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
07/28 19:29:05 yS0 lock made
07/28 19:29:05 yS0 waiting for ``RING_''
07/28 19:29:05 yS0 got: [0d][0a]RING[0d] ** found **
07/28 19:29:05 yS0 waiting for ``RING_''
07/28 19:29:05 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 19:29:10 yS0 waiting for ``RING_''
07/28 19:29:10 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 19:29:15 yS0 waiting for ``RING_''
07/28 19:29:15 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 19:29:20 yS0 waiting for ``RING_''
07/28 19:29:20 yS0 got: [0a]
07/28 21:52:00 yS0 timeout in chat script, waiting for `RING_'
07/28 21:52:00 ##### phone stopped ringing (rings=4)
07/28 21:52:00 yS0 waiting for line to clear (VTIME), read:
[0a]RING[0d][0a]
07/28 21:52:01 yS0 removing lock file
07/28 21:52:01 yS0 waiting...
07/28 21:52:05 yS0 checking lockfiles, locking the line
07/28 21:52:05 yS0 makelock(ttyS0) called
07/28 21:52:05 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
07/28 21:52:05 yS0 lock made
07/28 21:52:05 yS0 waiting for ``RING_''
07/28 21:52:05 yS0 got: [0d][0a]RING[0d] ** found **
07/28 21:52:05 yS0 waiting for ``RING_''
07/28 21:52:05 yS0 got: [0a][0d][0a]RING[0d] ** found **
07/28 21:52:10 yS0 waiting for ``RING_''
07/28 21:52:10 yS0 got: [0a]
07/28 23:54:10 ##### failed dev=ttyS0, pid=9314, got signal 15, exiting
07/28 23:54:10 yS0 removing lock file
--
07/28 23:54:10 yS0 vgetty: experimental test release 0.7.2 / 12Apr97
07/28 23:54:10 yS0 mgetty: experimental test release 1.1.8-Jul27
07/28 23:54:11 yS0 reading program vgetty configuration from config
file /etc/mgetty+sendfax/voice.conf
07/28 23:54:11 yS0 reading port ttyS0 configuration from config file
/etc/mgetty+sendfax/voice.conf
07/28 23:54:11 yS0 reading /etc/mgetty+sendfax/voice.conf...
---------------------------------------------------------------------------------------------------------------------