Juergen Fiedler <[EMAIL PROTECTED]> wrote:
 
> On Fri, 26 Feb 1999, Robert Baines wrote:
> 
> > it *will* make log files. enable mgetty, get someone to phone you and wait
> > while it fails to connect. then run a 'find' if you need to, to get the
> > log file. if you don't have a log file it means mgetty did not go into
> > action.
> 
> OK... I found the log file and took the liberty to attach it. I hope
> that's OK - it's not too huge. Unfortunately, it could quite as well be
> written in Chinese - I just can't make any sense of it.
> The way I obtained it was thus: First, I deleted the old log file. It was
> getting a bit big. Then I tried to dial in from another computer using
> HyperTerminal again. A while after HyperTerminal had timed out, I turned
> the modem in the Linux machine off - it was still happily screeching away.
> And the attached log file is all I got out of that.
> Can you make any sense of the log?
> 

--
02/26 01:32:27 yS1  mgetty: official release 1.0.0
02/26 01:32:27 yS1   mgetty.c compiled at Feb 23 1999, 17:49:50
02/26 01:32:27 yS1   reading configuration data for port 'ttyS1'
02/26 01:32:27 yS1   conf lib: read: 'debug 4'
02/26 01:32:27 yS1   conf lib: read: 'fax-id 49 89 xxxxxxxx'
02/26 01:32:27 yS1   conf lib: read: 'speed 38400'
02/26 01:32:27 yS1   key: 'speed', type=0, flags=2, data=57600
02/26 01:32:27 yS1   key: 'switchbd', type=0, flags=1, data=0
02/26 01:32:27 yS1   key: 'direct', type=3, flags=1, data=FALSE
02/26 01:32:27 yS1   key: 'blocking', type=3, flags=1, data=FALSE
02/26 01:32:27 yS1   key: 'port-owner', type=1, flags=1, data=uucp
02/26 01:32:27 yS1   key: 'port-group', type=1, flags=1, data=modem

do you have a modem group??

02/26 01:32:27 yS1   key: 'port-mode', type=0, flags=1, data=432
02/26 01:32:27 yS1   key: 'toggle-dtr', type=3, flags=1, data=TRUE
02/26 01:32:27 yS1   key: 'toggle-dtr-waittime', type=0, flags=1, data=500
02/26 01:32:27 yS1   key: 'data-only', type=3, flags=1, data=FALSE
02/26 01:32:27 yS1   key: 'fax-only', type=3, flags=1, data=FALSE
02/26 01:32:27 yS1   key: 'modem-type', type=1, flags=1, data=auto
02/26 01:32:27 yS1   key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK 
ATS0=0Q0&D3&C1 OK 
02/26 01:32:27 yS1   key: 'force-init-chat', type=2, flags=1, data= 
\d\d\d\d+++\d\d\d
\dATQ0V1H0 OK 
02/26 01:32:27 yS1   key: 'modem-check-time', type=0, flags=1, data=3600
02/26 01:32:27 yS1   key: 'rings', type=0, flags=2, data=2
02/26 01:32:27 yS1   key: 'get-cnd-chat', type=2, flags=0, data=(empty)
02/26 01:32:27 yS1   key: 'answer-chat', type=2, flags=1, data= ATA CONNECT \c 
 
