1. pedro Avatar pedro
  2. FreshTomato
  3. freshtomato-arm
  4. Issues

PPPoE connection to ISP can become "stuck" and unable to reconnect again (until router restart)

Issue #330 closed
nuc created an issue

I am getting rarely but repeatedly an issue with my Netgear R6700v3 router.

It happens that the router is unable to make a PPPoE connection. At some point it becomes “stuck” and I am unable to reconnect via pressing reconnect. The connection attempt will not succeed.

After restarting the router it works again.

My setup is an VDSL 250 connection, a Netgear R6700v3 which is connected to a FritzBox 7520 that is working only as a modem (with VLAN 7 configured and PPPoE passthrough).

My router firmware is freshtomato-R6700v3-K26ARM-2024.2-AIO-128K.trx.

‌

I have a barebones setup. Apart from that only wifi 2.4Ghz is configured.

This issue happens every few days so far. I will monitor it further.

‌

This is my first issue, I am a new freshtomato user. If you need more info or I need to do something different please give me a note :)

How should I upload log files? Here is a sample of the “reconnect loop”:

Jul 17 10:33:52 unknown daemon.err pppd[7129]: tdb_store failed: IO Error
Jul 17 10:33:52 unknown daemon.err pppd[7129]: tdb_store failed: IO Error
Jul 17 10:33:52 unknown daemon.err pppd[7129]: tdb_store failed: IO Error
Jul 17 10:33:52 unknown daemon.notice pppd[7129]: local  IP address 92.116.73.207
Jul 17 10:33:52 unknown daemon.notice pppd[7129]: remote IP address 94.134.199.105
Jul 17 10:33:52 unknown daemon.notice pppd[7129]: primary   DNS address 62.220.18.8
Jul 17 10:33:52 unknown daemon.notice pppd[7129]: secondary DNS address 89.246.64.8
Jul 17 10:34:00 unknown user.notice mwwatchdog[7267]: Connection WAN0 DOWN - Reconnect is already in progress ...
Jul 17 10:34:08 unknown daemon.info dnsmasq[6786]: exiting on receipt of SIGTERM
Jul 17 10:34:08 unknown daemon.info dnsmasq[7353]: started, version 2.90-b8ff4bb cachesize 4096
Jul 17 10:34:08 unknown daemon.info dnsmasq[7353]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
Jul 17 10:34:08 unknown daemon.info dnsmasq[7353]: asynchronous logging enabled, queue limit is 5 messages
Jul 17 10:34:08 unknown daemon.info dnsmasq-dhcp[7353]: DHCP, IP range 192.168.1.2 -- 192.168.1.99, lease time 1d
Jul 17 10:34:08 unknown user.warn preinit[1]: service: dnsmasq already running; its PID: 7353
Jul 17 10:34:08 unknown daemon.warn dnsmasq[7353]: no servers found in /etc/resolv.dnsmasq, will retry
Jul 17 10:34:08 unknown daemon.info dnsmasq[7353]: read /etc/hosts - 3 names
Jul 17 10:34:08 unknown daemon.info dnsmasq[7353]: read /etc/hosts.dnsmasq - 7 names
Jul 17 10:34:08 unknown user.notice adblock[6969]: Exiting 1 - Execution time: 00h 00m 23s
Jul 17 10:34:08 unknown user.info preinit[1]: httpd is stopped
Jul 17 10:34:09 unknown user.info preinit[1]: httpd is started
Jul 17 10:34:19 unknown user.info redial[7130]: Redial: wan DOWN. Reconnecting ...
Jul 17 10:34:20 unknown user.notice adblock[7511]: Stopping Adblock/DNS-filtering...
Jul 17 10:34:21 unknown daemon.info pppd[7129]: Terminating on signal 15
Jul 17 10:34:21 unknown daemon.err pppd[7129]: tdb_store failed: IO Error
Jul 17 10:34:21 unknown daemon.err pppd[7129]: tdb_store failed: IO Error
Jul 17 10:34:21 unknown daemon.err pppd[7129]: tdb_store failed: IO Error
Jul 17 10:34:21 unknown daemon.info pppd[7129]: Connect time 0.5 minutes.
Jul 17 10:34:21 unknown daemon.info pppd[7129]: Sent 65065 bytes, received 84929 bytes.
Jul 17 10:34:21 unknown daemon.notice pppd[7129]: Connection terminated.
Jul 17 10:34:21 unknown daemon.err pppd[7129]: tdb_store failed: IO Error
Jul 17 10:34:21 unknown daemon.info pppd[7129]: Sent PADT
Jul 17 10:34:21 unknown daemon.info pppd[7129]: Exit.
Jul 17 10:34:24 unknown user.info preinit[1]: MultiWAN: MWAN is 1 (max 4)
Jul 17 10:34:24 unknown daemon.info pppd[7593]: Plugin rp-pppoe.so loaded.
Jul 17 10:34:24 unknown daemon.info pppd[7593]: RP-PPPoE plugin version 3.15 compiled against pppd 2.4.9
Jul 17 10:34:24 unknown daemon.err pppd[7593]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7593]: tdb_store key failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store key failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7593]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.notice pppd[7594]: pppd 2.4.9 started by root, uid 0
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store key failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store key failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store key failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown user.info redial[7595]: Redial (wan) started, the check interval is 20 seconds
Jul 17 10:34:24 unknown user.err preinit[1]: ERROR in: _ifconfig line: 70 - file "ppp0": No such device
Jul 17 10:34:24 unknown daemon.info pppd[7594]: PPP session is 58936 (0xe638)
Jul 17 10:34:24 unknown daemon.warn pppd[7594]: Connected to 98:9b:cb:70:54:34 via interface vlan2
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.info pppd[7594]: Using interface ppp0
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store key failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store key failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store key failed: IO Error
Jul 17 10:34:24 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:24 unknown daemon.notice pppd[7594]: Connect: ppp0 <--> vlan2
Jul 17 10:34:27 unknown daemon.info pppd[7594]: Remote message: [UI-SBR:250000,40000;UI-LINEID:DEU.DTAG.IB159;]
Jul 17 10:34:27 unknown daemon.notice pppd[7594]: PAP authentication succeeded
Jul 17 10:34:27 unknown daemon.notice pppd[7594]: peer from calling number 98:9B:CB:70:54:34 authorized
Jul 17 10:34:27 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:27 unknown daemon.err pppd[7594]: tdb_store key failed: IO Error
Jul 17 10:34:27 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:27 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:27 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:27 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:27 unknown daemon.notice pppd[7594]: local  IP address 92.116.73.207
Jul 17 10:34:27 unknown daemon.notice pppd[7594]: remote IP address 94.134.199.105
Jul 17 10:34:27 unknown daemon.notice pppd[7594]: primary   DNS address 62.220.18.8
Jul 17 10:34:27 unknown daemon.notice pppd[7594]: secondary DNS address 89.246.64.8
Jul 17 10:34:42 unknown daemon.info dnsmasq[7353]: exiting on receipt of SIGTERM
Jul 17 10:34:42 unknown daemon.info dnsmasq[7658]: started, version 2.90-b8ff4bb cachesize 4096
Jul 17 10:34:42 unknown daemon.info dnsmasq[7658]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
Jul 17 10:34:42 unknown daemon.info dnsmasq[7658]: asynchronous logging enabled, queue limit is 5 messages
Jul 17 10:34:42 unknown daemon.info dnsmasq-dhcp[7658]: DHCP, IP range 192.168.1.2 -- 192.168.1.99, lease time 1d
Jul 17 10:34:42 unknown daemon.warn dnsmasq[7658]: no servers found in /etc/resolv.dnsmasq, will retry
Jul 17 10:34:42 unknown daemon.info dnsmasq[7658]: read /etc/hosts - 3 names
Jul 17 10:34:42 unknown daemon.info dnsmasq[7658]: read /etc/hosts.dnsmasq - 7 names
Jul 17 10:34:43 unknown user.notice adblock[7511]: Exiting 1 - Execution time: 00h 00m 24s
Jul 17 10:34:43 unknown daemon.info dnsmasq[7658]: exiting on receipt of SIGTERM
Jul 17 10:34:43 unknown daemon.info dnsmasq[7677]: started, version 2.90-b8ff4bb cachesize 4096
Jul 17 10:34:43 unknown daemon.info dnsmasq[7677]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
Jul 17 10:34:43 unknown daemon.info dnsmasq[7677]: asynchronous logging enabled, queue limit is 5 messages
Jul 17 10:34:43 unknown daemon.info dnsmasq-dhcp[7677]: DHCP, IP range 192.168.1.2 -- 192.168.1.99, lease time 1d
Jul 17 10:34:43 unknown daemon.warn dnsmasq[7677]: no servers found in /etc/resolv.dnsmasq, will retry
Jul 17 10:34:43 unknown daemon.info dnsmasq[7677]: read /etc/hosts - 3 names
Jul 17 10:34:43 unknown daemon.info dnsmasq[7677]: read /etc/hosts.dnsmasq - 7 names
Jul 17 10:34:43 unknown user.info preinit[1]: httpd is stopped
Jul 17 10:34:44 unknown user.info preinit[1]: httpd is started
Jul 17 10:34:54 unknown user.info redial[7595]: Redial: wan DOWN. Reconnecting ...
Jul 17 10:34:54 unknown user.notice adblock[7829]: Stopping Adblock/DNS-filtering...
Jul 17 10:34:55 unknown daemon.info pppd[7594]: Terminating on signal 15
Jul 17 10:34:55 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:55 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:55 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:55 unknown daemon.info pppd[7594]: Connect time 0.5 minutes.
Jul 17 10:34:55 unknown daemon.info pppd[7594]: Sent 43386 bytes, received 34924 bytes.
Jul 17 10:34:55 unknown daemon.notice pppd[7594]: Connection terminated.
Jul 17 10:34:55 unknown daemon.err pppd[7594]: tdb_store failed: IO Error
Jul 17 10:34:55 unknown daemon.info pppd[7594]: Sent PADT
Jul 17 10:34:55 unknown daemon.info pppd[7594]: Exit.
Jul 17 10:34:59 unknown user.info preinit[1]: MultiWAN: MWAN is 1 (max 4)
Jul 17 10:34:59 unknown daemon.info pppd[7904]: Plugin rp-pppoe.so loaded.
Jul 17 10:34:59 unknown daemon.info pppd[7904]: RP-PPPoE plugin version 3.15 compiled against pppd 2.4.9
Jul 17 10:34:59 unknown daemon.err pppd[7904]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7904]: tdb_store key failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store key failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7904]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.notice pppd[7905]: pppd 2.4.9 started by root, uid 0
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown user.info redial[7906]: Redial (wan) started, the check interval is 20 seconds
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store key failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store key failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store key failed: IO Error
Jul 17 10:34:59 unknown user.err preinit[1]: ERROR in: _ifconfig line: 70 - file "ppp0": No such device
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.info pppd[7905]: PPP session is 2376 (0x948)
Jul 17 10:34:59 unknown daemon.warn pppd[7905]: Connected to 98:9b:cb:70:54:34 via interface vlan2
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.info pppd[7905]: Using interface ppp0
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store key failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store key failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store key failed: IO Error
Jul 17 10:34:59 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:34:59 unknown daemon.notice pppd[7905]: Connect: ppp0 <--> vlan2
Jul 17 10:35:02 unknown daemon.info pppd[7905]: Remote message: [UI-SBR:250000,40000;UI-LINEID:DEU.DTAG.IB159;]
Jul 17 10:35:02 unknown daemon.notice pppd[7905]: PAP authentication succeeded
Jul 17 10:35:02 unknown daemon.notice pppd[7905]: peer from calling number 98:9B:CB:70:54:34 authorized
Jul 17 10:35:02 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:35:02 unknown daemon.err pppd[7905]: tdb_store key failed: IO Error
Jul 17 10:35:02 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:35:02 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:35:02 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:35:02 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:35:02 unknown daemon.notice pppd[7905]: local  IP address 92.116.73.207
Jul 17 10:35:02 unknown daemon.notice pppd[7905]: remote IP address 94.134.199.105
Jul 17 10:35:02 unknown daemon.notice pppd[7905]: primary   DNS address 62.220.18.8
Jul 17 10:35:02 unknown daemon.notice pppd[7905]: secondary DNS address 89.246.64.8
Jul 17 10:35:10 unknown daemon.info dnsmasq-dhcp[7677]: DHCPREQUEST(br0) 192.168.1.31 00:e0:4c:68:04:c9
Jul 17 10:35:10 unknown daemon.info dnsmasq-dhcp[7677]: DHCPACK(br0) 192.168.1.31 00:e0:4c:68:04:c9 DESKTOP-61F1TKH
Jul 17 10:35:17 unknown daemon.info dnsmasq[7677]: exiting on receipt of SIGTERM
Jul 17 10:35:17 unknown daemon.info dnsmasq[8014]: started, version 2.90-b8ff4bb cachesize 4096
Jul 17 10:35:17 unknown daemon.info dnsmasq[8014]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
Jul 17 10:35:17 unknown daemon.info dnsmasq[8014]: asynchronous logging enabled, queue limit is 5 messages
Jul 17 10:35:17 unknown daemon.info dnsmasq-dhcp[8014]: DHCP, IP range 192.168.1.2 -- 192.168.1.99, lease time 1d
Jul 17 10:35:17 unknown daemon.warn dnsmasq[8014]: no servers found in /etc/resolv.dnsmasq, will retry
Jul 17 10:35:17 unknown daemon.info dnsmasq[8014]: read /etc/hosts - 3 names
Jul 17 10:35:17 unknown daemon.info dnsmasq[8014]: read /etc/hosts.dnsmasq - 7 names
Jul 17 10:35:17 unknown daemon.info dnsmasq[8014]: exiting on receipt of SIGTERM
Jul 17 10:35:18 unknown daemon.info dnsmasq[8042]: started, version 2.90-b8ff4bb cachesize 4096
Jul 17 10:35:18 unknown daemon.info dnsmasq[8042]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
Jul 17 10:35:18 unknown daemon.info dnsmasq[8042]: asynchronous logging enabled, queue limit is 5 messages
Jul 17 10:35:18 unknown daemon.info dnsmasq-dhcp[8042]: DHCP, IP range 192.168.1.2 -- 192.168.1.99, lease time 1d
Jul 17 10:35:18 unknown daemon.warn dnsmasq[8042]: no servers found in /etc/resolv.dnsmasq, will retry
Jul 17 10:35:18 unknown daemon.info dnsmasq[8042]: read /etc/hosts - 3 names
Jul 17 10:35:18 unknown daemon.info dnsmasq[8042]: read /etc/hosts.dnsmasq - 7 names
Jul 17 10:35:18 unknown user.info preinit[1]: httpd is stopped
Jul 17 10:35:18 unknown user.notice adblock[7829]: Exiting 1 - Execution time: 00h 00m 24s
Jul 17 10:35:19 unknown user.info preinit[1]: httpd is started
Jul 17 10:35:29 unknown user.info redial[7906]: Redial: wan DOWN. Reconnecting ...
Jul 17 10:35:29 unknown user.notice adblock[8177]: Stopping Adblock/DNS-filtering...
Jul 17 10:35:30 unknown daemon.info pppd[7905]: Terminating on signal 15
Jul 17 10:35:30 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:35:30 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:35:30 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:35:30 unknown daemon.info pppd[7905]: Connect time 0.5 minutes.
Jul 17 10:35:30 unknown daemon.info pppd[7905]: Sent 68074 bytes, received 56704 bytes.
Jul 17 10:35:30 unknown daemon.notice pppd[7905]: Connection terminated.
Jul 17 10:35:30 unknown daemon.err pppd[7905]: tdb_store failed: IO Error
Jul 17 10:35:30 unknown daemon.info pppd[7905]: Sent PADT
Jul 17 10:35:30 unknown daemon.info pppd[7905]: Exit.
Jul 17 10:35:33 unknown user.info preinit[1]: MultiWAN: MWAN is 1 (max 4)
Jul 17 10:35:33 unknown daemon.info pppd[8252]: Plugin rp-pppoe.so loaded.
Jul 17 10:35:33 unknown daemon.info pppd[8252]: RP-PPPoE plugin version 3.15 compiled against pppd 2.4.9
Jul 17 10:35:33 unknown daemon.err pppd[8252]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8252]: tdb_store key failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store key failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8252]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown daemon.notice pppd[8253]: pppd 2.4.9 started by root, uid 0
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store key failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store key failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store key failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:33 unknown user.info redial[8254]: Redial (wan) started, the check interval is 20 seconds
Jul 17 10:35:33 unknown user.err preinit[1]: ERROR in: _ifconfig line: 70 - file "ppp0": No such device
Jul 17 10:35:34 unknown daemon.info pppd[8253]: PPP session is 34884 (0x8844)
Jul 17 10:35:34 unknown daemon.warn pppd[8253]: Connected to 98:9b:cb:70:54:34 via interface vlan2
Jul 17 10:35:34 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:34 unknown daemon.info pppd[8253]: Using interface ppp0
Jul 17 10:35:34 unknown daemon.err pppd[8253]: tdb_store key failed: IO Error
Jul 17 10:35:34 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:34 unknown daemon.err pppd[8253]: tdb_store key failed: IO Error
Jul 17 10:35:34 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:34 unknown daemon.err pppd[8253]: tdb_store key failed: IO Error
Jul 17 10:35:34 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:34 unknown daemon.notice pppd[8253]: Connect: ppp0 <--> vlan2
Jul 17 10:35:37 unknown daemon.info pppd[8253]: Remote message: [UI-SBR:250000,40000;UI-LINEID:DEU.DTAG.IB159;]
Jul 17 10:35:37 unknown daemon.notice pppd[8253]: PAP authentication succeeded
Jul 17 10:35:37 unknown daemon.notice pppd[8253]: peer from calling number 98:9B:CB:70:54:34 authorized
Jul 17 10:35:37 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:37 unknown daemon.err pppd[8253]: tdb_store key failed: IO Error
Jul 17 10:35:37 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:37 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:37 unknown daemon.err pppd[8253]: tdb_store failed: IO Error
Jul 17 10:35:37 unknown daemon.err pppd[8253]: tdb_store failed: IO Error

