Reply
New Member
Posts: 3
Registered: ‎08-10-2017

PPPoE link drops and fails to re-establish

[ Edited ]

I've seen these symptoms a few times, but only went investigating today...

I'm currently running an ERLite-3, on v1.9.7, though I've seen the symptoms on at least v1.9.1, possibly earlier.

 

What appears to be happening is the cry of "the internet isn't working" - I'm sure many of you are familiar with.

 

  • My ISP is BT, I'm on an Infinity 72Mb/s package.
  • The VDSL modem (Vigor 130) shows that the link is in SHOWTIME, with good sync rates and SNR margins. (down: 79997Kb/s / up: 19999 Kb/s).
  • The EdgeRouter shows that the pppoe0 link is disconnected.

Logging in to the ER via SSH and running the following appears to resolve the problem:

disconnect interface pppoe0
connect interface pppoe0

Then (this is something I've not looked into before) I had a look into the pppoe0 log:

$ show interfaces pppoe pppoe0 log
[...]
Connected to 24:af:4a:c0:fe:b8 via interface eth2
using channel 3
Using interface ppp0
Connect: ppp0 <--> eth2
sent [LCP ConfReq id=0xc <magic 0xd6b7c0a8>]
rcvd [LCP ConfReq id=0x28 <mru 1500> <auth chap MD5> <magic 0x44d506d1>]
lcp_reqci: returning CONFACK.
sent [LCP ConfAck id=0x28 <mru 1500> <auth chap MD5> <magic 0x44d506d1>]
rcvd [LCP ConfAck id=0xc <magic 0xd6b7c0a8>]
IPCP: Up event in state 2!
rcvd [CHAP Challenge id=0x1 <7ba473abe3926f953a8720eaaf87d1d50958eaf847539bb87f31a7379362b60f072a3aeb3c>, name = "acc-aln1.chl"]
sent [CHAP Response id=0x1 <95648340d2d874590629da878e389176>, name = "bthomehub@btbroadband.com"]
rcvd [CHAP Success id=0x1 "CHAP authentication success"]
CHAP authentication succeeded: CHAP authentication success
CHAP authentication succeeded
peer from calling number 24:AF:4A:C0:FE:B8 authorized
sent [IPCP ConfReq id=0x5 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
rcvd [IPV6CP ConfReq id=0xf2 <addr fe80::0221:05ff:fead:9024>]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0xd 80 57 01 f2 00 0e 01 0a 02 21 05 ff fe ad 90 24]
rcvd [IPCP ConfReq id=0x5 <addr 172.16.16.4>]
ipcp: returning Configure-ACK
sent [IPCP ConfAck id=0x5 <addr 172.16.16.4>]
rcvd [IPCP ConfNak id=0x5 <addr 86.150.246.82> <ms-dns1 81.139.57.100> <ms-dns3 81.139.56.100>]
sent [IPCP ConfReq id=0x6 <addr 86.150.246.82> <ms-dns1 81.139.57.100> <ms-dns3 81.139.56.100>]
rcvd [IPV6CP TermReq id=0xf3]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0xe 80 57 05 f3 00 04]
rcvd [IPCP ConfAck id=0x6 <addr 86.150.246.82> <ms-dns1 81.139.57.100> <ms-dns3 81.139.56.100>]
ipcp: up
Script /etc/ppp/ip-pre-up started (pid 28852)
Script /etc/ppp/ip-pre-up finished (pid 28852), status = 0x0
local  IP address 86.150.246.82
remote IP address 172.16.16.4
primary   DNS address 81.139.57.100
secondary DNS address 81.139.56.100
Script /etc/ppp/ip-up started (pid 28902)
rcvd [IPV6CP TermReq id=0xf4]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0xf 80 57 05 f4 00 04]
Script /etc/ppp/ip-up finished (pid 28902), status = 0x0
No response to 6 echo-requests
Serial link appears to be disconnected.
ipcp: down
Connect time 3.3 minutes.
Sent 577556 bytes, received 876900 bytes.
Script /etc/ppp/ip-down started (pid 29135)
sent [LCP TermReq id=0x10 "Peer not responding"]
sent [LCP TermReq id=0x11 "Peer not responding"]
Script /etc/ppp/ip-down finished (pid 29135), status = 0x0
Connection terminated: no multilink.
Modem hangup
LCP: Down event in state 0!
Connected to 00:1d:aa:8a:7b:6c via interface eth2
using channel 4
Using interface ppp0
Connect: ppp0 <--> eth2
sent [LCP ConfReq id=0x12 <magic 0x131763af>]
sent [LCP ConfReq id=0x12 <magic 0x131763af>]
sent [LCP ConfReq id=0x12 <magic 0x131763af>]
[...]
sent [LCP ConfReq id=0x12 <magic 0x131763af>]
sent [LCP ConfReq id=0x12 <magic 0x131763af>]
sent [LCP ConfReq id=0x12 <magic 0x131763af>]
Fri Jan  9 16:40:43 UTC 2015: User attie stopping PPP daemon for pppoe0 by disconnect command
Terminating on signal 15
sent [LCP TermReq id=0x13 "User request"]
sent [LCP TermReq id=0x14 "User request"]
Connection terminated: no multilink.
Modem hangup
Fri Jan  9 16:40:52 UTC 2015: User attie starting PPP daemon for pppoe0 by connect command
Connected to 24:af:4a:c0:fe:b8 via interface eth2
using channel 5
Using interface ppp0
Connect: ppp0 <--> eth2
sent [LCP ConfReq id=0x1 <magic 0x7be54fdb>]
rcvd [LCP ConfReq id=0x87 <mru 1500> <auth chap MD5> <magic 0x605460c3>]
lcp_reqci: returning CONFACK.
sent [LCP ConfAck id=0x87 <mru 1500> <auth chap MD5> <magic 0x605460c3>]
rcvd [LCP ConfAck id=0x1 <magic 0x7be54fdb>]
sent [LCP EchoReq id=0x0 magic=0x7be54fdb]
rcvd [CHAP Challenge id=0x1 <27e65c0c8e1ba213f4d8cd913a9720f0d07b1739297932004683337c63d7398a3e9516ccb038>, name = "acc-aln1.chl"]
sent [CHAP Response id=0x1 <456de5f95d8284111130fedb485f4357>, name = "bthomehub@btbroadband.com"]
rcvd [LCP EchoRep id=0x0 magic=0x605460c3]
rcvd [CHAP Success id=0x1 "CHAP authentication success"]
CHAP authentication succeeded: CHAP authentication success
CHAP authentication succeeded
peer from calling number 24:AF:4A:C0:FE:B8 authorized
sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
rcvd [IPV6CP ConfReq id=0xe8 <addr fe80::0221:05ff:fead:9024>]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0x2 80 57 01 e8 00 0e 01 0a 02 21 05 ff fe ad 90 24]
rcvd [IPCP ConfReq id=0xc9 <addr 172.16.16.4>]
ipcp: returning Configure-ACK
sent [IPCP ConfAck id=0xc9 <addr 172.16.16.4>]
rcvd [IPCP ConfNak id=0x1 <addr 86.189.232.46> <ms-dns1 81.139.56.100> <ms-dns3 81.139.57.100>]
sent [IPCP ConfReq id=0x2 <addr 86.189.232.46> <ms-dns1 81.139.56.100> <ms-dns3 81.139.57.100>]
rcvd [IPV6CP TermReq id=0xe9]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0x3 80 57 05 e9 00 04]
rcvd [IPCP ConfAck id=0x2 <addr 86.189.232.46> <ms-dns1 81.139.56.100> <ms-dns3 81.139.57.100>]
ipcp: up
Script /etc/ppp/ip-pre-up started (pid 4492)
Script /etc/ppp/ip-pre-up finished (pid 4492), status = 0x0
local  IP address 86.189.232.46
remote IP address 172.16.16.4
primary   DNS address 81.139.56.100
secondary DNS address 81.139.57.100
Script /etc/ppp/ip-up started (pid 4543)
rcvd [IPV6CP TermReq id=0xea]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0x4 80 57 05 ea 00 04]
Script /etc/ppp/ip-up finished (pid 4543), status = 0x0

