From owner-freebsd-current@FreeBSD.ORG Sun Aug 1 09:45:27 2004 Return-Path: Delivered-To: freebsd-current@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 347C816A4CE; Sun, 1 Aug 2004 09:45:27 +0000 (GMT) Received: from cpanel.ezone.ru (cpanel.ezone.ru [213.85.31.234]) by mx1.FreeBSD.org (Postfix) with ESMTP id 42D2D43D64; Sun, 1 Aug 2004 09:45:26 +0000 (GMT) (envelope-from mcsi@mcsi.pp.ru) Received: from [81.195.16.111] (ppp16-111.pppoe.mtu-net.ru [81.195.16.111]) (authenticated bits=0) by cpanel.ezone.ru (8.13.0/8.12.11) with ESMTP id i719jJGc052092; Sun, 1 Aug 2004 13:45:20 +0400 (MSD) (envelope-from mcsi@mcsi.pp.ru) Message-ID: <410CBBA9.1080302@mcsi.pp.ru> Date: Sun, 01 Aug 2004 13:45:13 +0400 From: Maxim Maximov User-Agent: Mozilla/5.0 (X11; U; FreeBSD i386; en-US; rv:1.7.1) Gecko/20040720 X-Accept-Language: ru, en-us, en MIME-Version: 1.0 To: Gleb Smirnoff References: <20040730091525.U484@pukruppa.net> <20040731213854.GB38076@cell.sick.ru> <410CB1E4.2030103@mcsi.pp.ru> <20040801093316.GA40608@cell.sick.ru> In-Reply-To: <20040801093316.GA40608@cell.sick.ru> Content-Type: text/plain; charset=us-ascii; format=flowed Content-Transfer-Encoding: 7bit X-Spam-Status: No, hits=-4.9 required=5.0 tests=BAYES_00 autolearn=ham version=2.63 X-Spam-Checker-Version: SpamAssassin 2.63 (2004-01-11) on mail3.ezone.ru cc: freebsd-current@freebsd.org Subject: Re: PPPoE problems with latest -CURRENT X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sun, 01 Aug 2004 09:45:27 -0000 Gleb Smirnoff wrote: > Maxim, > > On Sun, Aug 01, 2004 at 01:03:32PM +0400, Maxim Maximov wrote: > M> I can confirm that the problems are still there. ppp.log and tcpdump > M> output follow: > > This is a different problem. You either can't negotiate MRU with your > peer, are you are suppling incorrect CHAP secret. > > What operating system/hardware is in remote side? > > Please check your password, too. > Gleb, My ppp.conf haven't been changed from the time this machine have FreeBSD installed. Password configured is the same that I'm successfuly using now with rp-pppoe. Please look at this successful rp-pppoe session log. I see MRU is really changed to 1500. Why ng_pppoe can't do this anymore? OS/hardware on the remote side is beyond my knowledge. I could try to find it out, but it may take some time. Aug 1 13:03:10 ultra ppp[723]: Phase: Using interface: tun0 Aug 1 13:03:10 ultra ppp[723]: Phase: deflink: Created in closed state Aug 1 13:03:10 ultra ppp[723]: tun0: Command: default: ident user-ppp VERSION (built COMPILATIONDATE) Aug 1 13:03:10 ultra ppp[723]: tun0: Command: default: set device /dev/cuaa1 Aug 1 13:03:10 ultra ppp[723]: tun0: Command: default: enable dns Aug 1 13:03:10 ultra ppp[723]: tun0: Command: mtu-rp: set device !/usr/local/sbin/pppoe -U -I sk0 Aug 1 13:03:10 ultra ppp[723]: tun0: Command: mtu-rp: set ifaddr 172.16.0.1/0 172.16.0.2/0 255.255.255.0 0.0.0.0 Aug 1 13:03:10 ultra ppp[723]: tun0: Command: mtu-rp: add default HISADDR Aug 1 13:03:10 ultra ppp[723]: tun0: Command: mtu-rp: set authname xxxxxxxx Aug 1 13:03:10 ultra ppp[723]: tun0: Command: mtu-rp: set authkey ******** Aug 1 13:03:10 ultra ppp[724]: tun0: Phase: PPP Started (auto mode). Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: bundle: Establish Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: deflink: closed -> opening Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: deflink: Connected! Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: deflink: opening -> dial Aug 1 13:03:12 ultra ppp[724]: tun0: Chat: deflink: Dial attempt 1 of 1 Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: deflink: dial -> carrier Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: deflink: carrier -> login Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: deflink: login -> lcp Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: FSM: Using "deflink" as a transport Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: State change Initial --> Closed Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: State change Closed --> Stopped Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: RecvConfigReq(1) state = Stopped Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: MRU[4] 1492 Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: MAGICNUM[6] 0x592003fa Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: ACFCOMP[2] Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: PROTOCOMP[2] Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: ACCMAP[6] 0x00000000 Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: MRU[4] 1500 Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: MAGICNUM[6] 0xbc956200 Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: SendConfigAck(1) state = Stopped Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: MRU[4] 1492 Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: MAGICNUM[6] 0x592003fa Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: LayerStart Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: State change Stopped --> Ack-Sent Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: RecvConfigAck(1) state = Ack-Sent Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: ACFCOMP[2] Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: PROTOCOMP[2] Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: ACCMAP[6] 0x00000000 Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: MRU[4] 1500 Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: MAGICNUM[6] 0xbc956200 Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: State change Ack-Sent --> Opened Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: LayerUp Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: deflink: SendIdent(0) state = Opened Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: MAGICNUM bc956200 Aug 1 13:03:12 ultra ppp[724]: tun0: LCP: TEXT user-ppp 3.2 (built Jul 30 2004) Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: bundle: Authenticate Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: deflink: his = CHAP 0x05, mine = none Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: Chap Input: CHALLENGE (16 bytes from A947-agr01) Aug 1 13:03:12 ultra ppp[724]: tun0: Phase: Chap Output: RESPONSE (xxxxxxxxxxxxxx) Aug 1 13:03:13 ultra ppp[724]: tun0: Phase: Chap Input: SUCCESS Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: Using trigger address 0.0.0.0 Aug 1 13:03:13 ultra ppp[724]: tun0: CCP: FSM: Using "deflink" as a transport Aug 1 13:03:13 ultra ppp[724]: tun0: CCP: deflink: State change Initial --> Closed Aug 1 13:03:13 ultra ppp[724]: tun0: CCP: deflink: LayerStart. Aug 1 13:03:13 ultra ppp[724]: tun0: CCP: MPPE: Not usable without CHAP81 Aug 1 13:03:13 ultra ppp[724]: tun0: CCP: deflink: SendConfigReq(1) state = Closed Aug 1 13:03:13 ultra ppp[724]: tun0: CCP: DEFLATE[4] win 15 Aug 1 13:03:13 ultra ppp[724]: tun0: CCP: PRED1[2] Aug 1 13:03:13 ultra ppp[724]: tun0: CCP: deflink: State change Closed --> Req-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: Phase: deflink: lcp -> open Aug 1 13:03:13 ultra ppp[724]: tun0: Phase: bundle: Network Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: FSM: Using "deflink" as a transport Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: State change Initial --> Closed Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: LayerStart. Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: IPADDR[6] 0.0.0.0 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: PRIDNS[6] 212.188.4.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: SECDNS[6] 255.255.255.255 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: State change Closed --> Req-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: RecvConfigReq(1) state = Req-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: IPADDR[6] 10.2.255.2 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: SendConfigAck(1) state = Req-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: IPADDR[6] 10.2.255.2 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: State change Req-Sent --> Ack-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: LCP: deflink: RecvProtocolRej(2) state = Opened Aug 1 13:03:13 ultra ppp[724]: tun0: LCP: deflink: -- Protocol 0x80fd (Compression Control Protocol) was rejected! Aug 1 13:03:13 ultra ppp[724]: tun0: CCP: deflink: State change Req-Sent --> Stopped Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: RecvConfigRej(1) state = Ack-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: LCP: deflink: SendIdent(1) state = Opened Aug 1 13:03:13 ultra ppp[724]: tun0: LCP: MAGICNUM bc956200 Aug 1 13:03:13 ultra ppp[724]: tun0: LCP: TEXT user-ppp 3.2 (built Jul 30 2004) Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: SendConfigReq(2) state = Ack-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: IPADDR[6] 0.0.0.0 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: PRIDNS[6] 212.188.4.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: SECDNS[6] 255.255.255.255 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: RecvConfigNak(2) state = Ack-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: IPADDR[6] 81.195.16.111 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: IPADDR[6] changing address: 0.0.0.0 --> 81.195.16.111 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: PRIDNS[6] 195.34.32.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: SECDNS[6] 212.188.4.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: Primary nameserver set to 195.34.32.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: Secondary nameserver set to 212.188.4.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: SendConfigReq(3) state = Ack-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: IPADDR[6] 81.195.16.111 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: PRIDNS[6] 195.34.32.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: SECDNS[6] 212.188.4.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: RecvConfigAck(3) state = Ack-Sent Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: IPADDR[6] 81.195.16.111 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: PRIDNS[6] 195.34.32.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: SECDNS[6] 212.188.4.10 Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: State change Ack-Sent --> Opened Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: deflink: LayerUp. Aug 1 13:03:13 ultra ppp[724]: tun0: IPCP: myaddr 81.195.16.111 hisaddr = 10.2.255.2 Aug 1 13:03:14 ultra ppp[724]: tun0: LCP: deflink: RecvEchoRequest(1) state = Opened Aug 1 13:03:14 ultra ppp[724]: tun0: LCP: deflink: SendEchoReply(1) state = Opened Aug 1 13:04:56 ultra ppp[724]: tun0: LCP: deflink: RecvEchoRequest(2) state = Opened Aug 1 13:04:56 ultra ppp[724]: tun0: LCP: deflink: SendEchoReply(2) state = Opened Aug 1 13:05:17 ultra ppp[724]: tun0: LCP: deflink: RecvEchoRequest(3) state = Opened -- Maxim Maximov