From owner-freebsd-net Fri Feb 2 14:51:35 2001 Delivered-To: freebsd-net@freebsd.org Received: from jason.argos.org (a1-3b044.neo.lrun.com [24.93.181.44]) by hub.freebsd.org (Postfix) with ESMTP id 70CEB37B699 for ; Fri, 2 Feb 2001 14:51:15 -0800 (PST) Received: from localhost (mike@localhost) by jason.argos.org (8.10.1/8.10.1) with ESMTP id f12Mf6i25074 for ; Fri, 2 Feb 2001 17:41:06 -0500 Date: Fri, 2 Feb 2001 17:41:06 -0500 (EST) From: Mike Nowlin To: freebsd-net@freebsd.org Subject: PPP - CHAP failure after CHAP success??? Message-ID: MIME-Version: 1.0 Content-Type: TEXT/PLAIN; charset=US-ASCII Sender: owner-freebsd-net@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.org On a recently cvsup'd machine (4.2-S as of two days ago), incoming PPP w/CHAP via RADIUS has suddenly broken. Basically, RADIUS OK's the connection, addr info is transferred & approved, everything looks normal, until after the log line listing myaddr and hisaddr - why is it doing CHAP again, and what happened to my RADIUS server? README.changes diffs only mentioned MSCHAPv2 and MPPE changes - disabled both of those, but it doesn't make any difference. --mike Feb 2 16:06:56 rimmer ppp[320]: tun1: Phase: bundle: Authenticate Feb 2 16:06:56 rimmer ppp[320]: tun1: Phase: deflink: his = none, mine = CHAP 0x05 Feb 2 16:06:56 rimmer ppp[320]: tun1: Phase: Chap Output: CHALLENGE Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: Chap Input: RESPONSE (16 bytes from argos) Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: Radius: Request sent Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: Radius: ACCEPT received Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: MTU 1500 Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: VJ enabled Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: IP 10.99.1.6 Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: Netmask 255.255.255.252 Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: Chap Output: SUCCESS Feb 2 16:06:57 rimmer ppp[320]: tun1: Warning: 10.99.1.6: Cannot determine ethernet address for proxy ARP Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: deflink: lcp -> open Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: bundle: Network Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: FSM: Using "deflink" as a transport Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: State change Initial --> Closed Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: LayerStart. Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: SendConfigReq(1) state = Closed Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: IPADDR[6] 10.129.1.2 Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: State change Closed --> Req-Sent Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: RecvConfigReq(3) state = Req-Sent Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: IPADDR[6] 10.99.1.6 Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: SendConfigAck(3) state = Req-Sent Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: IPADDR[6] 10.99.1.6 Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: State change Req-Sent --> Ack-Sent Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: RecvConfigAck(1) state = Ack-Sent Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: State change Ack-Sent --> Opened Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: deflink: LayerUp. Feb 2 16:06:57 rimmer ppp[320]: tun1: IPCP: myaddr 10.129.1.2 hisaddr = 10.99.1.6 Feb 2 16:06:57 rimmer ppp[320]: tun1: Warning: 10.99.1.6: Cannot determine ethernet address for proxy ARP ...new stuff starts here - these lines never showed up before..... Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: radius: No RADIUS servers specified Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: Chap Output: FAILURE Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: deflink: open -> lcp Feb 2 16:06:57 rimmer ppp[320]: tun1: LCP: deflink: LayerDown Feb 2 16:06:57 rimmer ppp[320]: tun1: LCP: deflink: SendTerminateReq(3) state = Opened Feb 2 16:06:57 rimmer ppp[320]: tun1: LCP: deflink: State change Opened --> Closing Feb 2 16:06:57 rimmer ppp[320]: tun1: LCP: deflink: RecvTerminateReq(4) state = Closing Feb 2 16:06:57 rimmer ppp[320]: tun1: LCP: deflink: SendTerminateAck(4) state = Closing Feb 2 16:06:57 rimmer ppp[320]: tun1: LCP: deflink: RecvTerminateAck(3) state = Closing Feb 2 16:06:57 rimmer ppp[320]: tun1: LCP: deflink: LayerFinish Feb 2 16:06:57 rimmer ppp[320]: tun1: LCP: deflink: State change Closing --> Closed Feb 2 16:06:57 rimmer ppp[320]: tun1: LCP: deflink: State change Closed --> Initial Feb 2 16:06:57 rimmer ppp[320]: tun1: Warning: deflink: Unable to set physical to speed 0 Feb 2 16:06:57 rimmer ppp[320]: tun1: Phase: deflink: Disconnected! To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-net" in the body of the message