Re: PPPoE problems with latest -CURRENT

From: Maxim Maximov <mcsi_at_mcsi.pp.ru>
Date: Sun, 01 Aug 2004 14:19:57 +0400
Gleb Smirnoff wrote:
> On Sun, Aug 01, 2004 at 01:45:13PM +0400, Maxim Maximov wrote:
> M> 	My ppp.conf haven't been changed from the time this machine have 
> M> FreeBSD installed. Password configured is the same that I'm successfuly 
> M> using now with rp-pppoe.
> 
> If you suspect ng_pppoe, than please backout ng_pppoe.c to rev 1.65, 
> ng_pppoe.h to 1.19. Install backed out ng_pppoe.h, rebuild and reinstall
> both ppp(8) and ng_pppoe(4). Try this and reply whether it works or not.
> 

	Well, it definitely works. Notice "Warning: deflink: Reducing 
configured MRU from 1500 to 1492" which didnt show up in previous logs.
	Also I had to backout ppp/ether.c to r1.27

Aug  1 14:14:18 ultra ppp[221]: tun0: Phase: bundle: Establish
Aug  1 14:14:18 ultra ppp[221]: tun0: Phase: deflink: closed -> opening
Aug  1 14:14:18 ultra ppp[221]: tun0: Phase: deflink: Connected!
Aug  1 14:14:18 ultra ppp[221]: tun0: Phase: deflink: opening -> dial
Aug  1 14:14:18 ultra ppp[221]: tun0: Chat: deflink: Dial attempt 1 of 1
Aug  1 14:14:18 ultra ppp[221]: tun0: Phase: deflink: dial -> carrier
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: Received NGM_PPPOE_ACNAME 
(hook "MGTS")
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: Received NGM_PPPOE_SESSIONID
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: Received NGM_PPPOE_SUCCESS
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: deflink: carrier -> login
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: deflink: login -> lcp
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: FSM: Using "deflink" as a 
transport
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: State change Initial 
--> Closed
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: State change Closed 
--> Stopped
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: RecvConfigReq(1) 
state = Stopped
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MRU[4] 1492
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MAGICNUM[6] 0x596121ce
Aug  1 14:14:19 ultra ppp[221]: tun0: Warning: deflink: Reducing 
configured MRU from 1500 to 1492
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: SendConfigReq(1) 
state = Stopped
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  ACFCOMP[2]
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  PROTOCOMP[2]
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  ACCMAP[6] 0x00000000
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MRU[4] 1492
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MAGICNUM[6] 0xc09c974e
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: SendConfigAck(1) 
state = Stopped
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MRU[4] 1492
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MAGICNUM[6] 0x596121ce
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: LayerStart
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: State change Stopped 
--> Ack-Sent
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: RecvConfigAck(1) 
state = Ack-Sent
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  ACFCOMP[2]
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  PROTOCOMP[2]
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  ACCMAP[6] 0x00000000
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MRU[4] 1492
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MAGICNUM[6] 0xc09c974e
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: State change 
Ack-Sent --> Opened
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: LayerUp
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: SendIdent(0) state = 
Opened
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MAGICNUM c09c974e
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  TEXT user-ppp 3.2 (built Aug 
  1 2004)
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: bundle: Authenticate
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: deflink: his = CHAP 0x05, 
mine = none
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: Chap Input: CHALLENGE (16 
bytes from A947-agr01)
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: Chap Output: RESPONSE 
(xxxxxxxxxx)
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: Chap Input: SUCCESS
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: Using trigger address 0.0.0.0
Aug  1 14:14:19 ultra ppp[221]: tun0: CCP: FSM: Using "deflink" as a 
transport
Aug  1 14:14:19 ultra ppp[221]: tun0: CCP: deflink: State change Initial 
--> Closed
Aug  1 14:14:19 ultra ppp[221]: tun0: CCP: deflink: LayerStart.
Aug  1 14:14:19 ultra ppp[221]: tun0: CCP: MPPE: Not usable without CHAP81
Aug  1 14:14:19 ultra ppp[221]: tun0: CCP: deflink: SendConfigReq(1) 
state = Closed
Aug  1 14:14:19 ultra ppp[221]: tun0: CCP:  DEFLATE[4] win 15
Aug  1 14:14:19 ultra ppp[221]: tun0: CCP:  PRED1[2]
Aug  1 14:14:19 ultra ppp[221]: tun0: CCP: deflink: State change Closed 
--> Req-Sent
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: deflink: lcp -> open
Aug  1 14:14:19 ultra ppp[221]: tun0: Phase: bundle: Network
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: FSM: Using "deflink" as a 
transport
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: deflink: State change 
Initial --> Closed
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: deflink: LayerStart.
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: deflink: SendConfigReq(1) 
state = Closed
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots 
with slot compression
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  PRIDNS[6] 212.188.4.10
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: deflink: State change Closed 
--> Req-Sent
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: deflink: RecvConfigReq(1) 
state = Req-Sent
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  IPADDR[6] 10.2.255.2
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: deflink: SendConfigAck(1) 
state = Req-Sent
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  IPADDR[6] 10.2.255.2
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: deflink: State change 
Req-Sent --> Ack-Sent
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: RecvProtocolRej(2) 
state = Opened
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: -- Protocol 0x80fd 
(Compression Control Protocol) was rejected!
Aug  1 14:14:19 ultra ppp[221]: tun0: CCP: deflink: State change 
Req-Sent --> Stopped
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: deflink: RecvConfigRej(1) 
state = Ack-Sent
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP: deflink: SendIdent(1) state = 
Opened
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  MAGICNUM c09c974e
Aug  1 14:14:19 ultra ppp[221]: tun0: LCP:  TEXT user-ppp 3.2 (built Aug 
  1 2004)
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots 
with slot compression
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP: deflink: SendConfigReq(2) 
state = Ack-Sent
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  PRIDNS[6] 212.188.4.10
Aug  1 14:14:19 ultra ppp[221]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP: deflink: RecvConfigNak(2) 
state = Ack-Sent
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  IPADDR[6] 81.195.16.111
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  IPADDR[6] changing address: 
0.0.0.0  --> 81.195.16.111
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  PRIDNS[6] 195.34.32.10
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  SECDNS[6] 212.188.4.10
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP: Primary nameserver set to 
195.34.32.10
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP: Secondary nameserver set to 
212.188.4.10
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP: deflink: SendConfigReq(3) 
state = Ack-Sent
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  IPADDR[6] 81.195.16.111
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  PRIDNS[6] 195.34.32.10
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  SECDNS[6] 212.188.4.10
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP: deflink: RecvConfigAck(3) 
state = Ack-Sent
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  IPADDR[6] 81.195.16.111
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  PRIDNS[6] 195.34.32.10
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP:  SECDNS[6] 212.188.4.10
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP: deflink: State change 
Ack-Sent --> Opened
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP: deflink: LayerUp.
Aug  1 14:14:20 ultra ppp[221]: tun0: IPCP: myaddr 81.195.16.111 hisaddr 
= 10.2.255.2
Aug  1 14:14:20 ultra ppp[221]: tun0: LCP: deflink: RecvEchoRequest(1) 
state = Opened
Aug  1 14:14:20 ultra ppp[221]: tun0: LCP: deflink: SendEchoReply(1) 
state = Opened
Aug  1 14:14:42 ultra ppp[221]: tun0: LCP: deflink: RecvEchoRequest(2) 
state = Opened
Aug  1 14:14:42 ultra ppp[221]: tun0: LCP: deflink: SendEchoReply(2) 
state = Opened
Aug  1 14:14:52 ultra ppp[221]: tun0: LCP: deflink: RecvEchoRequest(3) 
state = Opened


-- 
Maxim Maximov
Received on Sun Aug 01 2004 - 08:20:09 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:38:04 UTC