Is it my ISP or OpenWrt on my CPE?

Installing and Using OpenWrt
1

I am on an inexpensive fiber to the premises internet plan and do not expect wonders. However, the service I am getting is not what the company advertised. I installed OpenWrt to monitor the connection and installed collectd-mod-ping.

The graphing is consistent with the quality of my connection, however I also looked into the system log and see a lot of issues about the WAN, for instance

Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' has link connectivity loss
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is now down
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is disabled
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is enabled

Is this because of OpenWrt on my raspberry Pi or is the problem with my ISP? Here's a fuller log with IP addreses redacted. Before I contact my ISP and make a case with the local telecommunications obudsman, I want to be sure that it's not my equipment:

Sat Jul  2 11:36:13 2022 user.notice firewall: Reloading firewall due to ifup of WAN (pppoe-WAN)
Sat Jul  2 11:44:59 2022 authpriv.info dropbear[6445]: Child connection from 192.168.1.180:52410
Sat Jul  2 11:45:04 2022 authpriv.notice dropbear[6445]: Password auth succeeded for 'root' from 192.168.1.180:52410
Sat Jul  2 11:45:55 2022 daemon.info pppd[5919]: LCP terminated by peer
Sat Jul  2 11:45:55 2022 daemon.info pppd[5919]: Connect time 9.7 minutes.
Sat Jul  2 11:45:55 2022 daemon.info pppd[5919]: Sent 82176425 bytes, received 20378137 bytes.
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Network device 'pppoe-WAN' link is down
Sat Jul  2 11:45:55 2022 daemon.notice pppd[5919]: Modem hangup
Sat Jul  2 11:45:55 2022 daemon.notice pppd[5919]: Connection terminated.
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Interface 'WAN' has lost the connection
Sat Jul  2 11:45:55 2022 daemon.warn dnsmasq[2253]: no servers found in /tmp/resolv.conf.d/resolv.conf.auto, will retry
Sat Jul  2 11:45:55 2022 daemon.info pppd[5919]: Sent PADT
Sat Jul  2 11:45:55 2022 daemon.info pppd[5919]: Exit.
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Interface 'WAN' is now down
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Interface 'WAN' is disabled
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Interface 'WAN' is enabled
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Interface 'WAN' is setting up now
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Network device 'eth1' link is down
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Interface 'WAN' has link connectivity loss
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Interface 'WAN' is now down
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Interface 'WAN' is disabled
Sat Jul  2 11:45:55 2022 daemon.notice netifd: Interface 'WAN' is enabled
Sat Jul  2 11:45:59 2022 kern.info kernel: [15888.955257] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Network device 'eth1' link is up
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' has link connectivity
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is setting up now
Sat Jul  2 11:45:59 2022 kern.info kernel: [15888.962420] r8152 2-2:1.0 eth1: carrier on
Sat Jul  2 11:45:59 2022 daemon.err insmod: module is already loaded - slhc
Sat Jul  2 11:45:59 2022 daemon.err insmod: module is already loaded - ppp_generic
Sat Jul  2 11:45:59 2022 daemon.err insmod: module is already loaded - pppox
Sat Jul  2 11:45:59 2022 daemon.err insmod: module is already loaded - pppoe
Sat Jul  2 11:45:59 2022 daemon.info pppd[6569]: Plugin rp-pppoe.so loaded.
Sat Jul  2 11:45:59 2022 daemon.info pppd[6569]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.8
Sat Jul  2 11:45:59 2022 daemon.notice pppd[6569]: pppd 2.4.8 started by root, uid 0
Sat Jul  2 11:45:59 2022 daemon.err pppd[6569]: PADS: System-Error: No resources
Sat Jul  2 11:45:59 2022 daemon.err pppd[6569]: PADS: System-Error: No resources
Sat Jul  2 11:45:59 2022 daemon.err pppd[6569]: PADS: System-Error: No resources
Sat Jul  2 11:45:59 2022 daemon.warn pppd[6569]: Timeout waiting for PADS packets
Sat Jul  2 11:45:59 2022 daemon.err pppd[6569]: Unable to complete PPPoE Discovery
Sat Jul  2 11:45:59 2022 daemon.info pppd[6569]: Exit.
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is now down
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is disabled
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is enabled
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is setting up now
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Network device 'eth1' link is down
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' has link connectivity loss
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is now down
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is disabled
Sat Jul  2 11:45:59 2022 daemon.notice netifd: Interface 'WAN' is enabled
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Network device 'eth1' link is up
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' has link connectivity
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' is setting up now
Sat Jul  2 11:46:03 2022 kern.info kernel: [15893.006949] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Sat Jul  2 11:46:03 2022 kern.info kernel: [15893.013866] r8152 2-2:1.0 eth1: carrier on
Sat Jul  2 11:46:03 2022 daemon.err insmod: module is already loaded - slhc
Sat Jul  2 11:46:03 2022 daemon.err insmod: module is already loaded - ppp_generic
Sat Jul  2 11:46:03 2022 daemon.err insmod: module is already loaded - pppox
Sat Jul  2 11:46:03 2022 daemon.err insmod: module is already loaded - pppoe
Sat Jul  2 11:46:03 2022 daemon.info pppd[6665]: Plugin rp-pppoe.so loaded.
Sat Jul  2 11:46:03 2022 daemon.info pppd[6665]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.8
Sat Jul  2 11:46:03 2022 daemon.notice pppd[6665]: pppd 2.4.8 started by root, uid 0
Sat Jul  2 11:46:03 2022 daemon.err pppd[6665]: PADS: System-Error: No resources
Sat Jul  2 11:46:03 2022 daemon.err pppd[6665]: PADS: System-Error: No resources
Sat Jul  2 11:46:03 2022 daemon.err pppd[6665]: PADS: System-Error: No resources
Sat Jul  2 11:46:03 2022 daemon.warn pppd[6665]: Timeout waiting for PADS packets
Sat Jul  2 11:46:03 2022 daemon.err pppd[6665]: Unable to complete PPPoE Discovery
Sat Jul  2 11:46:03 2022 daemon.info pppd[6665]: Exit.
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' is now down
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' is disabled
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' is enabled
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' is setting up now
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Network device 'eth1' link is down
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' has link connectivity loss
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' is now down
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' is disabled
Sat Jul  2 11:46:03 2022 daemon.notice netifd: Interface 'WAN' is enabled
Sat Jul  2 11:46:06 2022 daemon.notice netifd: Network device 'eth1' link is up
Sat Jul  2 11:46:06 2022 daemon.notice netifd: Interface 'WAN' has link connectivity
Sat Jul  2 11:46:06 2022 daemon.notice netifd: Interface 'WAN' is setting up now
Sat Jul  2 11:46:06 2022 kern.info kernel: [15896.830356] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Sat Jul  2 11:46:06 2022 kern.info kernel: [15896.837216] r8152 2-2:1.0 eth1: carrier on
Sat Jul  2 11:46:06 2022 daemon.err insmod: module is already loaded - slhc
Sat Jul  2 11:46:06 2022 daemon.err insmod: module is already loaded - ppp_generic
Sat Jul  2 11:46:06 2022 daemon.err insmod: module is already loaded - pppox
Sat Jul  2 11:46:06 2022 daemon.err insmod: module is already loaded - pppoe
Sat Jul  2 11:46:06 2022 daemon.info pppd[6760]: Plugin rp-pppoe.so loaded.
Sat Jul  2 11:46:06 2022 daemon.info pppd[6760]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.8
Sat Jul  2 11:46:06 2022 daemon.notice pppd[6760]: pppd 2.4.8 started by root, uid 0
Sat Jul  2 11:46:07 2022 daemon.info pppd[6760]: PPP session is 375
Sat Jul  2 11:46:07 2022 daemon.warn pppd[6760]: Connected to 40:de:ad:1c:24:0c via interface eth1
Sat Jul  2 11:46:07 2022 kern.info kernel: [15896.953914] pppoe-WAN: renamed from ppp0
Sat Jul  2 11:46:07 2022 daemon.info pppd[6760]: Renamed interface ppp0 to pppoe-WAN
Sat Jul  2 11:46:07 2022 daemon.info pppd[6760]: Using interface pppoe-WAN
Sat Jul  2 11:46:07 2022 daemon.notice pppd[6760]: Connect: pppoe-WAN <--> eth1
Sat Jul  2 11:46:07 2022 daemon.notice pppd[6760]: PAP authentication succeeded
Sat Jul  2 11:46:07 2022 daemon.notice pppd[6760]: peer from calling number 40:DE:AD:1C:24:0C authorized
Sat Jul  2 11:46:07 2022 daemon.notice pppd[6760]: local  IP address xxx.xx.xx.xx
Sat Jul  2 11:46:07 2022 daemon.notice pppd[6760]: remote IP address xx.xxx.xxx.x
Sat Jul  2 11:46:07 2022 daemon.notice pppd[6760]: primary   DNS address 1.1.1.1
Sat Jul  2 11:46:07 2022 daemon.notice pppd[6760]: secondary DNS address 8.8.8.8
Sat Jul  2 11:46:07 2022 daemon.notice netifd: Network device 'pppoe-WAN' link is up
Sat Jul  2 11:46:07 2022 daemon.notice netifd: Interface 'WAN' is now up
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: reading /tmp/resolv.conf.d/resolv.conf.auto
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using only locally-known addresses for domain test
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using only locally-known addresses for domain onion
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using only locally-known addresses for domain localhost
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using only locally-known addresses for domain local
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using only locally-known addresses for domain invalid
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using only locally-known addresses for domain bind
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using nameserver 8.8.8.8#53
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using nameserver 1.1.1.1#53
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using only locally-known addresses for domain lan
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using nameserver 1.1.1.1#53
Sat Jul  2 11:46:07 2022 daemon.info dnsmasq[2253]: using nameserver 8.8.8.8#53
Sat Jul  2 11:46:07 2022 user.notice firewall: Reloading firewall due to ifup of WAN (pppoe-WAN)