02/26 01:32:27 yS1   key: 'answer-chat-timeout', type=0, flags=1, data=80
02/26 01:32:27 yS1   key: 'autobauding', type=3, flags=1, data=FALSE
02/26 01:32:27 yS1   key: 'ringback', type=3, flags=1, data=FALSE
02/26 01:32:27 yS1   key: 'ringback-time', type=0, flags=1, data=30
02/26 01:32:27 yS1   key: 'ignore-carrier', type=3, flags=1, data=FALSE
02/26 01:32:27 yS1   key: 'issue-file', type=1, flags=1, data=/etc/issue
02/26 01:32:27 yS1   key: 'prompt-waittime', type=0, flags=1, data=500
02/26 01:32:27 yS1   key: 'login-prompt', type=1, flags=1, data=@!login: 
02/26 01:32:27 yS1   key: 'login-time', type=0, flags=1, data=240
02/26 01:32:27 yS1   key: 'fido-send-emsi', type=3, flags=1, data=TRUE
02/26 01:32:27 yS1   key: 'fax-id', type=1, flags=3, data=49 89 xxxxxxxx
02/26 01:32:27 yS1   key: 'fax-server-file', type=1, flags=0, data=(empty)
02/26 01:32:27 yS1   key: 'diskspace', type=0, flags=1, data=1024
02/26 01:32:27 yS1   key: 'notify', type=1, flags=1, data=faxadmin
02/26 01:32:27 yS1   key: 'fax-owner', type=1, flags=1, data=uucp
02/26 01:32:27 yS1   key: 'fax-group', type=1, flags=0, data=(empty)
02/26 01:32:27 yS1   key: 'fax-mode', type=0, flags=1, data=432
02/26 01:32:27 yS1   key: 'debug', type=0, flags=2, data=5
02/26 01:32:27 yS1   key: 'statistics-chat', type=2, flags=0, data=(empty)
02/26 01:32:27 yS1   key: 'statistics-file', type=1, flags=0, data=(empty)
02/26 01:32:27 yS1   key: 'gettydefs', type=1, flags=1, data=n
02/26 01:32:27 yS1   key: 'term', type=1, flags=0, data=(empty)

some of this stuff is modem specific, and you will have to check it agrees
with your modem.

02/26 01:32:27 yS1  check for lockfiles
02/26 01:32:27 yS1   checklock: stat failed, no file
02/26 01:32:27 yS1  locking the line
02/26 01:32:27 yS1   makelock(ttyS1) called
02/26 01:32:27 yS1   do_makelock: lock='/var/lock/LCK..ttyS1'
02/26 01:32:27 yS1   lock made

this is ok, the lock file is made while the modem is set up

02/26 01:32:27 yS1  can't get group 'modem': No such file or directory

this needs looking at. its probably part of the problem.

02/26 01:32:28 yS1   tio_get_rs232_lines: status: RTS CTS DSR DTR
02/26 01:32:28 yS1  lowering DTR to reset Modem
02/26 01:32:28 yS1   tss: set speed to 57600 (10001)
02/26 01:32:28 yS1   tio_set_flow_control( HARD )
02/26 01:32:28 yS1   waiting for line to clear (VTIME), read: 
02/26 01:32:29 yS1  send: \dATQ0V1H0[0d]
02/26 01:32:29 yS1  waiting for ``OK''
02/26 01:32:29 yS1   got: ATQ0V1H0[0d][0d][0a]OK ** found **
02/26 01:32:29 yS1  send: ATS0=0Q0&D3&C1[0d]
02/26 01:32:29 yS1  waiting for ``OK''
02/26 01:32:29 yS1   got: [0d][0a]ATS0=0Q0&D3&C1[0d][0d][0a]OK ** found **
02/26 01:32:29 yS1  mdm_send: 'AT+FCLASS=2.0'
02/26 01:32:29 yS1   mdm_command: string 'AT+FCLASS=2.0'
02/26 01:32:30 yS1   mdm_command: string 'OK' -> OK
02/26 01:32:30 yS1  mdm_send: 'AT+FAA=1;+FCR=1'
02/26 01:32:30 yS1   mdm_command: string 'AT+FAA=1;+FCR=1'
02/26 01:32:30 yS1   mdm_command: string 'OK' -> OK
02/26 01:32:30 yS1  mdm_send: 'AT+FBO=1'
02/26 01:32:30 yS1   mdm_command: string 'AT+FBO=1'
02/26 01:32:30 yS1   mdm_command: string 'OK' -> OK
02/26 01:32:30 yS1  mdm_send: 'AT+FNR=1,1,1,0'
02/26 01:32:30 yS1   mdm_command: string 'AT+FNR=1,1,1,0'
02/26 01:32:30 yS1   mdm_command: string 'OK' -> OK
02/26 01:32:30 yS1  mdm_send: 'AT+FLI="49 89 xxxxxxxx"'
02/26 01:32:30 yS1   mdm_command: string 'AT+FLI="49 89 xxxxxxxx"'
02/26 01:32:30 yS1   mdm_command: string 'OK' -> OK
02/26 01:32:30 yS1  mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
02/26 01:32:30 yS1   mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
02/26 01:32:30 yS1   mdm_command: string 'OK' -> OK
02/26 01:32:30 yS1   waiting for line to clear (VTIME), read: 
02/26 01:32:31 yS1   removing lock file