The "LCP ConfReq" lines are repeated over and over, just after the link is determined to be down - "No response to 6 echo-requests".

 

It looks like it just dumbly continus requesting config, while getting no response back... Could we alter this to fail after ~6 ConfReq attempts, and then fallback to a full down/up?

 

I'm happy to alter scripts to help iron out the problem, but unfortunately this is not a common occurence for me, so we may be here a while!

 

Thanks for any help,

Attie

 

Edit:

I'm upgrading to v1.9.7+hotfix.1 now...

Does anyone know what a ConfReq with ID of 0x12 is asking for?

New Member
Posts: 3
Registered: ‎08-10-2017

Re: PPPoE link drops and fails to re-establish

As a quick follow-on, I've just rebooted the modem while the PPPoE connection was active, and it seems to reconnect with no problems (perhaps the modem is coming back online too quickly...)

 

I did notice however that the following line (that was repeated over and over in the previous log) was suspiciously absent from the log this time:

sent [LCP ConfReq id=0x12 <magic 0x131763af>]

This time, the log skips straight to the reconnect:

Script /etc/ppp/ip-up started (pid 2692)
rcvd [IPV6CP TermReq id=0xee]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0x4 80 57 05 ee 00 04]
Script /etc/ppp/ip-up finished (pid 2692), status = 0x0
No response to 6 echo-requests
Serial link appears to be disconnected.
ipcp: down
Connect time 13.5 minutes.
Sent 1334083 bytes, received 1458379 bytes.
Script /etc/ppp/ip-down started (pid 3908)
sent [LCP TermReq id=0x5 "Peer not responding"]
sent [LCP TermReq id=0x6 "Peer not responding"]
Script /etc/ppp/ip-down finished (pid 3908), status = 0x0
Connection terminated: no multilink.
Modem hangup
LCP: Down event in state 0!
Connected to 24:af:4a:c0:fe:b8 via interface eth2
using channel 2
Using interface ppp0
Connect: ppp0 <--> eth2
sent [LCP ConfReq id=0x7 <magic 0xc9770a4e>]
rcvd [LCP ConfReq id=0xa2 <mru 1500> <auth chap MD5> <magic 0x45c8d148>]
lcp_reqci: returning CONFACK.
sent [LCP ConfAck id=0xa2 <mru 1500> <auth chap MD5> <magic 0x45c8d148>]
rcvd [LCP ConfAck id=0x7 <magic 0xc9770a4e>]
IPCP: Up event in state 2!
New Member
Posts: 1
Registered: ‎05-10-2017

