Modems hanging up
"Greg Berardi" (greg@elmnet.net)
Fri, 6 Nov 1998 05:26:37 +0100
Gert, I hope you can help me.
Periodically my modems will lock up.
I am using:
RedHat kernel 2.0.34
mgetty 1.1.14
pppd 2.2.0
cyclades 2.1.1.9
MultiTech MT2834ZDX 33.6K modems
The following is an example of the log file.
It looks like someone was trying to log in and then the modem locked up.
11/04 22:11:48 yC1 tio_set_flow_control( HARD )
11/04 22:11:48 yC1 print welcome banner (/etc/issue)
11/04 22:11:48 yC1 getlogname (AUTO_PPP), read:~[ff]}#[c0]!
11/04 22:11:48 yC1 input finished with '\r', setting ICRNL ONLCR
11/04 22:11:48 yC1 tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
11/04 22:11:48 yC1 match: user='/AutoPPP/', key=''
11/04 22:11:48 yC1 match: user='/AutoPPP/', key='/FIDO/'
11/04 22:11:48 yC1 match: user='/AutoPPP/', key=''
11/04 22:11:48 yC1 match: user='/AutoPPP/', key='/AutoPPP/'*** hit!
11/04 22:11:48 yC1 login: utmp entry: a_ppp
11/04 22:11:48 yC1 utmp + wtmp entry made
11/04 22:11:48 yC1 calling login: cmd='/usr/sbin/pppd', argv[]='pppd'
11/04 22:11:48 ##### data dev=ttyC1, pid=17526, caller='none', conn='14400
V42bis', name='', cmd='/usr/sbin/pppd', user='/AutoPPP/'
11/04 22:11:48 yC1 setenv: 'CALLER_ID=none'
11/04 22:11:48 yC1 setenv: 'CONNECT=14400 V42bis'
--
11/04 23:21:55 yC1 mgetty: experimental test release 1.1.14-Apr02
11/04 23:21:55 yC1 mgetty.c compiled at Jun 27 1998, 09:12:51
11/04 23:21:55 yC1 user id: 0, parent pid: 1
11/04 23:21:55 yC1 reading configuration data for port 'ttyC1'
11/04 23:21:55 yC1 reading /usr/local/etc/mgetty+sendfax/mgetty.config...
11/04 23:21:55 yC1 conf lib: read: 'debug 4'
11/04 23:21:55 yC1 conf lib: read: 'fax-id 49 89 xxxxxxxx'
11/04 23:21:55 yC1 conf lib: read: 'speed 38400'
11/04 23:21:55 yC1 key: 'speed', type=0, flags=2, data=57600
11/04 23:21:55 yC1 key: 'switchbd', type=0, flags=1, data=0
11/04 23:21:55 yC1 key: 'direct', type=3, flags=1, data=FALSE
11/04 23:21:55 yC1 key: 'blocking', type=3, flags=1, data=FALSE
11/04 23:21:55 yC1 key: 'port-owner', type=1, flags=1, data=uucp
11/04 23:21:55 yC1 key: 'port-group', type=1, flags=1, data=modem
11/04 23:21:55 yC1 key: 'port-mode', type=0, flags=1, data=432
11/04 23:21:55 yC1 key: 'toggle-dtr', type=3, flags=1, data=TRUE
11/04 23:21:55 yC1 key: 'toggle-dtr-waittime', type=0, flags=1, data=500
11/04 23:21:55 yC1 key: 'data-only', type=3, flags=2, data=TRUE
11/04 23:21:55 yC1 key: 'fax-only', type=3, flags=1, data=FALSE
11/04 23:21:55 yC1 key: 'modem-type', type=1, flags=1, data=auto
11/04 23:21:55 yC1 key: 'modem-quirks', type=0, flags=0, data=(empty)
11/04 23:21:55 yC1 key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK
ATS0=0Q0&D3&C1 OK
11/04 23:21:55 yC1 key: 'force-init-chat', type=2, flags=1, data=
\d\d\d\d+++\d\d\d
\dATQ0V1H0 OK
11/04 23:21:55 yC1 key: 'modem-check-time', type=0, flags=1, data=3600
11/04 23:21:55 yC1 key: 'rings', type=0, flags=2, data=2
11/04 23:21:55 yC1 key: 'get-cnd-chat', type=2, flags=0, data=(empty)
11/04 23:21:55 yC1 key: 'answer-chat', type=2, flags=1, data= ATA CONNECT
\c
11/04 23:21:55 yC1 key: 'answer-chat-timeout', type=0, flags=1, data=80
11/04 23:21:55 yC1 key: 'autobauding', type=3, flags=1, data=FALSE
11/04 23:21:55 yC1 key: 'ringback', type=3, flags=1, data=FALSE
11/04 23:21:55 yC1 key: 'ringback-time', type=0, flags=1, data=30
11/04 23:21:55 yC1 key: 'ignore-carrier', type=3, flags=1, data=FALSE
11/04 23:21:55 yC1 key: 'issue-file', type=1, flags=1, data=/etc/issue
11/04 23:21:55 yC1 key: 'prompt-waittime', type=0, flags=1, data=500
11/04 23:21:55 yC1 key: 'login-prompt', type=1, flags=1, data=@!login:
11/04 23:21:55 yC1 key: 'login-time', type=0, flags=1, data=240
11/04 23:21:55 yC1 key: 'fido-send-emsi', type=3, flags=1, data=TRUE
11/04 23:21:55 yC1 key: 'fax-id', type=1, flags=3, data=49 89 xxxxxxxx
11/04 23:21:55 yC1 key: 'fax-server-file', type=1, flags=0, data=(empty)
11/04 23:21:55 yC1 key: 'diskspace', type=0, flags=1, data=1024
11/04 23:21:55 yC1 key: 'notify', type=1, flags=1, data=faxadmin
11/04 23:21:55 yC1 key: 'fax-owner', type=1, flags=1, data=uucp
11/04 23:21:55 yC1 key: 'fax-group', type=1, flags=0, data=(empty)
11/04 23:21:55 yC1 key: 'fax-mode', type=0, flags=1, data=432
11/04 23:21:55 yC1 key: 'debug', type=0, flags=2, data=5
11/04 23:21:55 yC1 key: 'statistics-chat', type=2, flags=0, data=(empty)
11/04 23:21:55 yC1 key: 'statistics-file', type=1, flags=0, data=(empty)
11/04 23:21:55 yC1 key: 'gettydefs', type=1, flags=1, data=n
11/04 23:21:55 yC1 key: 'term', type=1, flags=0, data=(empty)
11/04 23:21:55 yC1 check for lockfiles
11/04 23:21:55 yC1 checklock: no active process has lock, will remove
11/04 23:21:55 yC1 locking the line
11/04 23:21:55 yC1 makelock(ttyC1) called
11/04 23:21:55 yC1 do_makelock: lock='/var/lock/LCK..ttyC1'
11/04 23:21:55 yC1 lock made
11/04 23:21:55 yC1 tio_get_rs232_lines: status: RTS CTS DTR
11/04 23:21:55 yC1 WARNING: DSR is off - modem turned off or bad cable?
11/04 23:21:55 yC1 lowering DTR to reset Modem
11/04 23:21:56 yC1 tss: set speed to 57600 (10001)
11/04 23:21:56 yC1 tio_set_flow_control( HARD )
11/04 23:21:56 yC1 waiting for line to clear (VTIME), read:
11/04 23:21:56 yC1 send: \dATQ0V1H0[0d]
11/04 23:21:56 yC1 waiting for ``OK''
11/04 23:21:56 yC1 got: ATQ0V1H0[0d][0d][0a]OK ** found **
11/04 23:21:56 yC1 send: ATS0=0Q0&D3&C1[0d]
11/04 23:21:56 yC1 waiting for ``OK''
11/04 23:21:56 yC1 got: [0d][0a]ATS0=0Q0&D3&C1[0d][0d][0a]OK ** found **
11/04 23:21:56 yC1 waiting for line to clear (VTIME), read: [0d][0a]
11/04 23:21:57 yC1 removing lock file
11/04 23:21:57 yC1 waiting...
11/04 23:22:05 yC1 checking lockfiles, locking the line
11/04 23:22:05 yC1 makelock(ttyC1) called
11/04 23:22:05 yC1 do_makelock: lock='/var/lock/LCK..ttyC1'
11/04 23:22:05 yC1 lock made
11/04 23:22:05 yC1 waiting for ``RING''
11/04 23:22:05 yC1 got: [0d][0a]RING ** found **
11/04 23:22:05 yC1 waiting for ``RING''
11/04 23:22:05 yC1 got: [0d][0a][0d][0a]RING ** found **
11/04 23:22:11 yC1 send: ATA[0d]
11/04 23:22:11 yC1 waiting for ``CONNECT''
11/04 23:22:11 yC1 got: [0d][0a]
11/04 23:23:31 yC1 timeout in chat script, waiting for `CONNECT'
11/04 23:23:31 ##### failed timeout dev=ttyC1, pid=19374, caller='none',
conn='', name=''
11/04 23:23:31 yC1 removing lock file
--
11/04 23:23:31 yC1 mgetty: experimental test release 1.1.14-Apr02
11/04 23:23:31 yC1 mgetty.c compiled at Jun 27 1998, 09:12:51
11/04 23:23:31 yC1 user id: 0, parent pid: 1
11/04 23:23:31 yC1 reading configuration data for port 'ttyC1'
11/04 23:23:31 yC1 reading /usr/local/etc/mgetty+sendfax/mgetty.config...
11/04 23:23:31 yC1 conf lib: read: 'debug 4'
11/04 23:23:31 yC1 conf lib: read: 'fax-id 49 89 xxxxxxxx'
11/04 23:23:31 yC1 conf lib: read: 'speed 38400'
11/04 23:23:31 yC1 key: 'speed', type=0, flags=2, data=57600
11/04 23:23:31 yC1 key: 'switchbd', type=0, flags=1, data=0
11/04 23:23:31 yC1 key: 'direct', type=3, flags=1, data=FALSE
11/04 23:23:31 yC1 key: 'blocking', type=3, flags=1, data=FALSE
11/04 23:23:31 yC1 key: 'port-owner', type=1, flags=1, data=uucp
11/04 23:23:31 yC1 key: 'port-group', type=1, flags=1, data=modem
11/04 23:23:31 yC1 key: 'port-mode', type=0, flags=1, data=432
11/04 23:23:31 yC1 key: 'toggle-dtr', type=3, flags=1, data=TRUE
11/04 23:23:31 yC1 key: 'toggle-dtr-waittime', type=0, flags=1, data=500
11/04 23:23:31 yC1 key: 'data-only', type=3, flags=2, data=TRUE
11/04 23:23:31 yC1 key: 'fax-only', type=3, flags=1, data=FALSE
11/04 23:23:31 yC1 key: 'modem-type', type=1, flags=1, data=auto
11/04 23:23:31 yC1 key: 'modem-quirks', type=0, flags=0, data=(empty)
11/04 23:23:31 yC1 key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK
ATS0=0Q0&D3&C1 OK
11/04 23:23:31 yC1 key: 'force-init-chat', type=2, flags=1, data=
\d\d\d\d+++\d\d\d
\dATQ0V1H0 OK
11/04 23:23:31 yC1 key: 'modem-check-time', type=0, flags=1, data=3600
11/04 23:23:31 yC1 key: 'rings', type=0, flags=2, data=2
11/04 23:23:31 yC1 key: 'get-cnd-chat', type=2, flags=0, data=(empty)
11/04 23:23:31 yC1 key: 'answer-chat', type=2, flags=1, data= ATA CONNECT
\c
11/04 23:23:31 yC1 key: 'answer-chat-timeout', type=0, flags=1, data=80
11/04 23:23:31 yC1 key: 'autobauding', type=3, flags=1, data=FALSE
11/04 23:23:31 yC1 key: 'ringback', type=3, flags=1, data=FALSE
11/04 23:23:31 yC1 key: 'ringback-time', type=0, flags=1, data=30
11/04 23:23:31 yC1 key: 'ignore-carrier', type=3, flags=1, data=FALSE
11/04 23:23:31 yC1 key: 'issue-file', type=1, flags=1, data=/etc/issue
11/04 23:23:31 yC1 key: 'prompt-waittime', type=0, flags=1, data=500
11/04 23:23:31 yC1 key: 'login-prompt', type=1, flags=1, data=@!login:
11/04 23:23:31 yC1 key: 'login-time', type=0, flags=1, data=240
11/04 23:23:31 yC1 key: 'fido-send-emsi', type=3, flags=1, data=TRUE
11/04 23:23:31 yC1 key: 'fax-id', type=1, flags=3, data=49 89 xxxxxxxx
11/04 23:23:31 yC1 key: 'fax-server-file', type=1, flags=0, data=(empty)
11/04 23:23:31 yC1 key: 'diskspace', type=0, flags=1, data=1024
11/04 23:23:31 yC1 key: 'notify', type=1, flags=1, data=faxadmin
11/04 23:23:31 yC1 key: 'fax-owner', type=1, flags=1, data=uucp
11/04 23:23:31 yC1 key: 'fax-group', type=1, flags=0, data=(empty)
11/04 23:23:31 yC1 key: 'fax-mode', type=0, flags=1, data=432
11/04 23:23:31 yC1 key: 'debug', type=0, flags=2, data=5
11/04 23:23:31 yC1 key: 'statistics-chat', type=2, flags=0, data=(empty)
11/04 23:23:31 yC1 key: 'statistics-file', type=1, flags=0, data=(empty)
11/04 23:23:31 yC1 key: 'gettydefs', type=1, flags=1, data=n
11/04 23:23:31 yC1 key: 'term', type=1, flags=0, data=(empty)
11/04 23:23:31 yC1 check for lockfiles
11/04 23:23:31 yC1 checklock: stat failed, no file
11/04 23:23:31 yC1 locking the line
11/04 23:23:31 yC1 makelock(ttyC1) called
11/04 23:23:31 yC1 do_makelock: lock='/var/lock/LCK..ttyC1'
11/04 23:23:31 yC1 lock made
11/04 23:23:31 yC1 tio_get_rs232_lines: status: RTS CTS DTR
11/04 23:23:31 yC1 WARNING: DSR is off - modem turned off or bad cable?
11/04 23:23:31 yC1 lowering DTR to reset Modem
11/04 23:23:32 yC1 tss: set speed to 57600 (10001)
11/04 23:23:32 yC1 tio_set_flow_control( HARD )
11/04 23:23:32 yC1 waiting for line to clear (VTIME), read:
11/04 23:23:32 yC1 send: \dATQ0V1H0[0d]
11/04 23:23:32 yC1 waiting for ``OK''
11/04 23:23:32 yC1 got:
11/04 23:23:52 yC1 timeout in chat script, waiting for `OK'
11/04 23:23:52 yC1 init chat timed out, trying force-init-chat
11/04 23:23:53 yC1 send: \d[10][03]\d\d\d+++\d\d\d[0d]\dATQ0V1H0[0d]
11/04 23:23:57 yC1 waiting for ``OK''
11/04 23:23:57 yC1 got:
11/04 23:24:17 yC1 timeout in chat script, waiting for `OK'
11/04 23:24:17 yC1 init chat failed, exiting...: Interrupted system call
11/04 23:24:17 ##### failed in mg_init_data, dev=ttyC1, pid=19414
11/04 23:24:17 yC1 removing lock file
--
11/04 23:24:17 yC1 mgetty: experimental test release 1.1.14-Apr02
11/04 23:24:17 yC1 mgetty.c compiled at Jun 27 1998, 09:12:51
11/04 23:24:17 yC1 user id: 0, parent pid: 1
11/04 23:24:17 yC1 reading configuration data for port 'ttyC1'
11/04 23:24:17 yC1 reading /usr/local/etc/mgetty+sendfax/mgetty.config...
11/04 23:24:17 yC1 conf lib: read: 'debug 4'
11/04 23:24:17 yC1 conf lib: read: 'fax-id 49 89 xxxxxxxx'
11/04 23:24:17 yC1 conf lib: read: 'speed 38400'
11/04 23:24:17 yC1 key: 'speed', type=0, flags=2, data=57600
11/04 23:24:17 yC1 key: 'switchbd', type=0, flags=1, data=0
11/04 23:24:17 yC1 key: 'direct', type=3, flags=1, data=FALSE
11/04 23:24:17 yC1 key: 'blocking', type=3, flags=1, data=FALSE
11/04 23:24:17 yC1 key: 'port-owner', type=1, flags=1, data=uucp
11/04 23:24:17 yC1 key: 'port-group', type=1, flags=1, data=modem
11/04 23:24:17 yC1 key: 'port-mode', type=0, flags=1, data=432
11/04 23:24:17 yC1 key: 'toggle-dtr', type=3, flags=1, data=TRUE
11/04 23:24:17 yC1 key: 'toggle-dtr-waittime', type=0, flags=1, data=500
11/04 23:24:17 yC1 key: 'data-only', type=3, flags=2, data=TRUE
11/04 23:24:17 yC1 key: 'fax-only', type=3, flags=1, data=FALSE
11/04 23:24:17 yC1 key: 'modem-type', type=1, flags=1, data=auto
-greg