‌

Comments (15)

  3. M_ars

    hi

    in a first step, can you move the VLAN tagging job to the FreshTomato router (VLAN ID 7) and test again? (remove the VLAN tag from the Fritzbox)

    https://wiki.freshtomato.org/doku.php/advanced-vlan

    ‌

    Another idea/test: Can you test a different VDSL modem? maybe you have an older modem with at least support for VDSL2 profil 17a (100 Mbit/s) ? Worst case you could test with your older ADSL2(+) modem? 🙃

  4. nuc reporter

    Hi @M_ars , I have tried to follow your suggestion. Thank you for pointing it out! However for some reason when I set the VLAN inside freshtomato it just would not work (cannot connect)!

    I have tried to set VID and VLAN to 7, and select “Tag WAN”. But nothing. My ISP says to use VLAN 7 and a pbit of 0, which is how I have set it in my FritzBox. So sadly I cannot use this option for some reason.

    ‌

    About testing another modem, yes I could test my old one, which is also a FritzBox but up to 100mbit only. But the issue occurs very rarely (ever 2 days so far roughly), so I would rather avoid it. But if necessary I will test it. I think I doubt that it is the modems fault, because rebooting the modem does not help, reconnecting the WAN cable does not help. But rebooting my Freshtomato router works :)

  5. M_ars

    FritzBox 7520 that is working only as a modem (with VLAN 7 configured and PPPoE passthrough

    ‌

    Strange issue. I never had (and have) problems with “real” modems (or at least hardware&software that support this operations mode) like DrayTek, Asus (some Router + VDSL-Modem devices), D-Link or Siemens or now Fiber-Modems from T-COM

    If i see it right the Fritzbox does (still) officially not support “Modem only Mode”. The router/software wants to establishe its own connection and you can additionally add one → not recommended according to the GUI :)

    ‌

    Also the software of your Fritzbox matters in that case. So maybe this workaround you are using is not working with every release of your Fritzbox software?

    ‌

    ‌

    ‌

  6. nuc reporter

    It is true that using a FritzBox “modem only” is not officially support. Although I struggle to see a difference, when I just disable all other options it is essentially a modem. I suppose it says “not recommended” due to potential security risks.

    I wonder why do you suspect my modem as being at fault but not freshtomato? It is freshtomato that is failing to reconnect, and it will reconnect once I reboot the software.

    Reboting the modem/fritz.box does not help. And there are no logs on the fritzbox which would indicate that anything happened.

    I will still try another fritzbox. Also I could try to install openwrt on that fritzbox (which uses binary driver blobs but still). Maybe it would help. If anything fails I might try buying a draytek, sadly they are very expensive.

    I was hoping maybe it is possible to examine the freshtomato logs to see what is going on.

  7. M_ars 
    I wonder why do you suspect my modem as being at fault but not freshtomato? It is freshtomato that is failing to reconnect, and it will reconnect once I reboot the software.

    What i wanted to say is, that i would also suggest to test a different (“real”) modem. Company AVM does not officially support “Modem only Mode” and therefore it is not 100% sure it will work always.

    Nevertheless not long ago, someone else reported an issue very similar to yours

    Disconnect/Reconnect does not help, I always had to reboot …

    Please test the following images

    https://bitbucket.org/M_ars/freshtomato-arm/downloads/

    1. “…-12072024_pppd_v001-VPN-128K”
    2. “…-22032024-VPN-128K” (only if 12072024 image is making no difference)

    (only VPN images, since you are using no AIO features)

  8. nuc reporter

    Ok thank you for you effort!

    Your image “…-12072024_pppd_v001-VPN-128K” did not help, and I did not try the other one.

    But luckily I was able to completely nail it down by trial and error.

    So I noticed that the connection was super difficult to establish. Basically If I would pull the WAN cable and replug - connection would not establish. When I even press disconnect and then connect - connection would fail.

    However I did notice that despite i could not apparently “fully” establish a connection, that I am already getting an IP adress and gateway IP defined (changing after every attempt!). However in the end it would never show the status “connected” but just stay in “connecting…” status (and finally abort).

    So I had the idea - while I saw it got a new IP adress and gateway IP, to try if I can reach the internet…. hmm nope browser says: DNS issue. Ok so I tried “ping 1.1.1.1” in my terminal, and boom that worked! Ok?? So a problem with DNS connection!

    So by trial and error - or intuitively - I decided to switch off “Check connections every…” in the firmware (my value there was duckduckgo.com, but I’ve also tried 1.1.1.1!), and wow – as soon as I disable that setting the connection establishes immediately!!

    ‌

    So yeah the “Check connections every 1 minute” setting is a 100% the issue for me. For some reason, with this setting, it would only work immediatly after reboot (once, while the cable is connected), and I even had a situation where I had to reboot twice!

    ‌

    I don’t know, what causes the issue internally, but this setting is causing the bug.

  9. M_ars

    So by trial and error - or intuitively - I decided to switch off “Check connections every…” in the firmware (my value there was duckduckgo.com, but I’ve also tried 1.1.1.1!), and wow – as soon as I disable that setting the connection establishes immediately!!

    i just checked the default ones 

    google.com,microsoft.com

    and it worked correct for me. How does that look like on your side?

    ‌

    I don’t know, what causes the issue internally, but this setting is causing the bug.

    Can you enable debugging via cmd-line?

    nvram set mwanwatchdog_debug=1