Graphing:

Screen Shot 2022-07-02 at 12.22.54 pm
Screen Shot 2022-07-02 at 12.22.54 pm1522×1158 288 KB

2

Which port did you use for the WAN?
The built in, or the external?

3

The external. Do you advise I change the WAN port to the inbuilt eth0 and make eth1 (currently the WAN) into the LAN port?

4

Try swapping then, in case it's a power issue.

5

I tried going into the web-interface and changing the device on the WAN to the inbuilt ethernet eth0. The LAN's device is br-lan so I went into devices and set the device in there to eth1 (the external). After applying the config, I lost connectivity and was unable to get back in.

Fortunately I had cloned the systems SD card to another and I'm back on, currently cloning the working on onto the original...

Is there a guide to performing is kind of an operation or could you explain it to me?

Considering the output of cat /etc/config/network (with my account and password redacted), it should simply be a matter of changing eth0 to eth1 under br-lan and eth0 to eth1 in the WAN section - shouldn't it?

Imaging the card with the good config takes about 45 minutes, so I'll try that when I can.

root@OpenWrt:~# cat /etc/config/network

config interface 'loopback'
	option device 'lo'
	option proto 'static'
	option ipaddr '127.0.0.1'
	option netmask '255.0.0.0'

config globals 'globals'
	option ula_prefix 'fd40:fe14:d04f::/48'
	option packet_steering '1'

config device
	option name 'br-lan'
	option type 'bridge'
	list ports 'eth0'

config interface 'lan'
	option device 'br-lan'
	option proto 'static'
	option ipaddr '192.168.1.1'
	option netmask '255.255.255.0'
	option ip6assign '60'

config interface 'WAN'
	option proto 'pppoe'
	option device 'eth1'
	option username '<redacted>'
	option password '<redacted>'
	option ipv6 'auto'
6

Yep, just swap eth0<>1 in the file.
Save, do /etc/init.d/network restart

1 Like
7

Thanks, I'll try it when it finishes imaging.

8

Yes!!!! It worked! :smiley:

9

Is there a command available to get the uptime of the WAN connection? I can see it in the LuCI interface but Id like to write a script to log it to a file on a periodic basis.

Edit: ooop, answer is here: How can I check PPPoE uptime via SSH? - #2 by mpa

10

Currently working with the ISP to resolve issues/optimise connection. Thanks @frollic

11

This topic was automatically closed 10 days after the last reply. New replies are no longer allowed.