this looks ok, you should check the modem commands carefully to be
sure.

02/26 01:32:31 yS1  waiting...

now the lock file has been removed and mgetty is waiting for the port to
interrupt because the phone has rung.

02/26 01:43:18 yS1   checking lockfiles, locking the line

now the phone has rung, so mgetty tries to lock the modem.

02/26 01:43:18 yS1   makelock(ttyS1) called
02/26 01:43:18 yS1   do_makelock: lock='/var/lock/LCK..ttyS1'
02/26 01:43:18 yS1  lock not made: lock file exists
02/26 01:43:18 yS1   lock file exists (dialout)!

but it can't because there is a lockfile already. it might be wrong about
dialout. i think it just assumes that.

02/26 01:43:18 yS1   lockfile found, pid=306
02/26 01:43:18 yS1   utmp + wtmp entry made
02/26 01:43:18 yS1   lockfile found, pid=306

it keeps checking every ten seconds, but there is still a lockfile. you
should check what program has pid 306. it owns the lockfile. it should not
be running. kill it and see what happens.

02/26 01:43:28 yS1   lockfile found, pid=306
02/26 01:43:38 yS1   lockfile found, pid=306
02/26 01:43:48 yS1   lockfile found, pid=306


here is some of my log file. it is a different modem, a different version
of mgetty, etc, but may be useful:

--
02/26 18:21:26 yS2  mgetty: experimental test release 1.1.14-Apr02
02/26 18:21:26 yS2   mgetty.c compiled at Apr 24 1998, 18:10:42
02/26 18:21:26 yS2   user id: 0, parent pid: 1

run as root

02/26 18:21:26 yS2   reading configuration data for port 'ttyS2'
02/26 18:21:26 yS2   reading /etc/mgetty+sendfax/mgetty.config...
02/26 18:21:26 yS2   key: 'speed', type=0, flags=2, data=115200
02/26 18:21:26 yS2   key: 'switchbd', type=0, flags=1, data=0
02/26 18:21:26 yS2   key: 'direct', type=3, flags=1, data=FALSE
02/26 18:21:26 yS2   key: 'blocking', type=3, flags=1, data=FALSE
02/26 18:21:26 yS2   key: 'port-owner', type=1, flags=1, data=uucp
02/26 18:21:26 yS2   key: 'port-group', type=1, flags=1, data=uucp
02/26 18:21:26 yS2   key: 'port-mode', type=0, flags=1, data=432
02/26 18:21:26 yS2   key: 'toggle-dtr', type=3, flags=1, data=TRUE
02/26 18:21:26 yS2   key: 'toggle-dtr-waittime', type=0, flags=1, data=500
02/26 18:21:26 yS2   key: 'data-only', type=3, flags=1, data=FALSE
02/26 18:21:26 yS2   key: 'fax-only', type=3, flags=1, data=FALSE
02/26 18:21:26 yS2   key: 'modem-type', type=1, flags=2, data=1
02/26 18:21:26 yS2   key: 'modem-quirks', type=0, flags=0, data=(empty)
02/26 18:21:26 yS2   key: 'init-chat', type=2, flags=1, data= \dATQ0V1H0 OK 
ATS0=0Q0&D3&C1 OK 
02/26 18:21:26 yS2   key: 'force-init-chat', type=2, flags=1, data= 
\d\d\d\d+++\d\d\d
\dATQ0V1H0 OK 
02/26 18:21:26 yS2   key: 'modem-check-time', type=0, flags=1, data=3600
02/26 18:21:26 yS2   key: 'rings', type=0, flags=2, data=2
02/26 18:21:26 yS2   key: 'get-cnd-chat', type=2, flags=0, data=(empty)
02/26 18:21:26 yS2   key: 'answer-chat', type=2, flags=1, data= ATA CONNECT \c 
 