nvram set mwan_debug=1
nvram commit

    After saving and re-connect, you will get more infos at syslog

    ...
user.debug mwwatchdog[19344]: prefix=WAN0, iface=ppp0, uptime=151, ISGW=1, WEIGHT=1
user.debug mwwatchdog[19344]: ip route add 142.250.186.142 via a.b.c.d dev ppp0
user.debug mwwatchdog[19344]: ip route add 142.250.186.142 dev ppp0 metric 50000
user.debug mwwatchdog[19344]: ip route add 20.70.246.20 via a.b.c.d dev ppp0
user.debug mwwatchdog[19344]: ip route add 20.70.246.20 dev ppp0 metric 50000
user.debug mwwatchdog[19344]: start test for: ppp0 ...
user.debug mwwatchdog[19344]: tracert test result for: ppp0 - OK
user.debug mwwatchdog[19344]: ip route del 142.250.186.142 via a.b.c.d dev ppp0
user.debug mwwatchdog[19344]: ip route del 142.250.186.142 dev ppp0 metric 50000
user.debug mwwatchdog[19344]: ip route del 20.70.246.20 via a.b.c.d dev ppp0
user.debug mwwatchdog[19344]: ip route del 20.70.246.20 dev ppp0 metric 50000
user.debug mwwatchdog[19344]: Connection WAN0 is functioning
...

    ‌

    By default the checker is disabled and i also do not use it (no Multi-WAN setup). The firmware will reconnect after interruption

    ‌

    ‌

  10. nuc reporter

    Ok I just had to rewrite everything again because I had not internet…

    Basically this is the log, it will loop all the time:

    daemon.info pppd[21037]: Connect time 0.5 minutes.