Re: PPPoE link drops and fails to re-establish

[ Edited ]

I'm in almost the exact same boat right now! I called my ISP and they said their logs show 60 disconnects today alone. This has been going on for about 4 days now. Does anyone have any input? Also, I'm on an ER-X currently. 

New Member
Posts: 10
Registered: ‎01-07-2017

Re: PPPoE link drops and fails to re-establish

I've had the same problem twice in about 5 weeks my ER-X is in production.

 

EdgeRouter X v1.9.7+hotfix.4

 

Script /etc/ppp/ip-up started (pid 10139)
Script /etc/ppp/ip-up finished (pid 10139), status = 0x0
rcvd [LCP TermAck id=0x1]
ipcp: down
Connect time 337.3 minutes.
Sent 35369743 bytes, received 141383389 bytes.
Script /etc/ppp/ip-down started (pid 19769)
sent [LCP ConfReq id=0x2f <mru 1492> <magic 0xb94e3485>]
sent [LCP ConfReq id=0x2f <mru 1492> <magic 0xb94e3485>]
Script /etc/ppp/ip-down finished (pid 19769), status = 0x0
sent [LCP ConfReq id=0x2f <mru 1492> <magic 0xb94e3485>]
sent [LCP ConfReq id=0x2f <mru 1492> <magic 0xb94e3485>]
sent [LCP ConfReq id=0x2f <mru 1492> <magic 0xb94e3485>]
sent [LCP ConfReq id=0x2f <mru 1492> <magic 0xb94e3485>]
sent [LCP ConfReq id=0x2f <mru 1492> <magic 0xb94e3485>]
New Member
Posts: 3
Registered: ‎03-13-2018
Kudos: 1

Re: PPPoE link drops and fails to re-establish

Did anyone resolve this?

I am also getting this - modem stays online and in-sync ( draytek 130), but pppoe disconnects and re-connects resulting in a new IP multiple times daily.

 

Edgerouter lite 3:

----------------------------

sent [LCP ProtRej id=0xa 80 57 05 3e 00 04]
Script /etc/ppp/ip-up finished (pid 27751), status = 0x0
No response to 6 echo-requests
Serial link appears to be disconnected.
ipcp: down
Connect time 73.3 minutes.
Sent 788783731 bytes, received 3187454416 bytes.
Script /etc/ppp/ip-down started (pid 26902)
sent [LCP TermReq id=0xb "Peer not responding"]
sent [LCP TermReq id=0xc "Peer not responding"]
Connection terminated: no multilink.
Modem hangup
Script /etc/ppp/ip-down finished (pid 26902), status = 0x0

New Member
Posts: 5
Registered: ‎09-11-2016

Re: PPPoE link drops and fails to re-establish

My connection Drops with this same error of echo  

  1. Script /etc/ppp/ip-up started (pid 1823)
  2. Script /etc/ppp/ip-up finished (pid 1823), status = 0x0
  3. No response to 6 echo-requests
  4. Serial link appears to be disconnected.
  5. ipcp: down
  6. Connect time 503.4 minutes.

someone have a solution?

New Member
Posts: 3
Registered: ‎03-13-2018
Kudos: 1

Re: PPPoE link drops and fails to re-establish

