Date: Thu, 13 Nov 1997 01:05:23 +0000 From: Brian Somers <brian@awfulhak.org> To: "Johan Granlund" <johang@mail.algonet.se> Cc: current@FreeBSD.ORG Subject: Re: ppp and ascend router problems Message-ID: <199711130105.BAA08951@awfulhak.demon.co.uk> In-Reply-To: Your message of "Wed, 12 Nov 1997 23:05:36 %2B0100." <199711122212.OAA14636@hub.freebsd.org>
next in thread | previous in thread | raw e-mail | index | archive | help
> Hi
> Once upon a (long) time (ago) i had ppp working, not any more. It has ben
> some traffic about ppp and i decided to get it working again.
> When trying from a 2.2-stable from around 1 month ago it works fine, but not
> from my current machine.
> My ISP has some sort of big Ascend router. WinNT and Win95 works.
>
> Sending ppp.log and hopes anyone have a clue why?
>
> >From ppp.conf:
>
> disable lqr
> deny lqr
> set openmode active
> disable pred1
> deny pred1
>
> >From ppp.log:
>
> Nov 12 22:34:58 phoenix ppp[621]: tun0: IPCP: Using trigger address
> 255.255.255.0
Why are you using this ? Change it to 0.0.0.0 (or remove it
altogether). It's the 4th arg to "set ifaddr". This isn't the
problem though, you're not getting as far as IPCP negotiation.
> Nov 12 22:34:58 phoenix ppp[621]: tun0: LCP: State change
> Initial --> Closed
> Nov 12 22:34:58 phoenix ppp[621]: tun0: LCP:
> LcpSendConfigReq
> Nov 12 22:34:58 phoenix ppp[621]: tun0: LCP: ACFCOMP
> Nov 12 22:34:58 phoenix ppp[621]: tun0: LCP: PROTOCOMP
> Nov 12 22:34:58 phoenix ppp[621]: tun0: LCP: ACCMAP [6] 00000000
> Nov 12 22:34:58 phoenix ppp[621]: tun0: LCP: MRU [4] 1500
> Nov 12 22:34:58 phoenix ppp[621]: tun0: LCP: MAGICNUM [6] 58270b8d
> Nov 12 22:34:58 phoenix ppp[621]: tun0: HDLC: HdlcOutput
> Nov 12 22:34:58 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 01 01 00 18 08 02
> 07 02 02 06 00 00
> Nov 12 22:34:58 phoenix ppp[621]: tun0: HDLC: 00 00 01 04
> 05 dc 05 06 58 27 0b 8d 5d c1
> Nov 12 22:34:58 phoenix ppp[621]: tun0: LCP: State change Closed --> Req-Sent
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: LcpSendConfigReq
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: ACFCOMP
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: PROTOCOMP
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: ACCMAP [6] 00000000
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: MRU [4] 1500
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: MAGICNUM [6] 58270b8d
> Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: HdlcOutput
> Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 01 02 00 18 08 02
> 07 02 02 06 00 00 Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: 00 00 01 04
> 05 dc 05 06 58 27 0b 8d 17 53
> Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: HdlcInput:
> Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 01 01 00 1f 01 04
> 05 f4 02 06 00 0a
> Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: 00 00 03 04
> c0 23 07 02 08 02 13 09 03 00 c0 7b
> Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: 5f d3 10 50 aa
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: Received Configure Request (1)
> state = Req-Sent (6)
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: MRU 1524
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: ACCMAP 000a0000
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: AUTHPROTO proto= c023
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: PROTOCOMP
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: ACFCOMP
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: ???[13]
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: SendConfigRej(Req-Sent)
> Nov 12 22:35:01 phoenix ppp[621]: tun0: LCP: ???[13]
ppp says it doesn't know what a [13] is.
> Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: HdlcOutput
> Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 04 01 00 0d 13 09
> 03 00 c0 7b 5f d3
> Nov 12 22:35:01 phoenix ppp[621]: tun0: HDLC: 10 09 96
> Nov 12 22:35:04 phoenix ppp[621]: tun0: LCP: LcpSendConfigReq
> Nov 12 22:35:04 phoenix ppp[621]: tun0: LCP: ACFCOMP
> Nov 12 22:35:04 phoenix ppp[621]: tun0: LCP: PROTOCOMP
> Nov 12 22:35:04 phoenix ppp[621]: tun0: LCP: ACCMAP [6] 00000000
> Nov 12 22:35:04 phoenix ppp[621]: tun0: LCP: MRU [4] 1500
> Nov 12 22:35:04 phoenix ppp[621]: tun0: LCP: MAGICNUM [6] 58270b8d
> Nov 12 22:35:04 phoenix ppp[621]: tun0: HDLC: HdlcOutput
> Nov 12 22:35:04 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 01 03 00 18 08 02
> 07 02 02 06 00 00
> Nov 12 22:35:04 phoenix ppp[621]: tun0: HDLC: 00 00 01 04
> 05 dc 05 06 58 27 0b 8d de da
> Nov 12 22:35:05 phoenix ppp[621]: tun0: HDLC: HdlcInput:
> Nov 12 22:35:05 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 01 01 00 1f 01 04
> 05 f4 02 06 00 0a
> Nov 12 22:35:05 phoenix ppp[621]: tun0: HDLC: 00 00 03 04
> c0 23 07 02 08 02 13 09 03 00 c0 7b
> Nov 12 22:35:05 phoenix ppp[621]: tun0: HDLC: 5f d3 10 50 aa
> Nov 12 22:35:05 phoenix ppp[621]: tun0: LCP: Received Configure Request (1)
> state =Req-Sent (6)
> Nov 12 22:35:05 phoenix ppp[621]: tun0: LCP: MRU 1524
> Nov 12 22:35:05 phoenix ppp[621]: tun0: LCP: ACCMAP 000a0000
> Nov 12 22:35:05 phoenix ppp[621]: tun0: LCP: AUTHPROTO proto = c023
> Nov 12 22:35:05 phoenix ppp[621]: tun0: LCP: PROTOCOMP
> Nov 12 22:35:05 phoenix ppp[621]: tun0: LCP: ACFCOMP
> Nov 12 22:35:05 phoenix ppp[621]: tun0: LCP: ???[13]
The peer has just violated the ppp protocol by asking for a [13]
again :-(
> Nov 12 22:35:05 phoenix ppp[621]: tun0: LCP: SendConfigRej(Req-Sent)
> Nov 12 22:35:05 phoenix ppp[621]: tun0: LCP: ???[13]
We still don't know.
> Nov 12 22:35:05 phoenix ppp[621]: tun0: HDLC: HdlcOutput
> Nov 12 22:35:05 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 04 01 00 0d 13 09
> 03 00 c0 7b 5f d3
> Nov 12 22:35:05 phoenix ppp[621]: tun0: HDLC: 10 09 96
> Nov 12 22:35:07 phoenix ppp[621]: tun0: LCP: LcpSendConfigReq
> Nov 12 22:35:07 phoenix ppp[621]: tun0: LCP: ACFCOMP
> Nov 12 22:35:07 phoenix ppp[621]: tun0: LCP: PROTOCOMP
> Nov 12 22:35:07 phoenix ppp[621]: tun0: LCP: ACCMAP [6] 00000000
> Nov 12 22:35:07 phoenix ppp[621]: tun0: LCP: MRU [4] 1500
> Nov 12 22:35:07 phoenix ppp[621]: tun0: LCP: MAGICNUM [6] 58270b8d
> Nov 12 22:35:07 phoenix ppp[621]: tun0: HDLC: HdlcOutput
> Nov 12 22:35:07 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 01 04 00 18 08 02
> 07 02 02 06 00 00
> Nov 12 22:35:07 phoenix ppp[621]: tun0: HDLC: 00 00 01 04
> 05 dc 05 06 58 27 0b 8d 92 7f
> Nov 12 22:35:09 phoenix ppp[621]: tun0: HDLC: HdlcInput:
> Nov 12 22:35:09 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 01 01 00 1f 01 04
> 05 f4 02 06 00 0a
> Nov 12 22:35:09 phoenix ppp[621]: tun0: HDLC: 00 00 03 04
> c0 23 07 02 08 02 13 09 03 00 c0 7b
> Nov 12 22:35:09 phoenix ppp[621]: tun0: HDLC: 5f d3 10 50 aa
> Nov 12 22:35:09 phoenix ppp[621]: tun0: LCP: Received Configure Request (1)
> state =Req-Sent (6)
> Nov 12 22:35:09 phoenix ppp[621]: tun0: LCP: MRU 1524
> Nov 12 22:35:09 phoenix ppp[621]: tun0: LCP: ACCMAP 000a0000
> Nov 12 22:35:09 phoenix ppp[621]: tun0: LCP: AUTHPROTO proto = c023
> Nov 12 22:35:09 phoenix ppp[621]: tun0: LCP: PROTOCOMP
> Nov 12 22:35:09 phoenix ppp[621]: tun0: LCP: ACFCOMP
> Nov 12 22:35:09 phoenix ppp[621]: tun0: LCP: ???[13]
> Nov 12 22:35:09 phoenix ppp[621]: tun0: LCP: SendConfigRej(Req-Sent)
> Nov 12 22:35:09 phoenix ppp[621]: tun0: LCP: ???[13]
Deja vous ?
> Nov 12 22:35:09 phoenix ppp[621]: tun0: HDLC: HdlcOutput
> Nov 12 22:35:09 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 04 01 00 0d 13 09
> 03 00 c0 7b 5f d3
> Nov 12 22:35:09 phoenix ppp[621]: tun0: HDLC: 10 09 96
> Nov 12 22:35:10 phoenix ppp[621]: tun0: LCP: LcpSendConfigReq
> Nov 12 22:35:10 phoenix ppp[621]: tun0: LCP: ACFCOMP
> Nov 12 22:35:10 phoenix ppp[621]: tun0: LCP: PROTOCOMP
> Nov 12 22:35:10 phoenix ppp[621]: tun0: LCP: ACCMAP [6] 00000000
> Nov 12 22:35:10 phoenix ppp[621]: tun0: LCP: MRU [4] 1500
> Nov 12 22:35:10 phoenix ppp[621]: tun0: LCP: MAGICNUM [6] 58270b8d
> Nov 12 22:35:10 phoenix ppp[621]: tun0: HDLC: HdlcOutput
> Nov 12 22:35:10 phoenix ppp[621]: tun0: HDLC: ff 03 c0 21 01 05 00 18 08 02
> 07 02 02 06 00 00
> Nov 12 22:35:10 phoenix ppp[621]: tun0: HDLC: 00 00 01 04
> 05 dc 05 06 58 27 0b 8d 5b f6
> Nov 12 22:35:13 phoenix ppp[621]: tun0: LCP: State change Req-Sent -->
> Stopped
> Nov 12 22:35:13 phoenix ppp[621]: tun0: LCP: LcpLayerFinish
> Nov 12 22:35:14 phoenix ppp[621]: tun0: Phase: Connect time: 37 secs
> Nov 12 22:35:14 phoenix ppp[621]: tun0: Phase: Modem: 259 octets in, 362
> octets out
> Nov 12 22:35:14 phoenix ppp[621]: tun0: Phase: NewPhase: Dead Nov
> 12 22:35:14 phoenix ppp[621]: tun0: IPCP: Using trigger address 255.255.255.0
> Nov 12 22:35:14 phoenix ppp[621]: tun0: Phase: Disconnected!
> Nov 12 22:35:14 phoenix ppp[621]: tun0: LCP: LcpLayerFinish
> Nov 12 22:35:14 phoenix ppp[621]: tun0: Phase: NewPhase: Dead
> Nov 12 22:35:14 phoenix ppp[621]: tun0: IPCP: Using trigger address
> 255.255.255.0
> ov 12 22:35:31 phoenix ppp[621]: tun0: Phase: PPP Terminated
> (normal).
It looks like the peer is misbehaving. I'd be interested in seeing a
copy of the logs from the working 2.2 stable from about a month ago.
Nothing should have changed with LCP negotiations.
> /Johan
> ___________________________________________________________
>
> Internet: Johang@Algonet.se
>
> I don't even speak for myself
--
Brian <brian@Awfulhak.org>, <brian@FreeBSD.org>, <bri@OpenBSD.org>
<http://www.Awfulhak.org>;
Don't _EVER_ lose your sense of humour....
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?199711130105.BAA08951>