daemon.info pppd[21037]: Sent 32609 bytes, received 115316 bytes.
daemon.notice pppd[21037]: Connection terminated.
daemon.err pppd[21037]: tdb_store failed: Corrupt database
daemon.info pppd[21037]: Sent PADT
daemon.info pppd[21037]: Exit.
user.info preinit[1]: MultiWAN: MWAN is 1 (max 4)
daemon.info pppd[21471]: Plugin rp-pppoe.so loaded.
daemon.info pppd[21471]: RP-PPPoE plugin version 3.15 compiled against pppd 2.4.9
daemon.err pppd[21471]: tdb_store failed: Corrupt database
daemon.err pppd[21471]: tdb_store key failed: Corrupt database
daemon.err pppd[21472]: tdb_store key failed: Corrupt database
daemon.err pppd[21471]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.notice pppd[21472]: pppd 2.4.9 started by root, uid 0
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store key failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store key failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store key failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
user.info redial[21473]: Redial (wan) started, the check interval is 20 seconds
user.err preinit[1]: ERROR in: _ifconfig line: 70 - file "ppp0": No such device
daemon.info pppd[21472]: PPP session is 8128 (0x1fc0)
daemon.warn pppd[21472]: Connected to 98:9b:cb:70:54:34 via interface vlan2
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.info pppd[21472]: Using interface ppp0
daemon.err pppd[21472]: tdb_store key failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store key failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store key failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.notice pppd[21472]: Connect: ppp0 <--> vlan2
daemon.info pppd[21472]: Remote message: [UI-SBR:250000,40000;UI-LINEID:DEU.DTAG.IB159;]
daemon.notice pppd[21472]: PAP authentication succeeded
daemon.notice pppd[21472]: peer from calling number 98:9B:CB:70:54:34 authorized
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store key failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.notice pppd[21472]: local  IP address 92.116.74.88
daemon.notice pppd[21472]: remote IP address 94.134.199.105
daemon.notice pppd[21472]: primary   DNS address 62.220.18.8
daemon.notice pppd[21472]: secondary DNS address 89.246.64.8
syslog.info root: -- MARK --
user.debug mwwatchdog[21560]: prefix=WAN0, iface=ppp0, uptime=0, ISGW=1, WEIGHT=1
user.debug mwwatchdog[21560]: start test for: ppp0 ...
user.debug mwwatchdog[21560]: tracert test result for: ppp0 - FAILED!
user.notice mwwatchdog[21560]: Connection WAN0 DOWN - Reconnect is already in progress ...
daemon.info dnsmasq[21197]: exiting on receipt of SIGTERM
daemon.info dnsmasq[21696]: started, version 2.90-b8ff4bb cachesize 4096
daemon.info dnsmasq[21696]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
daemon.info dnsmasq[21696]: asynchronous logging enabled, queue limit is 5 messages
daemon.info dnsmasq-dhcp[21696]: DHCP, IP range 192.168.1.2 -- 192.168.1.99, lease time 1d
daemon.warn dnsmasq[21696]: no servers found in /etc/resolv.dnsmasq, will retry
daemon.info dnsmasq[21696]: read /etc/hosts - 3 names
daemon.info dnsmasq[21696]: read /etc/hosts.dnsmasq - 7 names
daemon.info dnsmasq[21696]: exiting on receipt of SIGTERM
daemon.info dnsmasq[21717]: started, version 2.90-b8ff4bb cachesize 4096
daemon.info dnsmasq[21717]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
daemon.info dnsmasq[21717]: asynchronous logging enabled, queue limit is 5 messages
daemon.info dnsmasq-dhcp[21717]: DHCP, IP range 192.168.1.2 -- 192.168.1.99, lease time 1d
daemon.warn dnsmasq[21717]: no servers found in /etc/resolv.dnsmasq, will retry
daemon.info dnsmasq[21717]: read /etc/hosts - 3 names
daemon.info dnsmasq[21717]: read /etc/hosts.dnsmasq - 7 names
user.info preinit[1]: httpd is stopped
user.notice adblock[21376]: Exiting 1 - Execution time: 00h 00m 23s
user.info preinit[1]: httpd is started
user.info redial[21473]: Redial: wan DOWN. Reconnecting ...
user.notice adblock[21894]: Stopping Adblock/DNS-filtering...
daemon.info pppd[21472]: Terminating on signal 15
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.info pppd[21472]: Connect time 0.5 minutes.
daemon.info pppd[21472]: Sent 69747 bytes, received 67010 bytes.
daemon.notice pppd[21472]: Connection terminated.
daemon.err pppd[21472]: tdb_store failed: Corrupt database
daemon.info pppd[21472]: Sent PADT
daemon.info pppd[21472]: Exit.

    The only debug messages I can find are:

    user.debug mwwatchdog[21560]: prefix=WAN0, iface=ppp0, uptime=0, ISGW=1, WEIGHT=1