02/26 18:21:26 yS2   key: 'answer-chat-timeout', type=0, flags=1, data=80
02/26 18:21:26 yS2   key: 'autobauding', type=3, flags=1, data=FALSE
02/26 18:21:26 yS2   key: 'ringback', type=3, flags=1, data=FALSE
02/26 18:21:26 yS2   key: 'ringback-time', type=0, flags=1, data=30
02/26 18:21:26 yS2   key: 'ignore-carrier', type=3, flags=1, data=FALSE
02/26 18:21:26 yS2   key: 'issue-file', type=1, flags=1, data=/etc/issue
02/26 18:21:26 yS2   key: 'prompt-waittime', type=0, flags=1, data=500
02/26 18:21:26 yS2   key: 'login-prompt', type=1, flags=1, data=login: 
02/26 18:21:26 yS2   key: 'login-time', type=0, flags=1, data=240
02/26 18:21:26 yS2   key: 'fido-send-emsi', type=3, flags=1, data=TRUE
02/26 18:21:26 yS2   key: 'fax-id', type=1, flags=1, data=49 89 xxxxxxxx
02/26 18:21:26 yS2   key: 'fax-server-file', type=1, flags=0, data=(empty)
02/26 18:21:26 yS2   key: 'diskspace', type=0, flags=1, data=1024
02/26 18:21:26 yS2   key: 'notify', type=1, flags=1, data=faxadmin
02/26 18:21:26 yS2   key: 'fax-owner', type=1, flags=1, data=uucp
02/26 18:21:26 yS2   key: 'fax-group', type=1, flags=0, data=(empty)
02/26 18:21:26 yS2   key: 'fax-mode', type=0, flags=1, data=432
02/26 18:21:26 yS2   key: 'debug', type=0, flags=2, data=5
02/26 18:21:26 yS2   key: 'statistics-chat', type=2, flags=0, data=(empty)
02/26 18:21:26 yS2   key: 'statistics-file', type=1, flags=0, data=(empty)
02/26 18:21:26 yS2   key: 'gettydefs', type=1, flags=1, data=n
02/26 18:21:26 yS2   key: 'term', type=1, flags=0, data=(empty)

now it locks and checks out the modem:

