Periodic loss of internet connection

Dear Community,

I face some disconnection issues, they last only for a minute or two, but they occur about once an hour. I give you the logs and my setup for reviewing, I hope I did something basic wrong. I am grateful about any tips.
Before changing to the TO I had a fritzbox (AVM router+modem) in place which didn’t cause any troubles and these kinds of connection disruptions.
Running the connection test I get a failed IPv4 gateway connectivity and failed IPv6 connectitiy and gateway connectivity test. IPv6 is not used with my ISP I guess, that checks.

From what I am reading off this logs I see the router is checking the connection and didn’t get a response to the 5 echo-requests, the router thinks the connection is down. What happens after that I do not fully understand, I greatly appriciate any explanations. I am relativly new to this topic and eager to learn something. A ton of time googleing unfortunetly didn’t get me anywhere.

my setup:
Turris Omnia 2020,
ALLNET ALL4781V Mini GBIC, VDSL2 SFP modem

PPPoE connection using eth2.7 interface
Kernel Log:
[185439.021117] IPv6: ADDRCONF(NETDEV_UP): eth2.7: link is not ready
[185439.077750] mvneta f1034000.ethernet eth2: Link is Down
[185439.092007] mvneta f1034000.ethernet eth2: configuring for inband/1000base-x link mode
[185439.101743] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
[185454.432714] pppoe-wan: renamed from ppp0
System Log:
Jan 12 19:10:51 turris pppd[10383]: No response to 5 echo-requests
Jan 12 19:10:51 turris pppd[10383]: Serial link appears to be disconnected.
Jan 12 19:10:51 turris pppd[10383]: Connect time 85.7 minutes.
Jan 12 19:10:51 turris pppd[10383]: Sent 83970678 bytes, received 2786084432 bytes.
Jan 12 19:10:51 turris netifd: Network device ‘pppoe-wan’ link is down
Jan 12 19:10:51 turris netifd: Network alias ‘pppoe-wan’ link is down
Jan 12 19:10:51 turris netifd: Interface ‘wan6’ has link connectivity loss
Jan 12 19:10:51 turris netifd: Interface ‘wan6’ is now down
Jan 12 19:10:51 turris netifd: Interface ‘wan6’ is disabled
Jan 12 19:10:51 turris netifd: Interface ‘wan6’ is enabled
Jan 12 19:10:51 turris netifd: Interface ‘wan’ has lost the connection
Jan 12 19:10:51 turris netifd: Interface ‘wan6’ is disabled
Jan 12 19:10:57 turris pppd[10383]: Connection terminated.
Jan 12 19:10:57 turris pppd[10383]: Sent PADT
Jan 12 19:10:57 turris pppd[10383]: Modem hangup
Jan 12 19:10:57 turris pppd[10383]: Exit.
Jan 12 19:10:57 turris netifd: Interface ‘wan’ is now down
Jan 12 20:10:57 turris kernel: [178152.675125] IPv6: ADDRCONF(NETDEV_UP): eth2.7: link is not ready
Jan 12 19:10:57 turris netifd: Interface ‘wan’ is disabled
Jan 12 20:10:57 turris kernel: [178152.711223] mvneta f1034000.ethernet eth2: Link is Down
Jan 12 20:10:57 turris kernel: [178152.725142] mvneta f1034000.ethernet eth2: configuring for inband/1000base-x link mode
Jan 12 20:10:57 turris kernel: [178152.733282] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 12 19:10:57 turris netifd: Interface ‘wan’ is enabled
Jan 12 19:10:57 turris netifd: Interface ‘wan’ is setting up now
Jan 12 19:10:57 turris insmod: module is already loaded - slhc
Jan 12 19:10:57 turris insmod: module is already loaded - ppp_generic
Jan 12 19:10:57 turris insmod: module is already loaded - pppox
Jan 12 19:10:57 turris insmod: module is already loaded - pppoe
Jan 12 19:10:57 turris netifd: wan (17517): ppp: warning: Sleeping for ‘10’ seconds
Jan 12 19:11:01 turris crond[17781]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Jan 12 19:11:01 turris crond[17780]: (root) CMDEND (/usr/bin/rainbow_button_sync.sh)
Jan 12 19:11:07 turris pppd[17794]: Plugin rp-pppoe.so loaded.
Jan 12 19:11:07 turris pppd[17794]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Jan 12 19:11:07 turris pppd[17794]: pppd 2.4.7 started by root, uid 0
Jan 12 19:11:22 turris pppd[17794]: Timeout waiting for PADO packets
Jan 12 19:11:22 turris pppd[17794]: Unable to complete PPPoE Discovery
Jan 12 19:11:22 turris pppd[17794]: Exit.
Jan 12 19:11:22 turris netifd: Interface ‘wan’ is now down
Jan 12 20:11:22 turris kernel: [178177.992478] IPv6: ADDRCONF(NETDEV_UP): eth2.7: link is not ready
Jan 12 19:11:22 turris netifd: Interface ‘wan’ is disabled
Jan 12 20:11:22 turris kernel: [178178.030488] mvneta f1034000.ethernet eth2: Link is Down
Jan 12 20:11:22 turris kernel: [178178.043680] mvneta f1034000.ethernet eth2: configuring for inband/1000base-x link mode
Jan 12 20:11:22 turris kernel: [178178.051804] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 12 19:11:22 turris netifd: Interface ‘wan’ is enabled
Jan 12 19:11:22 turris netifd: Interface ‘wan’ is setting up now
Jan 12 19:11:22 turris insmod: module is already loaded - slhc
Jan 12 19:11:22 turris insmod: module is already loaded - ppp_generic
Jan 12 19:11:22 turris insmod: module is already loaded - pppox
Jan 12 19:11:22 turris insmod: module is already loaded - pppoe
Jan 12 19:11:22 turris netifd: wan (17853): ppp: warning: Sleeping for ‘10’ seconds
Jan 12 19:11:32 turris pppd[18127]: Plugin rp-pppoe.so loaded.
Jan 12 19:11:32 turris pppd[18127]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Jan 12 19:11:32 turris pppd[18127]: pppd 2.4.7 started by root, uid 0
Jan 12 20:11:34 turris dnsmasq-dhcp[13596]: DHCPDISCOVER(br-lan)
Jan 12 20:11:34 turris dnsmasq-dhcp[13596]: DHCPOFFER(br-lan)
Jan 12 20:11:34 turris dnsmasq-dhcp[13596]: DHCPREQUEST(br-lan)
Jan 12 20:11:34 turris dnsmasq-dhcp[13596]: DHCPACK(br-lan)
Jan 12 19:11:35 turris /dhcp_host_domain_ng.py: DHCP update hostname []
Jan 12 19:11:35 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Jan 12 19:11:47 turris pppd[18127]: Timeout waiting for PADO packets
Jan 12 19:11:47 turris pppd[18127]: Unable to complete PPPoE Discovery
Jan 12 19:11:47 turris pppd[18127]: Exit.
Jan 12 19:11:47 turris netifd: Interface ‘wan’ is now down
Jan 12 20:11:47 turris kernel: [178203.341154] IPv6: ADDRCONF(NETDEV_UP): eth2.7: link is not ready
Jan 12 19:11:47 turris netifd: Interface ‘wan’ is disabled
Jan 12 20:11:47 turris kernel: [178203.418312] mvneta f1034000.ethernet eth2: Link is Down
Jan 12 20:11:47 turris kernel: [178203.432744] mvneta f1034000.ethernet eth2: configuring for inband/1000base-x link mode
Jan 12 20:11:48 turris kernel: [178203.441229] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 12 19:11:48 turris netifd: Interface ‘wan’ is enabled
Jan 12 19:11:48 turris netifd: Interface ‘wan’ is setting up now
Jan 12 19:11:48 turris insmod: module is already loaded - slhc
Jan 12 19:11:48 turris insmod: module is already loaded - ppp_generic
Jan 12 19:11:48 turris insmod: module is already loaded - pppox
Jan 12 19:11:48 turris insmod: module is already loaded - pppoe
Jan 12 19:11:48 turris netifd: wan (18221): ppp: warning: Sleeping for ‘10’ seconds
Jan 12 19:11:58 turris pppd[18471]: Plugin rp-pppoe.so loaded.
Jan 12 19:11:58 turris pppd[18471]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Jan 12 19:11:58 turris pppd[18471]: pppd 2.4.7 started by root, uid 0
Jan 12 19:12:01 turris crond[18486]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Jan 12 19:12:01 turris crond[18487]: (root) CMD (/usr/sbin/logrotate -s /tmp/logrotate.state /etc/logrotate.conf)
Jan 12 19:12:01 turris crond[18485]: (root) CMDEND (/usr/sbin/logrotate -s /tmp/logrotate.state /etc/logrotate.conf)
Jan 12 19:12:01 turris crond[18484]: (root) CMDEND (/usr/bin/rainbow_button_sync.sh)
Jan 12 19:12:13 turris pppd[18471]: Timeout waiting for PADO packets
Jan 12 19:12:13 turris pppd[18471]: Unable to complete PPPoE Discovery
Jan 12 19:12:13 turris pppd[18471]: Exit.
Jan 12 19:12:13 turris netifd: Interface ‘wan’ is now down
Jan 12 20:12:13 turris kernel: [178228.710157] IPv6: ADDRCONF(NETDEV_UP): eth2.7: link is not ready
Jan 12 19:12:13 turris netifd: Interface ‘wan’ is disabled
Jan 12 20:12:13 turris kernel: [178228.766567] mvneta f1034000.ethernet eth2: Link is Down
Jan 12 20:12:13 turris kernel: [178228.782346] mvneta f1034000.ethernet eth2: configuring for inband/1000base-x link mode
Jan 12 20:12:13 turris kernel: [178228.790449] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 12 19:12:13 turris netifd: Interface ‘wan’ is enabled
Jan 12 19:12:13 turris netifd: Interface ‘wan’ is setting up now
Jan 12 19:12:13 turris insmod: module is already loaded - slhc
Jan 12 19:12:13 turris insmod: module is already loaded - ppp_generic
Jan 12 19:12:13 turris insmod: module is already loaded - pppox
Jan 12 19:12:13 turris insmod: module is already loaded - pppoe
Jan 12 19:12:13 turris netifd: wan (18563): ppp: warning: Sleeping for ‘10’ seconds
Jan 12 19:12:23 turris pppd[18820]: Plugin rp-pppoe.so loaded.
Jan 12 19:12:23 turris pppd[18820]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Jan 12 19:12:23 turris pppd[18820]: pppd 2.4.7 started by root, uid 0
Jan 12 19:12:33 turris pppd[18820]: PPP session is 3945
Jan 12 19:12:33 turris pppd[18820]: Connected to [] via interface eth2.7
Jan 12 20:12:33 turris kernel: [178249.175877] pppoe-wan: renamed from ppp0
Jan 12 19:12:33 turris pppd[18820]: Renamed interface ppp0 to pppoe-wan
Jan 12 19:12:33 turris pppd[18820]: Using interface pppoe-wan
Jan 12 19:12:33 turris pppd[18820]: Connect: pppoe-wan <–> eth2.7
Jan 12 19:12:33 turris pppd[18820]: PAP authentication succeeded
Jan 12 19:12:33 turris pppd[18820]: peer from calling number [] authorized
Jan 12 19:12:34 turris pppd[18820]: local IP address []
Jan 12 19:12:34 turris pppd[18820]: remote IP address []
Jan 12 19:12:34 turris pppd[18820]: primary DNS address []
Jan 12 19:12:34 turris pppd[18820]: secondary DNS address []
Jan 12 19:12:34 turris netifd: Network device ‘pppoe-wan’ link is up
Jan 12 19:12:34 turris netifd: Interface ‘wan6’ is enabled
Jan 12 19:12:34 turris netifd: Network alias ‘pppoe-wan’ link is up
Jan 12 19:12:34 turris netifd: Interface ‘wan6’ has link connectivity
Jan 12 19:12:34 turris netifd: Interface ‘wan6’ is setting up now
Jan 12 19:12:34 turris netifd: Interface ‘wan6’ is now up
Jan 12 19:12:34 turris netifd: Interface ‘wan’ is now up
Jan 12 19:12:34 turris firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Jan 12 19:12:37 turris firewall: Reloading firewall due to ifup of wan6 (pppoe-wan)

