From owner-freebsd-questions  Sat May 31 20:43:52 1997
Return-Path: <owner-questions>
Received: (from root@localhost)
          by hub.freebsd.org (8.8.5/8.8.5) id UAA03775
          for questions-outgoing; Sat, 31 May 1997 20:43:52 -0700 (PDT)
Received: from awfulhak.demon.co.uk (awfulhak.demon.co.uk [158.152.17.1])
          by hub.freebsd.org (8.8.5/8.8.5) with ESMTP id UAA03770
          for <freebsd-questions@FreeBSD.ORG>; Sat, 31 May 1997 20:43:46 -0700 (PDT)
Received: from awfulhak.demon.co.uk (localhost [127.0.0.1])
	by awfulhak.demon.co.uk (8.8.5/8.8.5) with ESMTP id EAA26950;
	Sun, 1 Jun 1997 04:40:33 +0100 (BST)
Message-Id: <199706010340.EAA26950@awfulhak.demon.co.uk>
X-Mailer: exmh version 1.6.9 8/22/96
To: brian@utell.co.uk
cc: robert@chalmers.com.au, freebsd-questions <freebsd-questions@FreeBSD.ORG>,
        brian@awfulhak.org
Subject: Re: Ordinary ppp dialing to provider woes 
In-reply-to: Your message of "Thu, 29 May 1997 15:18:49 BST."
             <199705291418.PAA04714@utell.co.uk> 
Mime-Version: 1.0
Content-Type: text/plain; charset=us-ascii
Date: Sun, 01 Jun 1997 04:40:33 +0100
From: Brian Somers <brian@awfulhak.org>
Sender: owner-questions@FreeBSD.ORG
X-Loop: FreeBSD.org
Precedence: bulk

> > > Hi again,
> > > Well, I'm getting closer...
> > [.....]
> > > Now, the log file shows;  ( and I knew some magic was involved in this)
> > > 
> > > magic is same!! 5ac0bac0, 5ac0bac0, 56d8
> > > 
> > [.....]
> > > and then it times out and hangs up!
> > > 
> > > So, does anyone know what I'm doing wrong now!!!
> > > sheezzz. 
> > 
> > I assume you're using -current.
> > 
> > I've seen this in my test environment recently.  I can't fathom
> > what's going on here.  The only thing that's changed recently
> > here is that ppp now uses proper random numbers (I have several
> > "entrophy" IRQs set up) to generate the magic number.  It doesn't
> > fail always - sometimes things are ok (~30% of the time).  This
> > is over a direct serial link from a -current box to a RELENG_2_2
> > box.  My ISP connection from the -current box has *never* seen
> > this problem.
> > 
> > I'll try undoing the random stuff in GenerateMagic() and see
> > if it helps........
> 
> And of course the client side now initiates hand-shaking by default.
> Can you try putting the line "set openmode passive" in your config
> and see if it helps ?

Well, I've pinned down the problem, but I still don't know what's
happening !  Here's my log:

06-01 04:01:19 [25324] LCP: state change Initial --> Closed
06-01 04:01:19 [25324] LCP: SendConfigReq
06-01 04:01:19 [25324]  ACFCOMP
06-01 04:01:19 [25324]  PROTOCOMP
06-01 04:01:19 [25324]  ACCMAP [6] 00000000
06-01 04:01:19 [25324]  MRU [4] 1800
06-01 04:01:19 [25324]  MAGICNUM [6] 00001234
06-01 04:01:19 [25324]  QUALPROTO (500)

Ok, I'm sending my first LCP.

06-01 04:01:19 [25324] WriteModem
06-01 04:01:19 [25324]  7e ff 7d 23 c0 21 7d 21 7d 21 7d 20 20 7d 28 7d
06-01 04:01:19 [25324]  22 7d 27 7d 22 7d 22 7d 26 7d 20 7d 20 7d 20 7d
06-01 04:01:19 [25324]  20 7d 21 7d 24 7d 27 7d 28 7d 25 7d 26 7d 20 7d
06-01 04:01:19 [25324]  20 7d 32 34 7d 24 7d 28 c0 25 7d 20 7d 20 7d 21
06-01 04:01:19 [25324]  f4 43 64 7e

That's the *real* data.

06-01 04:01:19 [25324] LCP: state change Closed --> Req-Sent
06-01 04:01:19 [25324] ReadFromModem
06-01 04:01:19 [25324]  55 73 65 72 20 50 72 6f 63 65 73 73 20 50 50 50
06-01 04:01:19 [25324]  2e 20 57 72 69 74 74 65 6e 20 62 79 20 54 6f 73
06-01 04:01:19 [25324]  68 69 68 61 72 75 20 4f 48 4e 4f 2e 0d 0d 0a

This actually says: "User Process PPP. Written by Toshiharu OHNO.\r\n"
-direct now switches off this rubbish.

06-01 04:01:19 [25324] ReadFromModem 
06-01 04:01:19 [25324]  55 73 69 6e 67 20 69 6e 74 65 72 66 61 63 65 3a
06-01 04:01:19 [25324]  20 74 75 6e 30 0d 0d 0a 7e ff 7d 23 c0 21 7d 21
06-01 04:01:19 [25324]  7d 21 7d 20 20 7d 28 7d 22 7d 27 7d 22 7d 22 7d
06-01 04:01:19 [25324]  26 7d 20 7d 20 7d 20 7d 20 7d 21 7d 24 7d 27 7d
06-01 04:01:19 [25324]  28 7d 25 7d 26 7d 20 7d 20 7d 32 34 7d 24 7d 28
06-01 04:01:19 [25324]  c0 25 7d 20 7d 20 7d 21 f4 43 64 7e

This actually says: "Using interface: tun0\r\n", followed by the LCP data
I wrote to the modem in the first place.

06-01 04:01:19 [25324] LCP: Received Configure Request (1) state = Req-Sent (6)
06-01 04:01:19 [25324]  ACFCOMP 
06-01 04:01:19 [25324]  PROTOCOMP
06-01 04:01:19 [25324]  ACCMAP 00000000
06-01 04:01:19 [25324]  MRU 1800 
06-01 04:01:19 [25324]  MAGICNUM 00001234 
06-01 04:01:19 [25324] Magic is same (00001234) 
06-01 04:01:19 [25324]  QUALPROTO proto: c025, interval: 5000ms
06-01 04:01:19 [25324] LCP:  SendConfigNak(Req-Sent)

Strangely enough !

I've added a NAK for the "same magic" error, as per the rfc and I've
removed all of the startup printf's in -direct mode.  I'll keep
trying to corner this.
-- 
Brian <brian@awfulhak.org>, <brian@freebsd.org>
      <http://www.awfulhak.org>
Don't _EVER_ lose your sense of humour....