mgetty not logging (was: Fax receive times out))

Gert Doering (gert@greenie.muc.de)
Tue, 8 Sep 1998 15:46:41 +0200


Hi,

Jeremy Malcolm wrote:
> > Glad to hear that!  Have fun with it.
> 
> Um, I spoke too soon I'm afraid.  Yes, receiving faxes is now working
> fine... but dialing in and establishing a PPP connection is now broken!
> When I try dialling in using Windows NT, I now get an error, "TCP/IP CP
> reported error 738: The server did not assign an address."  If I
> specifically tell Windows NT my static IP address then it does not give me
> the error 738, but it gives me a whole heap of CRC errors while I'm
> connected and no packets are passed.

Hmmm.  Strange.

> PPP was working fine until I added "switchbd 19200" to my mgetty.config
> (yes, this was the problem that was preventing me from receiving faxes... I
> could have sworn I tried adding that line before, but perhaps I hadn't
> reloaded mgetty's config file).

Switchbd 19200 is only executed in the fax receive path.  Shouldn't change
the data path at all.

> Here is the logfile, if it gives anyone any obvious clues I would much
> appreciate some advice...
> 
> 09/09 03:19:49 yS0  mgetty: experimental test release 1.1.14-Apr02
[..]
> 09/09 03:23:16 yS0  send: ATA[0d]
> 09/09 03:23:16 yS0  waiting for ``CONNECT''
> 09/09 03:23:16 yS0   got: [0d][0a]ATA[0d][0d][0a]DATA[0d][0a][0d][0a]CONNECT
> **
> found **
> 09/09 03:23:33 yS0  send:
> 09/09 03:23:33 yS0  waiting for ``_''
> 09/09 03:23:33 yS0   got:  57600[0d][0a] ** found **

Looks good, so far (hmmm, it doesn't say anything about error control -
maybe that's the problem, maybe the modem is only set up to not report
anything).

> 09/09 03:23:33 yS0   waiting for line to clear (VTIME), read:
> ~[ff]}#[c0]!}!} }
> }4}"}&} } } } }%}&} } ?J}'}"}(}"[b5][f5]~
> 09/09 03:23:33 yS0   utmp + wtmp entry made
> 09/09 03:23:33 yS0   tio_set_flow_control( HARD )
> 09/09 03:23:33 yS0   print welcome banner (/etc/issue)
> 09/09 03:23:33 yS0   getlogname (FIDO AUTO_PPP), read:~[ff]}#[c0]!

AutoPPP detected.

> 09/09 03:23:35 yS0   input finished with '\r', setting ICRNL ONLCR
> 09/09 03:23:35 yS0   tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
> 09/09 03:23:35 yS0   match: user='/AutoPPP/', key=''
> 09/09 03:23:35 yS0   match: user='/AutoPPP/', key=''
> 09/09 03:23:35 yS0   match: user='/AutoPPP/', key='/AutoPPP/'*** hit!
> 09/09 03:23:35 yS0   login: utmp entry: a_ppp
> 09/09 03:23:35 yS0   utmp + wtmp entry made
> 09/09 03:23:35 yS0   calling login: cmd='/usr/sbin/pppd', argv[]='pppd
> auth -chap +pap login'
> 09/09 03:23:35 ##### data dev=ttyS0, pid=2268, caller='none', conn='57600',
> name='', cmd='/usr/sbin/pppd', user='/AutoPPP/'

And handed over to pppd.  From the mgetty side, things look fine.

[..]
> also sometimes it seems to end differently, it goes as follows:
> 
> 09/09 03:28:07 yS0   got:  57600[0d][0a] ** found **
> 09/09 03:28:07 yS0   waiting for line to clear (VTIME), read:
> 09/09 03:28:07 yS0   utmp + wtmp entry made
> 09/09 03:28:08 yS0   tio_set_flow_control( HARD )
> 09/09 03:28:08 yS0   print welcome banner (/etc/issue)
> 09/09 03:28:08 yS0   getlogname (FIDO AUTO_PPP), read:terminus[0d]
> 09/09 03:28:11 yS0   input finished with '\r', setting ICRNL ONLCR
> 09/09 03:28:11 yS0   tio_get_rs232_lines: status: RTS CTS DSR DTR DCD

That's a normal user login, with no PPP involved.

You might want to look at pppd's log file, which should tell you about its
negotiations with WinNT.

gert

-- 
USENET is *not* the non-clickable part of WWW!
                                                           //www.muc.de/~gert/
Gert Doering - Munich, Germany                             gert@greenie.muc.de
fax: +49-89-35655025                        gert.doering@physik.tu-muenchen.de