thanks,
Peter

there were problems reconnecting pppoe links, one of solution was adding little delay before reconnect:

config interface ‘wan’
option pppd_sleep ‘5’

you can search in forum.

And check temp of router, some (all?) sfp modems can run a bit hot causing all kind off strange issues

Thanks, it helped a lot to have the right terms to search for. I will try some of the proposals within a few days, hopefully it solves the issue.

I will try to get the ‘collecttd’ plugin running to check that in numbers. Judging from touching it it is hotter than I am comfortable with, but not screaming hot. So around 50°C I would guess.
I will try to change the AVM modem+router to modem only mode and deinstall the SFP, maybe this works so it could be an temp issue. But wouldn’t SFP modules almost always have this kind of problem, since they are passive cooled?

If you sfp supports diag you can ethtool -m to se temp, and other interresting stuff.

ethtool -m INTERFACE | grep temperature

Before the disconnect message, the log always shows a message that crond has run the rainbow_button_sync.sh script. Try removing the rainbow file from /etc/crond.d. Restart turris and observe if the eth2 link disconnection continues. This solution helped me. Please report back if this solved your problem.

this happens every minute and most probably has nothing to do with disconnecting.

I get ocassional reboots in the night, after my ISP disconnects me (in 24h intervals), it’s mentioned elsewhere in forum