PPPOE server with pppoed and mppe
Emil Filipov
tie at einet.bg
Fri Jan 16 06:21:24 PST 2004
OK guys, according to your advice I'm now trying with mpd (v.3.16).
Works like magic with a SMC Router, but when I try to connect from a Win2k box with RASPPPOE installed, the LCP negotiation fails.
Here is a logged example of one such failure:
Jan 16 15:46:28 opera mpd: Incoming PPPoE connection request via vlan107: for service "EuroIntegra" from 00:00:e2:6e:d0:d9
Jan 16 15:46:28 opera mpd: [pppoe1] Accepting PPPoE connection
Jan 16 15:46:28 opera mpd: [pppoe1] PPPoE response sent
Jan 16 15:46:28 opera mpd: [bun_pppoe1] IPCP: Open event
Jan 16 15:46:28 opera mpd: [bun_pppoe1] IPCP: state change Initial --> Starting
Jan 16 15:46:28 opera mpd: [bun_pppoe1] IPCP: LayerStart
Jan 16 15:46:28 opera mpd: [bun_pppoe1] bundle: OPEN event in state CLOSED
Jan 16 15:46:28 opera mpd: [bun_pppoe1] opening link "pppoe1"...
Jan 16 15:46:28 opera mpd: [pppoe1] link: OPEN event
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: Open event
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: state change Initial --> Starting
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: LayerStart
Jan 16 15:46:28 opera mpd: [pppoe1] device: OPEN event in state DOWN
Jan 16 15:46:28 opera mpd: [pppoe1] PppoeOpen() on incoming call
Jan 16 15:46:28 opera mpd: [pppoe1] device is now in state OPENING
Jan 16 15:46:28 opera mpd: [pppoe1] PPPoE connection successful
Jan 16 15:46:28 opera mpd: [pppoe1] device: UP event in state OPENING
Jan 16 15:46:28 opera mpd: [pppoe1] device is now in state UP
Jan 16 15:46:28 opera mpd: [pppoe1] link: UP event
Jan 16 15:46:28 opera mpd: [pppoe1] link: origination is remote
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: Up event
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: state change Starting --> Req-Sent
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: phase shift DEAD --> ESTABLISH
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: SendConfigReq #1
Jan 16 15:46:28 opera mpd: MRU 1492
Jan 16 15:46:28 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:28 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: rec'd Configure Request #0 link 0 (Req-Sent)
Jan 16 15:46:28 opera mpd: MAGICNUM 7f0f0305
Jan 16 15:46:28 opera mpd: MP MRRU 1614
Jan 16 15:46:28 opera mpd: ENDPOINTDISC [LOCAL] 7e e7 eb 44 29 cc 46 7d 97 6a c8 8f 33 00 9d 2a 00 00 00 00
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: SendConfigRej #0
Jan 16 15:46:28 opera mpd: MP MRRU 1614
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: rec'd Configure Nak #1 link 0 (Req-Sent)
Jan 16 15:46:28 opera mpd: MRU 1488
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: SendConfigReq #2
Jan 16 15:46:28 opera mpd: MRU 1488
Jan 16 15:46:28 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:28 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: rec'd Configure Request #1 link 0 (Req-Sent)
Jan 16 15:46:28 opera mpd: MAGICNUM 7f0f0305
Jan 16 15:46:28 opera mpd: ENDPOINTDISC [LOCAL] 7e e7 eb 44 29 cc 46 7d 97 6a c8 8f 33 00 9d 2a 00 00 00 00
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: SendConfigAck #1
Jan 16 15:46:28 opera mpd: MAGICNUM 7f0f0305
Jan 16 15:46:28 opera mpd: ENDPOINTDISC [LOCAL] 7e e7 eb 44 29 cc 46 7d 97 6a c8 8f 33 00 9d 2a 00 00 00 00
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: state change Req-Sent --> Ack-Sent
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: rec'd Configure Nak #2 link 0 (Ack-Sent)
Jan 16 15:46:28 opera mpd: MRU 1488
Jan 16 15:46:28 opera mpd: [pppoe1] LCP: SendConfigReq #3
Jan 16 15:46:28 opera mpd: MRU 1488
Jan 16 15:46:28 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:28 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: SendConfigReq #4
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:30 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: rec'd Configure Nak #4 link 0 (Ack-Sent)
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: SendConfigReq #5
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:30 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: rec'd Configure Nak #5 link 0 (Ack-Sent)
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: SendConfigReq #6
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:30 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: rec'd Configure Nak #6 link 0 (Ack-Sent)
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: SendConfigReq #7
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:30 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: rec'd Configure Nak #7 link 0 (Ack-Sent)
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: SendConfigReq #8
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:30 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: rec'd Configure Nak #8 link 0 (Ack-Sent)
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: SendConfigReq #9
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:30 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: rec'd Configure Nak #9 link 0 (Ack-Sent)
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: SendConfigReq #10
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: MAGICNUM 1c5e3cf8
Jan 16 15:46:30 opera mpd: AUTHPROTO CHAP MSOFTv2
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: rec'd Configure Nak #10 link 0 (Ack-Sent)
Jan 16 15:46:30 opera mpd: MRU 1488
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: not converging
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: parameter negotiation failed
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: state change Ack-Sent --> Stopped
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: LayerFinish
Jan 16 15:46:30 opera mpd: [pppoe1] device: CLOSE event in state UP
Jan 16 15:46:30 opera mpd: [pppoe1] device is now in state CLOSING
Jan 16 15:46:30 opera mpd: [pppoe1] device: DOWN event in state CLOSING
Jan 16 15:46:30 opera mpd: [pppoe1] device is now in state DOWN
Jan 16 15:46:30 opera mpd: [pppoe1] link: DOWN event
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: Down event
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: state change Stopped --> Starting
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: phase shift ESTABLISH --> DEAD
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: LayerStart
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: Close event
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: state change Starting --> Initial
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: LayerFinish
Jan 16 15:46:30 opera mpd: [bun_pppoe1] closing link "pppoe1"...
Jan 16 15:46:30 opera mpd: [bun_pppoe1] IPCP: Close event
Jan 16 15:46:30 opera mpd: [bun_pppoe1] IPCP: state change Starting --> Initial
Jan 16 15:46:30 opera mpd: [bun_pppoe1] IPCP: LayerFinish
Jan 16 15:46:30 opera mpd: [bun_pppoe1] bundle: CLOSE event in state OPENED
Jan 16 15:46:30 opera mpd: [pppoe1] link: CLOSE event
Jan 16 15:46:30 opera mpd: [pppoe1] LCP: Close event
Jan 16 15:46:30 opera mpd: [pppoe1] device: OPEN event in state DOWN
Jan 16 15:46:30 opera mpd: [pppoe1] pausing 4 seconds before open
Jan 16 15:46:30 opera mpd: [pppoe1] device is now in state DOWN
Jan 16 15:46:30 opera mpd: [pppoe1] device: CLOSE event in state DOWN
Jan 16 15:46:30 opera mpd: [pppoe1] device is now in state DOWN
As you can see the client does not agree with MRU of 1488 (I tried initially with the default of 1492). Bug in RASPPOE implementation?
Here is the relevant part of my mpd.conf:
pppoe_standart:
set bundle no multilink
set bundle enable compression
set bundle accept encryption
set bundle max-logins 1
set iface idle 0
set iface disable on-demand
set iface disable proxy-arp
set iface enable tcpmssfix
set ipcp no vjcomp
set link no pap chap acfcomp protocomp callback
set link yes chap-msv2 chap-msv1
set link max-redial -1
set ccp yes mpp-e40
set ccp yes mpp-e128
set ccp yes mpp-stateless
set pppoe iface vlan107
set pppoe service MyService
set pppoe disable originate
set pppoe enable incoming
Regards,
Emil Filipov
More information about the freebsd-net
mailing list