DSL (pppoe) connection drops and never reconnects

Hello everyone. Happy Holidays!


I have been experiencing some recent intermittent issues at at least one of my
client's sites where the DSL (PPPoE) connection is dropped, and all attempts
to re-establish the connection by m0n0wall fail until the DSL modem and the
m0n0wall are rebooted.

I have included some logs, I hope the post doesn't end up being too long. I'd
like to know if it is possible to determine who was/is responsible for
initially dropping the connection, and ultimately, why the link can not be
re-established without a reboot of the hardware.

I feel that I must add that I have sometimes seen with WRAP and ALIX boards in
the past that if you physically unplug and replug in an ethernet cable, even
though the link/activity LEDs seem to be indicating all is well, the
connection never comes back up. Same issue seen if switch where the WAN or LAN
connection is connected is power-cycled.

Is this important or relevant to my problem? Not sure.

So, in addition to my PPPoE issue/question, I guess I am also asking: "If the
pppoe connection is lost and mpd "cycles" the interface, could this be the
reason that PPPoE can never be re-established? "

Thanks for any thoughts on this.


Software: m0n0wall v1.3b15
Hardware: PC Engines ALIX (3-port)


Here is where the connection is dropped. This is the first time in the log
where pppoe messages are seen - after the previous reboot which was about
eight days earlier.


--[snip]--
Dec 25 12:20:46 m0n0wall mpd: [pppoe] LCP: no reply to 1 echo request(s)
Dec 25 12:20:46 m0n0wall mpd: [pppoe] LCP: no reply to 2 echo request(s)
Dec 25 12:20:56 m0n0wall mpd: [pppoe] LCP: no reply to 3 echo request(s)
Dec 25 12:21:06 m0n0wall mpd: [pppoe] LCP: no reply to 4 echo request(s)
Dec 25 12:21:16 m0n0wall mpd: [pppoe] LCP: no reply to 5 echo request(s)
Dec 25 12:21:16 m0n0wall mpd: [pppoe] LCP: peer not responding to echo requests
Dec 25 12:21:16 m0n0wall mpd: [pppoe] LCP: state change Opened --> Stopping
Dec 25 12:21:16 m0n0wall mpd: [pppoe] AUTH: Accounting data for user : 722993
seconds, 20675536808 octets in, 1188570556 octets out
Dec 25 12:21:16 m0n0wall mpd: [pppoe] Bundle up: 0 links, total bandwidth 9600 bps
Dec 25 12:21:16 m0n0wall mpd: [pppoe] IPCP: Close event
Dec 25 12:21:16 m0n0wall mpd: [pppoe] IPCP: state change Opened --> Closing
Dec 25 12:21:16 m0n0wall mpd: [pppoe] IPCP: SendTerminateReq #12
Dec 25 12:21:16 m0n0wall mpd: [pppoe] error writing len 8 frame to bypass:
Network is down
Dec 25 12:21:16 m0n0wall mpd: [pppoe] IPCP: LayerDown
Dec 25 12:21:16 m0n0wall mpd: [pppoe] IFACE: Down event
Dec 25 12:21:16 m0n0wall mpd: [pppoe] IPCP: Down event
Dec 25 12:21:16 m0n0wall mpd: [pppoe] IPCP: LayerFinish
Dec 25 12:21:16 m0n0wall mpd: [pppoe] No NCPs left. Closing links...
Dec 25 12:21:16 m0n0wall mpd: [pppoe] closing link "pppoe"...
Dec 25 12:21:16 m0n0wall mpd: [pppoe] IPCP: state change Closing --> Initial
Dec 25 12:21:16 m0n0wall mpd: [pppoe] Last link has gone and no noretry option,
will reopen in 3 seconds
Dec 25 12:21:16 m0n0wall mpd: [pppoe] AUTH: Cleanup
Dec 25 12:21:16 m0n0wall mpd: [pppoe] LCP: SendTerminateReq #8
Dec 25 12:21:16 m0n0wall mpd: [pppoe] LCP: LayerDown
Dec 25 12:21:16 m0n0wall mpd: [pppoe] link: CLOSE event
Dec 25 12:21:16 m0n0wall mpd: [pppoe] LCP: Close event
Dec 25 12:21:16 m0n0wall mpd: [pppoe] LCP: state change Stopping --> Closing
Dec 25 12:21:18 m0n0wall mpd: [pppoe] LCP: SendTerminateReq #9
Dec 25 12:21:19 m0n0wall mpd: [pppoe] Last link has gone and no noretry option,
reopening in 3 seconds
Dec 25 12:21:20 m0n0wall mpd: [pppoe] LCP: state change Closing --> Closed
Dec 25 12:21:20 m0n0wall mpd: [pppoe] LCP: LayerFinish
Dec 25 12:21:20 m0n0wall mpd: [pppoe] link: DOWN event
Dec 25 12:21:20 m0n0wall mpd: [pppoe] LCP: Down event
Dec 25 12:21:20 m0n0wall mpd: [pppoe] LCP: state change Closed --> Initial
--[snip]--