Yes. My isp was bt in the uk and I think that their server goes away for short periods of time. I found a way to edit the conf which is used to build the pppoe interface and edited the number of pings and time between pings to make it longer before the script thinks ppp is down and decides to re-connect.

Problem is I can’t remember the exact method at the moment as I just copied it from another forum post on the open source code which is used for the build.

New Member
Posts: 3
Registered: ‎08-10-2017

Re: PPPoE link drops and fails to re-establish

I've just experienced this again - to give you an idea of the frequency (~ 14 months)...

It may have happened in the meantime, but I don't remember.

 

Unfortunately I was off-site, and thus lost all communication with the systems behind this router until I got back, which was... "not ideal"...

 

This time I saw the following:

 

sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]

 

As before, a disconnect / connect resolved the issue:

 

disconnect interface pppoe0
connect interface pppoe0

 

The outage this time was ~5.5 hours.

 

Script /etc/ppp/ip-up finished (pid 28032), status = 0x0
No response to 6 echo-requests
Serial link appears to be disconnected.
ipcp: down
Connect time 2817.2 minutes.
Sent 1993321421 bytes, received 543302134 bytes.
Script /etc/ppp/ip-down started (pid 17548)
sent [LCP TermReq id=0x5d "Peer not responding"]
sent [LCP TermReq id=0x5e "Peer not responding"]
Connection terminated: no multilink.
Modem hangup
Script /etc/ppp/ip-down finished (pid 17548), status = 0x0
Connected to 00:1d:aa:8a:7b:6c via interface eth2
using channel 18
Using interface ppp0
Connect: ppp0 <--> eth2
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
[...]
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
sent [LCP ConfReq id=0x5f <magic 0xe226f89a>]
Thu Apr 28 11:12:05 UTC 2016: User attie stopping PPP daemon for pppoe0 by disconnect command
Terminating on signal 15
sent [LCP TermReq id=0x60 "User request"]
sent [LCP TermReq id=0x61 "User request"]
Connection terminated: no multilink.
Modem hangup
Thu Apr 28 11:12:34 UTC 2016: User attie starting PPP daemon for pppoe0 by connect command
Connected to 10:e8:78:a7:b9:65 via interface eth2
using channel 19
Using interface ppp0
Connect: ppp0 <--> eth2
sent [LCP ConfReq id=0x1 <magic 0x82d50e53>]
rcvd [LCP ConfReq id=0xef <mru 1500> <auth chap MD5> <magic 0x6fec38ea>]
lcp_reqci: returning CONFACK.
sent [LCP ConfAck id=0xef <mru 1500> <auth chap MD5> <magic 0x6fec38ea>]
rcvd [LCP ConfAck id=0x1 <magic 0x82d50e53>]
sent [LCP EchoReq id=0x0 magic=0x82d50e53]
rcvd [CHAP Challenge id=0x1 <d574496ce47a959db06c3e1bab4361916ee44dc0af38bfc879df7b62691be1be8f>, name = "acc-aln1.chl"]
sent [CHAP Response id=0x1 <afc2b40976c000365247f7d829893728>, name = "bthomehub@btbroadband.com"]
rcvd [LCP EchoRep id=0x0 magic=0x6fec38ea]
rcvd [CHAP Success id=0x1 "CHAP authentication success"]
CHAP authentication succeeded: CHAP authentication success
CHAP authentication succeeded
peer from calling number 10:E8:78:A7:B9:65 authorized
sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
rcvd [IPV6CP ConfReq id=0xb2 <addr fe80::0221:05ff:fead:9024>]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0x2 80 57 01 b2 00 0e 01 0a 02 21 05 ff fe ad 90 24]
rcvd [IPCP ConfReq id=0x8e <addr 172.16.16.4>]
ipcp: returning Configure-ACK
sent [IPCP ConfAck id=0x8e <addr 172.16.16.4>]
rcvd [IPCP ConfNak id=0x1 <addr 86.189.186.58>]
sent [IPCP ConfReq id=0x2 <addr 86.189.186.58>]
rcvd [IPV6CP TermReq id=0xb3]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0x3 80 57 05 b3 00 04]
rcvd [IPCP ConfAck id=0x2 <addr 86.189.186.58>]
ipcp: up
Script /etc/ppp/ip-pre-up started (pid 21919)
Script /etc/ppp/ip-pre-up finished (pid 21919), status = 0x0
local  IP address 86.189.186.58
remote IP address 172.16.16.4
Script /etc/ppp/ip-up started (pid 21984)
rcvd [IPV6CP TermReq id=0xb4]
Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
sent [LCP ProtRej id=0x4 80 57 05 b4 00 04]
Script /etc/ppp/ip-up finished (pid 21984), status = 0x0

Is there any official comment or a progress update on this issue?

Reply