02/26 18:21:26 yS2  check for lockfiles
02/26 18:21:26 yS2   checklock: stat failed, no file
02/26 18:21:26 yS2  locking the line
02/26 18:21:26 yS2   makelock(ttyS2) called
02/26 18:21:26 yS2   do_makelock: lock='/var/lock/LCK..ttyS2'
02/26 18:21:26 yS2   lock made
02/26 18:21:27 yS2   tio_get_rs232_lines: status: RTS CTS DSR DTR
02/26 18:21:27 yS2  lowering DTR to reset Modem
02/26 18:21:27 yS2   tss: set speed to 115200 (10002)
02/26 18:21:27 yS2   tio_set_flow_control( HARD )
02/26 18:21:27 yS2   waiting for line to clear (VTIME), read: 
02/26 18:21:27 yS2  send: \dATQ0V1H0[0d]
02/26 18:21:28 yS2  waiting for ``OK''
02/26 18:21:28 yS2   got: ATQ0V1H0[0d][0d][0a]OK ** found **
02/26 18:21:28 yS2  send: ATS0=0Q0&D3&C1[0d]
02/26 18:21:28 yS2  waiting for ``OK''
02/26 18:21:28 yS2   got: [0d][0a]ATS0=0Q0&D3&C1[0d][0d][0a]OK ** found **
02/26 18:21:28 yS2  mdm_send: 'ATI'
02/26 18:21:28 yS2   mdm_gis: string 1: '33600'
02/26 18:21:28 yS2   mdm_identify: string '33600'
02/26 18:21:28 yS2  Generic Rockwell modem (33600)
02/26 18:21:28 yS2  mdm_send: 'ATI3'
02/26 18:21:28 yS2   mdm_gis: string 1: 'V2.061-V34_ACF_DP1'
02/26 18:21:28 yS2  mdm_send: 'ATI4'
02/26 18:21:28 yS2   mdm_gis: string 1: 'AT-336PCR FOR UNITED KINGDOM V2.061 05/05/97'
02/26 18:21:28 yS2  additional info: 'AT-336PCR FOR UNITED KINGDOM V2.061 05/05/97'
02/26 18:21:28 yS2  modem quirks: 0004

it tries to see the fax capabilities:

02/26 18:21:28 yS2  mdm_send: 'AT+FCLASS=2'
02/26 18:21:28 yS2   mdm_command: string 'AT+FCLASS=2'
02/26 18:21:28 yS2   mdm_command: string 'ERROR' -> ERROR
02/26 18:21:28 yS2  mdm_send: 'AT+FCLASS=2.0'
02/26 18:21:28 yS2   mdm_command: string 'AT+FCLASS=2.0'
02/26 18:21:28 yS2   mdm_command: string 'ERROR' -> ERROR
02/26 18:21:28 yS2  mdm_send: 'AT+FCLASS=2'
02/26 18:21:28 yS2   mdm_command: string 'AT+FCLASS=2'
02/26 18:21:28 yS2   mdm_command: string 'ERROR' -> ERROR

but it's a crap modem and will only do class 1 fax.

02/26 18:21:28 yS2   no class 2/2.0 faxmodem, no faxing available
02/26 18:21:28 yS2   waiting for line to clear (VTIME), read: 
02/26 18:21:29 yS2   removing lock file
02/26 18:21:29 yS2  waiting...

waiting for port interrupt with lock removed:

02/26 18:22:17 yS2   checking lockfiles, locking the line

the port has interrupted, so locking it:

02/26 18:22:17 yS2   makelock(ttyS2) called
02/26 18:22:17 yS2   do_makelock: lock='/var/lock/LCK..ttyS2'
02/26 18:22:17 yS2  lock not made: lock file exists (pid=408)
02/26 18:22:17 yS2   lock file exists (dialout)!
02/26 18:22:17 yS2   lockfile found, pid=408
02/26 18:22:17 yS2   utmp + wtmp entry made
02/26 18:22:17 yS2   lockfile found, pid=408
02/26 18:22:27 yS2   lockfile found, pid=408
02/26 18:22:37 yS2   lockfile found, pid=408
02/26 18:22:47 yS2   lockfile found, pid=408
02/26 18:22:58 yS2   lockfile found, pid=408
02/26 18:23:08 yS2   lockfile found, pid=408
02/26 18:23:18 yS2   lockfile found, pid=408
02/26 18:23:28 yS2   lockfile found, pid=408
02/26 18:23:38 yS2   lockfile found, pid=408
02/26 18:23:48 yS2   lockfile found, pid=408
02/26 18:23:58 yS2   lockfile found, pid=408
02/26 18:24:08 yS2   lockfile found, pid=408
02/26 18:24:18 yS2   lockfile found, pid=408
02/26 18:24:28 yS2   lockfile found, pid=408
02/26 18:24:38 yS2   lockfile found, pid=408
02/26 18:24:48 yS2   lockfile found, pid=408
02/26 18:24:58 yS2   lockfile found, pid=408
02/26 18:25:08 yS2   lockfile found, pid=408
02/26 18:25:18 yS2   lockfile found, pid=408
02/26 18:25:28 yS2   lockfile found, pid=408
02/26 18:25:38 yS2   lockfile found, pid=408
02/26 18:25:48 yS2   lockfile found, pid=408
02/26 18:25:58 yS2   lockfile found, pid=408
02/26 18:26:08 yS2   lockfile found, pid=408
02/26 18:26:18 yS2   lockfile found, pid=408
02/26 18:26:28 yS2   lockfile found, pid=408
02/26 18:26:38 yS2   lockfile found, pid=408
02/26 18:26:48 yS2   checklock: stat failed, no file
02/26 18:26:53 yS2   checklock: stat failed, no file

