From owner-freebsd-stable Mon May 18 00:00:13 1998 Return-Path: Received: (from majordom@localhost) by hub.freebsd.org (8.8.8/8.8.8) id AAA20929 for freebsd-stable-outgoing; Mon, 18 May 1998 00:00:13 -0700 (PDT) (envelope-from owner-freebsd-stable@FreeBSD.ORG) Received: from colin.muc.de (root@colin.muc.de [193.174.4.1]) by hub.freebsd.org (8.8.8/8.8.8) with SMTP id AAA20845 for ; Mon, 18 May 1998 00:00:03 -0700 (PDT) (envelope-from lutz@muc.de) Received: from tavari.muc.de ([193.174.4.22]) by colin.muc.de with SMTP id <140575-2>; Mon, 18 May 1998 07:47:14 +0200 Received: from muc.de (abraxas [192.168.42.5]) by tavari.muc.de (8.8.8/8.8.7) with ESMTP id HAA05209; Mon, 18 May 1998 07:46:55 +0200 (CEST) Message-ID: <355FCB4E.24D58FC9@muc.de> Date: Mon, 18 May 1998 07:46:54 +0200 From: Lutz Albers X-Mailer: Mozilla 4.04 [en] (X11; U; FreeBSD 2.2.6-RELEASE i386) MIME-Version: 1.0 To: Brian Somers CC: freebsd-stable@FreeBSD.ORG Subject: Re: 2.2.6 ppp breaks compatability with T-Online ppp References: <199805180003.BAA17758@awfulhak.org> Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: 7bit Sender: owner-freebsd-stable@FreeBSD.ORG Precedence: bulk Brian Somers wrote: > > I just upgraded my server from 2.2.5 to 2.2.6. After this upgrade ppp > > (user level) is no longer able to connect to the german ISP T-Online. > > The 2.2.5 version worked just fine (I have recompiled the version from > > the 2.2.5 sources and it works again). Here is the output from ppp.log: > [.....] > > If someone wants more info's, then just contact. > > I think we need to see the LCP and IPCP logs too. This looks like > failed negotiation :-/ No problem, here it comes: May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: default: set timeout 70 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: default: set server 3000 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: default: set dial ABORT NO\sB-CHANNEL ABORT BUSY ABORT NO\sCARRIER ABORT NO\sDIALTONE TIMEOUT 10 "" AT&B54\\P3 OK-AT-OK \dATD\T TIMEOUT 40 CONNECT May 18 07:43:06 morranon ppp.2.2.6[5188]: Phase: Using interface: tun0 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set phone 0191011 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: deny chap May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: accept pap May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set authname ***** May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set authkey ******** May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set ifaddr 0 0 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set afilter 0 deny icmp May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set afilter 1 deny udp src eq 53 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set afilter 2 deny udp dst eq 53 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set afilter 3 deny udp src eq 520 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set afilter 4 deny udp dst eq 520 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set afilter 5 permit 0/0 0/0 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set dfilter 0 deny icmp May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set dfilter 1 deny tcp src eq 25 May 18 07:43:06 morranon ppp.2.2.6[5188]: Command: tol: set dfilter 2 deny tcp dst eq 25 May 18 07:43:07 morranon ppp.2.2.6[5188]: Command: tol: set dfilter 3 permit 0/0 0/0 May 18 07:43:07 morranon ppp.2.2.6[5188]: Phase: PPP Started. May 18 07:43:08 morranon ppp.2.2.6[5188]: Command: dial May 18 07:43:09 morranon ppp.2.2.6[5188]: Phase: Connected! May 18 07:43:09 morranon ppp.2.2.6[5188]: Connect: AT&B54\P3^M^M May 18 07:43:09 morranon ppp.2.2.6[5188]: Connect: OK May 18 07:43:11 morranon ppp.2.2.6[5188]: Phase: Phone: 0191011 May 18 07:43:11 morranon ppp.2.2.6[5188]: Connect: ^M May 18 07:43:13 morranon ppp.2.2.6[5188]: Connect: ATD0191011^M^M May 18 07:43:13 morranon ppp.2.2.6[5188]: Connect: CONNECT May 18 07:43:13 morranon ppp.2.2.6[5188]: LCP: State change Initial --> Closed May 18 07:43:13 morranon ppp.2.2.6[5188]: LCP: State change Closed --> Stopped May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: LcpSendConfigReq May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: ACFCOMP[2] May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: PROTOCOMP[2] May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: ACCMAP[6] 0x00000000 May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: MRU[4] 1500 May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: MAGICNUM[6] 0x6e3a18f9 May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: State change Stopped --> Req-Sent May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: Received Configure Request (1) state = Req-Sent (6) May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: MRU[4] 1524 May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: AUTHPROTO[4] 0xc023 (PAP) May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: MULTIED[9] 0300c07b6bee4f May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: SendConfigRej(Req-Sent) May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: MULTIED[9] 0300c07b6bee4f May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: Received Configure Reject (1) state = Req-Sent (6) May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: RecvConfigRej. May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: ACFCOMP[2] May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: PROTOCOMP[2] May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: LcpSendConfigReq May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: ACCMAP[6] 0x00000000 May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: MRU[4] 1500 May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: MAGICNUM[6] 0x6e3a18f9 May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: Received Configure Request (2) state = Req-Sent (6) May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: MRU[4] 1524 May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: AUTHPROTO[4] 0xc023 (PAP) May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: SendConfigAck(Req-Sent) May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: MRU[4] 1524 May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: AUTHPROTO[4] 0xc023 (PAP) May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: State change Req-Sent --> Ack-Sent May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: Received Configure Ack (2) state = Ack-Sent (8) May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: State change Ack-Sent --> Opened May 18 07:43:14 morranon ppp.2.2.6[5188]: LCP: LcpLayerUp May 18 07:43:14 morranon ppp.2.2.6[5188]: Phase: NewPhase: Authenticate May 18 07:43:14 morranon ppp.2.2.6[5188]: Phase: his = PAP, mine = none May 18 07:43:14 morranon ppp.2.2.6[5188]: Phase: PAP: 00011761267008993940363#1 May 18 07:43:15 morranon ppp.2.2.6[5188]: Phase: PapInput: ACK May 18 07:43:15 morranon ppp.2.2.6[5188]: Phase: Received PAP_ACK () May 18 07:43:15 morranon ppp.2.2.6[5188]: Phase: NewPhase: Network May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: State change Initial --> Closed May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPCP Up event!! May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IpcpSendConfigReq May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 0.0.0.0 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compres May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: State change Closed --> Req-Sent May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Request (1) state = Req-Sent (6) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compression May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.158.131.193 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: SendConfigNak(Req-Sent) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 0.0.0.0 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Nak (1) state = Req-Sent (6) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.159.108.3 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] changing address: 0.0.0.0 --> 193.159.108.3 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IpcpSendConfigReq May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.159.108.3 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compres May 18 07:43:15 morranon ppp.2.2.6[5188]: LCP: Received Protocol Reject (3) state = Opened (9) May 18 07:43:15 morranon ppp.2.2.6[5188]: LCP: -- Protocol (80fd) was rejected. May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Request (1) state = Req-Sent (6) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compression May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.158.131.193 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: SendConfigNak(Req-Sent) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 0.0.0.0 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Ack (2) state = Req-Sent (6) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: State change Req-Sent --> Ack-Rcvd May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Request (1) state = Ack-Rcvd (7) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compression May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.158.131.193 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: SendConfigNak(Ack-Rcvd) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 0.0.0.0 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Request (1) state = Ack-Rcvd (7) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compression May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.158.131.193 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: SendConfigNak(Ack-Rcvd) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 0.0.0.0 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Request (1) state = Ack-Rcvd (7) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compression May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.158.131.193 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: SendConfigNak(Ack-Rcvd) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 0.0.0.0 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Request (1) state = Ack-Rcvd (7) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compression May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.158.131.193 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: SendConfigNak(Ack-Rcvd) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 0.0.0.0 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Request (1) state = Ack-Rcvd (7) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compression May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.158.131.193 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: SendConfigNak(Ack-Rcvd) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 0.0.0.0 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Configure Request (1) state = Ack-Rcvd (7) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: COMPPROTO[6] 16 VJ slots with slot compression May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 193.158.131.193 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: SendConfigNak(Ack-Rcvd) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IPADDR[6] 0.0.0.0 May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Terminate Request (2) state = Ack-Rcvd (7) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IpcpSendTerminateAck May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: State change Ack-Rcvd --> Req-Sent May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: Received Terminate Request (3) state = Req-Sent (6) May 18 07:43:15 morranon ppp.2.2.6[5188]: IPCP: IpcpSendTerminateAck May 18 07:43:15 morranon ppp.2.2.6[5188]: LCP: Received Terminate Request (1) state = Opened (9) May 18 07:43:15 morranon ppp.2.2.6[5188]: LCP: LcpLayerDown May 18 07:43:15 morranon ppp.2.2.6[5188]: LCP: LcpSendTerminateAck. May 18 07:43:15 morranon ppp.2.2.6[5188]: LCP: State change Opened --> Stopping May 18 07:43:16 morranon ppp.2.2.6[5188]: Phase: Disconnected! May 18 07:43:16 morranon ppp.2.2.6[5188]: LCP: State change Stopping --> Starting May 18 07:43:16 morranon ppp.2.2.6[5188]: LCP: LcpLayerFinish May 18 07:43:16 morranon ppp.2.2.6[5188]: Phase: Modem: Connect time: 7 secs: 427 octets in, 600 octets out May 18 07:43:16 morranon ppp.2.2.6[5188]: Phase: total 146 bytes/sec May 18 07:43:16 morranon ppp.2.2.6[5188]: Phase: NewPhase: Dead May 18 07:43:25 morranon ppp.2.2.6[5188]: Command: qiot May 18 07:43:27 morranon ppp.2.2.6[5188]: Command: quit May 18 07:43:28 morranon ppp.2.2.6[5188]: Phase: PPP Terminated (normal). -- Lutz Albers, lutz@muc.de Do not take life too seriously, you will never get out of it alive. To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-stable" in the body of the message