And then this continues until a reboot:

--[snip]--
Dec 25 12:21:22 m0n0wall mpd: [pppoe] opening link "pppoe"...
Dec 25 12:21:22 m0n0wall mpd: [pppoe] link: OPEN event
Dec 25 12:21:22 m0n0wall mpd: [pppoe] LCP: Open event
Dec 25 12:21:22 m0n0wall mpd: [pppoe] LCP: state change Initial --> Starting
Dec 25 12:21:22 m0n0wall mpd: [pppoe] LCP: LayerStart
Dec 25 12:21:22 m0n0wall mpd: [pppoe] pausing 4 seconds before open
Dec 25 12:21:26 m0n0wall mpd: [pppoe] PPPoE: Connecting to ''
Dec 25 12:21:35 m0n0wall mpd: [pppoe] PPPoE connection timeout after 9 seconds
Dec 25 12:21:35 m0n0wall mpd: [pppoe] link: DOWN event
Dec 25 12:21:35 m0n0wall mpd: [pppoe] link: reconnection attempt 1
Dec 25 12:21:35 m0n0wall mpd: [pppoe] LCP: Down event
Dec 25 12:21:35 m0n0wall mpd: [pppoe] pausing 6 seconds before open
Dec 25 12:21:41 m0n0wall mpd: [pppoe] PPPoE: Connecting to ''
Dec 25 12:21:50 m0n0wall mpd: [pppoe] PPPoE connection timeout after 9 seconds
Dec 25 12:21:50 m0n0wall mpd: [pppoe] link: DOWN event
Dec 25 12:21:50 m0n0wall mpd: [pppoe] link: reconnection attempt 2
Dec 25 12:21:50 m0n0wall mpd: [pppoe] LCP: Down event
Dec 25 12:21:50 m0n0wall mpd: [pppoe] pausing 6 seconds before open
Dec 25 12:21:56 m0n0wall mpd: [pppoe] PPPoE: Connecting to ''
Dec 25 12:22:05 m0n0wall mpd: [pppoe] PPPoE connection timeout after 9 seconds
Dec 25 12:22:05 m0n0wall mpd: [pppoe] link: DOWN event
Dec 25 12:22:05 m0n0wall mpd: [pppoe] link: reconnection attempt 3
Dec 25 12:22:05 m0n0wall mpd: [pppoe] LCP: Down event
Dec 25 12:22:05 m0n0wall mpd: [pppoe] pausing 6 seconds before open
Dec 25 12:22:11 m0n0wall mpd: [pppoe] PPPoE: Connecting to ''
Dec 25 12:22:20 m0n0wall mpd: [pppoe] PPPoE connection timeout after 9 seconds
Dec 25 12:22:20 m0n0wall mpd: [pppoe] link: DOWN event
Dec 25 12:22:20 m0n0wall mpd: [pppoe] link: reconnection attempt 4
Dec 25 12:22:20 m0n0wall mpd: [pppoe] LCP: Down event
Dec 25 12:22:20 m0n0wall mpd: [pppoe] pausing 6 seconds before open
Dec 25 12:22:26 m0n0wall mpd: [pppoe] PPPoE: Connecting to ''
Dec 25 12:22:35 m0n0wall mpd: [pppoe] PPPoE connection timeout after 9 seconds
Dec 25 12:22:35 m0n0wall mpd: [pppoe] link: DOWN event
Dec 25 12:22:35 m0n0wall mpd: [pppoe] link: reconnection attempt 5
Dec 25 12:22:35 m0n0wall mpd: [pppoe] LCP: Down event
Dec 25 12:22:35 m0n0wall mpd: [pppoe] pausing 6 seconds before open
--[snip]--


--
Bill Arlofski
Reverse Polarity, LLC