but it was a mistake of some sort, so after a while it gives up.

now init has respawned it:

02/26 18:26:53 yS2  mgetty: experimental test release 1.1.14-Apr02
02/26 18:26:53 yS2   mgetty.c compiled at Apr 24 1998, 18:10:42
02/26 18:26:53 yS2   user id: 0, parent pid: 1
02/26 18:26:53 yS2   reading configuration data for port 'ttyS2'
02/26 18:26:53 yS2   reading /etc/mgetty+sendfax/mgetty.config...
02/26 18:26:53 yS2   key: 'speed', type=0, flags=2, data=115200

all that key and startup stuff is repeated....

02/26 18:26:55 yS2  waiting...

after an hour, it checks the modem again:

02/26 19:26:55 yS2  checking if modem is still alive
02/26 19:26:55 yS2   makelock(ttyS2) called
02/26 19:26:55 yS2   do_makelock: lock='/var/lock/LCK..ttyS2'
02/26 19:26:55 yS2   lock made
02/26 19:26:55 yS2  mdm_send: 'AT'
02/26 19:26:55 yS2   mdm_command: string 'AT'
02/26 19:26:55 yS2   mdm_command: string 'OK' -> OK
02/26 19:26:55 yS2   waiting for line to clear (VTIME), read: 
02/26 19:26:56 yS2   removing lock file
02/26 19:26:56 yS2  waiting...

then later on the port interrupts:

02/26 20:00:31 yS2   checking lockfiles, locking the line
02/26 20:00:31 yS2   makelock(ttyS2) called
02/26 20:00:31 yS2   do_makelock: lock='/var/lock/LCK..ttyS2'
02/26 20:00:31 yS2   lock made

it waits for two RINGs if the lock is successful:

02/26 20:00:31 yS2  waiting for ``RING''
02/26 20:00:31 yS2   got: [0d][0a]RING ** found **
02/26 20:00:31 yS2  waiting for ``RING''
02/26 20:00:31 yS2   got: [0d][0a][0d][0a]RING ** found **

it gets 2 rings so it tells the modem to answer the phone:

02/26 20:00:35 yS2  send: ATA[0d]
02/26 20:00:35 yS2  waiting for ``CONNECT''
02/26 20:00:35 yS2   got: [0d][0a]ATA[0d][0d][0a]NO CARRIER
02/26 20:01:28 yS2  found action string: ``NO CARRIER''
02/26 20:01:28 ##### failed A_FAIL dev=ttyS2, pid=700, caller='none', conn='', name=''

but theres no carrier because its my brother and he cant make right
noises:

02/26 20:01:28 yS2   removing lock file

so the lock file is removed, mgetty dies and is born again:

02/26 20:01:28 yS2  mgetty: experimental test release 1.1.14-Apr02
.....etc

02/26 20:01:30 yS2  waiting...
02/26 20:17:33 ##### failed dev=ttyS2, pid=835, got signal 15, exiting

then i telinit a new inittab and init kills it. the end.

robert

-
To unsubscribe from this list: send the line "unsubscribe linux-net" in
the body of a message to [EMAIL PROTECTED]

Reply via email to