[ previous ] [ next ] [ threads ]
 
 From:  "Gianluca Bosco" <g dot bosco at ko dash droppers dot dyndns dot org>
 To:  <m0n0wall at lists dot m0n0 dot ch>
 Subject:  problem establishing pppoe connection
 Date:  Sat, 19 Jun 2004 23:25:38 +0200
I have a problem with mono 1.0 running on a generic PC.

*Establishing the pppoe connection takes hours and hours.* After tons and
tons of retries - mono gets online and start to route packets correctly to
the Internet.
However, using the same modem/same ADSL line with a win XP machine works
like a charm.

One thing is worth to be noted. As a modem, I'm using a router ZyXEL 642R in
bridge mode
(RFC1483).

Following is the mpd/pppoe log related to a typical unsuccesfull attempt to
establish a connection:

***start***
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] opening link "pppoe"...
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] link: OPEN event
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] LCP: Open event
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] LCP: state change
Initial --> Starting
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] LCP: LayerStart
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] device: OPEN event in
state DOWN
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] device is now in state
OPENING
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] rec'd ACNAME "r-ve056"
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] rec'd command 11 from
"[4b]:"
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] rec'd proto LCP while
dead
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] connection successful
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] device: UP event in
state OPENING
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] device is now in state
UP
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] link: UP event
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] link: origination is
local
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] LCP: Up event
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] LCP: state change
Starting --> Req-Sent
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] LCP: phase shift
DEAD --> ESTABLISH
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] LCP: SendConfigReq #34
Jun 19 00:04:02 scaffandro.entropy.org mpd: MRU 1492
Jun 19 00:04:02 scaffandro.entropy.org mpd: MAGICNUM 59b03ecc
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] LCP: rec'd Configure Ack
#34 link 0 (Req-Sent)
Jun 19 00:04:02 scaffandro.entropy.org mpd: MRU 1492
Jun 19 00:04:02 scaffandro.entropy.org mpd: MAGICNUM 59b03ecc
Jun 19 00:04:02 scaffandro.entropy.org mpd: [pppoe] LCP: state change
Req-Sent --> Ack-Rcvd
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] LCP: rec'd Configure
Request #2 link 0 (Ack-Rcvd)
Jun 19 00:04:22 scaffandro.entropy.org mpd: MRU 1492
Jun 19 00:04:22 scaffandro.entropy.org mpd: AUTHPROTO PAP
Jun 19 00:04:22 scaffandro.entropy.org mpd: MAGICNUM 5561bdf6
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] LCP: SendConfigAck #2
Jun 19 00:04:22 scaffandro.entropy.org mpd: MRU 1492
Jun 19 00:04:22 scaffandro.entropy.org mpd: AUTHPROTO PAP
Jun 19 00:04:22 scaffandro.entropy.org mpd: MAGICNUM 5561bdf6
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] LCP: state change
Ack-Rcvd --> Opened
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] LCP: phase shift
ESTABLISH --> AUTHENTICATE
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] LCP: auth: peer wants
PAP, I want nothing
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] PAP: using authname
mylogin at myisp dot it
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] PAP: sending REQUEST
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] LCP: LayerUp
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] PAP: rec'd ACK #1
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] LCP: authorization
successful
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] LCP: phase shift
AUTHENTICATE --> NETWORK
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] setting interface ng0
MTU to 1492 bytes
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] up: 1 link, total
bandwidth 64000 bps
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: Up event
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: state change
Starting --> Req-Sent
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: SendConfigReq #100
Jun 19 00:04:22 scaffandro.entropy.org mpd: IPADDR 0.0.0.0
Jun 19 00:04:22 scaffandro.entropy.org mpd: COMPPROTO VJCOMP, 16 comp.
channels, no comp-cid
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: rec'd Configure
Request #1 link 0 (Req-Sent)
Jun 19 00:04:22 scaffandro.entropy.org mpd: IPADDR 192.168.100.1
Jun 19 00:04:22 scaffandro.entropy.org mpd: 192.168.100.1 is OK
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: SendConfigAck #1
Jun 19 00:04:22 scaffandro.entropy.org mpd: IPADDR 192.168.100.1
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: state change
Req-Sent --> Ack-Sent
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: rec'd Configure
Reject #100 link 0 (Ack-Sent)
Jun 19 00:04:22 scaffandro.entropy.org mpd: COMPPROTO VJCOMP, 16 comp.
channels, no comp-cid
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: SendConfigReq #101
Jun 19 00:04:22 scaffandro.entropy.org mpd: IPADDR 0.0.0.0
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: rec'd Configure
Nak #101 link 0 (Ack-Sent)
Jun 19 00:04:22 scaffandro.entropy.org mpd: IPADDR 82.50.178.13
Jun 19 00:04:22 scaffandro.entropy.org mpd: 82.50.178.13 is OK
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: SendConfigReq #102
Jun 19 00:04:22 scaffandro.entropy.org mpd: IPADDR 82.50.178.13
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: rec'd Configure
Ack #102 link 0 (Ack-Sent)
Jun 19 00:04:22 scaffandro.entropy.org mpd: IPADDR 82.50.178.13
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: state change
Ack-Sent --> Opened
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IPCP: LayerUp
Jun 19 00:04:22 scaffandro.entropy.org mpd: 82.50.178.13 -> 192.168.100.1
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] IFACE: Up event
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] setting interface ng0
MTU to 1492 bytes
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] exec: /sbin/ifconfig ng0
82.50.178.13 192.168.100.1 netmask 0xffffffff -link0
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] exec: /sbin/route add
82.50.178.13 -iface lo0
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] exec: /sbin/route add
0.0.0.0 192.168.100.1
Jun 19 00:04:22 scaffandro.entropy.org mpd: [pppoe] exec:
/usr/local/sbin/ppp-linkup ng0 inet 82.50.178.13 192.168.100.1
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] IFACE: Up event
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] connection closed
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] rec'd unexpected
protocol IP on link 0
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] device: DOWN event in
state UP
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] device is now in state
DOWN
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] error writing len 16
frame to bypass: Network is down
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] link: DOWN event
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] LCP: Down event
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] LCP: state change
Opened --> Starting
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] LCP: phase shift
NETWORK --> DEAD
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] setting interface ng0
MTU to 1500 bytes
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] up: 0 links, total
bandwidth 9600 bps
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] IPCP: Down event
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] IPCP: state change
Opened --> Starting
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] IPCP: LayerDown
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] IFACE: Down event
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] exec: /sbin/route delete
0.0.0.0 192.168.100.1
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] exec: /sbin/route delete
82.50.178.13 -iface lo0
Jun 19 00:15:06 scaffandro.entropy.org mpd: [pppoe] exec: /sbin/ifconfig ng0
down delete -link0
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] LCP: LayerDown
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto IP while
dead
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] device: OPEN event in
state DOWN
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] pausing 5 seconds before
open
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] device is now in state
DOWN
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto IP while
dead
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto LCP while
dead
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto IP while
dead
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto LCP while
dead
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto IP while
dead
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto LCP while
dead
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto IP while
dead
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto LCP while
dead
Jun 19 00:15:07 scaffandro.entropy.org mpd: [pppoe] rec'd proto IP while
dead
Jun 19 00:15:09 scaffandro.entropy.org mpd: [pppoe] closing link "pppoe"...
Jun 19 00:15:09 scaffandro.entropy.org mpd: [pppoe] link: CLOSE event
Jun 19 00:15:09 scaffandro.entropy.org mpd: [pppoe] LCP: Close event
Jun 19 00:15:09 scaffandro.entropy.org mpd: [pppoe] LCP: state change
Starting --> Initial
Jun 19 00:15:09 scaffandro.entropy.org mpd: [pppoe] LCP: LayerFinish
Jun 19 00:15:09 scaffandro.entropy.org mpd: [pppoe] device: CLOSE event in
state DOWN
Jun 19 00:15:09 scaffandro.entropy.org mpd: [pppoe] device is now in state
DOWN
***end***

Any help, observation or comment on the logs is MUCH appreciated!

Gianluca Bosco