user.debug mwwatchdog[21560]: start test for: ppp0 ...
user.debug mwwatchdog[21560]: tracert test result for: ppp0 - FAILED!

    Maybe it helps. I have set the “Check connection” setting to google.com and microsoft.com, but that did not change the results.

    After disabling that setting, my router connects almost immediately.

    Kind of ironic that an option to ensure uptime would cause the opposite

    :)

  11. M_ars

    important for you is the “Connect Mode” (see picture). Option “Keep Alive” will redial if the connection breaks/interrupts

    I dont think you need in additon the MultiWAN checker. “Keep Alive” setting will check your connection / Wan-up every xyz seconds (see Redial Interval) in the background.

    ‌

    ‌

    ‌

    ‌

  12. nuc reporter

    @M_ars which device have you been testing?

    I have just tested a R7000 and it does not exhibit the multiWAN reconnection issue (as the R6700v3 does). Same Freshtomato release, same config as far as I can see.

    ‌

    The VLAN issue remains.

  13. M_ars

    So to summarize:

    Issue 1: “stuck and unable to reconnect again until router reboot” → both router, R7000 & R6700v3

    Issue 2: “multiWAN checker” problem only with R6700v3 (R7000 is OK)

    On my side i only have a R7000 + huawei fiber modem. I have no Fritzboxes … and VDSL-Modem not possible for me anymore to test, cable/line is down

    I would suggest to test a “real” xDSL Modem and no Fritzbox. Like i said, we have no insight to this blackbox router fritzbox

    Maybe a different idea: you could use your R7000 router and flash back to latest Netgear original FW. Same problem? with Fritzbox modem only mode workaround + Netgeare R7000 Firmware?

  14. nuc reporter

    No, that is not correct:

    Issue 1: “stuck and unable to reconnect again until router reboot” is the same as “multiWAN checker problem” → only under R6700v3

    Issue 2: Cannot connect to ISP via VLAN 7. I have to use VLAN 7 in the fritz!box. I will not test this by buying a different modem, because I literally don’t care whether my fritzbox or my router provides the VLAN. Also personally I do not believe it is related to my fritzbox, as other users are reporting the VLAN tagging issues on their broadcom based routers as well (eg https://www.reddit.com/r/TomatoFTW/comments/10aitqs/need_help_with_simple_vlan_using_physical_port_on/j492u1b/)

    ‌

    Maybe a different idea: you could use your R7000 router and flash back to latest Netgear original FW. Same problem? with Fritzbox modem only mode workaround + Netgeare R7000 Firmware?

    Like I said, I do not care whether my modem or my router provides the VLAN. I do not see an advantage either way. The VLAN tagging thingy just appeared as a by-product of my testing of my main issue.

    And to re-phrase the main issue is “stuck and unable to reconnect again until router reboot” while “multiWAN checker” is enabled on R6700v3. On R7000 this does not happen.

    And again, personally my issues are solved, since I simply disabled multiWAN checker. But the underlying issue is still there, that is all I am saying.

    ‌

  16. Log in to comment
Assignee
–
Type
bug
Priority
major
Status
closed
Votes
0
Watchers
1
Jira: the preferred issue tracker for Bitbucket. Join the team!