1und1 FRITZ!Box 7412 as modem + Archer C7 OpenWrt as router

Hello. I have a default ISP FRITZ!Box 7412 modem in a bridge mode and TP-Link Archer C7 v5 as PPPoE client and access point. Until Monday it used to work quite well, so I'm pretty sure my ISP did change something.

Now it seems to be broken somehow and I can't figure out, what's wrong. Here's my ISP modem config in the router mode, and it works. Sorry for German, I hope, the general settings are fine.


So, If I switch the modem to a bridge mode, and try to bring up the PPPoE on the OpenWrt router, it fails on the ipv6 dhcp step. Here's the modem bridge config

Router network config

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

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

config globals 'globals'
        option ula_prefix 'fdd7:7c41:9228::/48'

config interface 'lan'
        option type 'bridge'
        option ifname 'eth0.1'
        option proto 'static'
        option ipaddr '192.168.1.1'
        option netmask '255.255.255.0'
        option ip6assign '60'

config interface 'wan'
        option proto 'dhcp'
        option ifname 'eth0.7'

config switch
        option name 'switch0'
        option reset '1'
        option enable_vlan '1'

config switch_vlan
        option device 'switch0'
        option vlan '1'
        option ports '0t 2 3 4 5'
        option vid '1'

config switch_vlan
        option device 'switch0'
        option vlan '2'
        option vid '7'
        option ports '0t 1'

config interface '1und1'
        option proto 'pppoe'
        option password 'hereisthepass'
        option username 'H1und1/id@online.de'
        option ifname 'eth0.7'
        option auto '0'
        option ipv6 'auto'

config interface 'wan6'
        option ifname 'eth0.7'
        option proto 'dhcpv6'
        option reqaddress 'try'
        option reqprefix 'auto'

And logs related to the connection attempt

Wed Dec  2 08:19:56 2020 daemon.notice netifd: Interface '1und1' is setting up now
Wed Dec  2 08:19:56 2020 daemon.err insmod: module is already loaded - slhc
Wed Dec  2 08:19:56 2020 daemon.err insmod: module is already loaded - ppp_generic
Wed Dec  2 08:19:56 2020 daemon.err insmod: module is already loaded - pppox
Wed Dec  2 08:19:56 2020 daemon.err insmod: module is already loaded - pppoe
Wed Dec  2 08:19:56 2020 daemon.info pppd[10815]: Plugin rp-pppoe.so loaded.
Wed Dec  2 08:19:56 2020 daemon.info pppd[10815]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Wed Dec  2 08:19:56 2020 daemon.notice pppd[10815]: pppd 2.4.7 started by root, uid 0
Wed Dec  2 08:19:56 2020 daemon.info pppd[10815]: PPP session is 26846
Wed Dec  2 08:19:56 2020 daemon.warn pppd[10815]: Connected to e8:df:70:9f:b1:34 via interface eth0.7
Wed Dec  2 08:19:56 2020 kern.info kernel: [21990.055857] pppoe-1und1: renamed from ppp0
Wed Dec  2 08:19:56 2020 daemon.info pppd[10815]: Renamed interface ppp0 to pppoe-1und1
Wed Dec  2 08:19:56 2020 daemon.info pppd[10815]: Using interface pppoe-1und1
Wed Dec  2 08:19:56 2020 daemon.notice pppd[10815]: Connect: pppoe-1und1 <--> eth0.7
Wed Dec  2 08:20:00 2020 daemon.info pppd[10815]: Remote message: [UI-SBR:112770,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]
Wed Dec  2 08:20:00 2020 daemon.notice pppd[10815]: PAP authentication succeeded
Wed Dec  2 08:20:00 2020 daemon.notice pppd[10815]: peer from calling number E8:DF:70:9F:B1:34 authorized
Wed Dec  2 08:20:00 2020 daemon.notice pppd[10815]: local  LL address fe80::68a4:33b7:af58:3343
Wed Dec  2 08:20:00 2020 daemon.notice pppd[10815]: remote LL address fe80::9203:25ff:fe74:4e06
Wed Dec  2 08:20:00 2020 daemon.notice netifd: Network device 'pppoe-1und1' link is up
Wed Dec  2 08:20:00 2020 daemon.notice netifd: Interface '1und1' is now up
Wed Dec  2 08:20:00 2020 daemon.notice netifd: Network alias 'pppoe-1und1' link is up
Wed Dec  2 08:20:00 2020 daemon.notice netifd: Interface '1und1_6' is enabled
Wed Dec  2 08:20:00 2020 daemon.notice netifd: Interface '1und1_6' has link connectivity
Wed Dec  2 08:20:00 2020 daemon.notice netifd: Interface '1und1_6' is setting up now
Wed Dec  2 08:20:00 2020 user.notice firewall: Reloading firewall due to ifup of 1und1 (pppoe-1und1)
Wed Dec  2 08:20:02 2020 authpriv.warn dropbear[10979]: Failed listening on '22': Error listening: Invalid argument
Wed Dec  2 08:20:02 2020 authpriv.info dropbear[10979]: Early exit: No listening ports available.
Wed Dec  2 08:20:02 2020 daemon.info pppd[10815]: LCP terminated by peer
Wed Dec  2 08:20:02 2020 daemon.err odhcp6c[10926]: Failed to send RS (Permission denied)
Wed Dec  2 08:20:02 2020 daemon.err odhcp6c[10926]: Failed to send DHCPV6 message to ff02::1:2 (Permission denied)
Wed Dec  2 08:20:02 2020 daemon.notice netifd: Network device 'pppoe-1und1' link is down
Wed Dec  2 08:20:02 2020 daemon.notice netifd: Network alias 'pppoe-1und1' link is down
Wed Dec  2 08:20:02 2020 daemon.notice netifd: Interface '1und1_6' has link connectivity loss
Wed Dec  2 08:20:02 2020 daemon.notice pppd[10815]: Modem hangup
Wed Dec  2 08:20:02 2020 daemon.notice pppd[10815]: Connection terminated.
Wed Dec  2 08:20:02 2020 daemon.info pppd[10815]: Connect time 0.1 minutes.
Wed Dec  2 08:20:02 2020 daemon.info pppd[10815]: Sent 686 bytes, received 590 bytes.
Wed Dec  2 08:20:02 2020 daemon.info pppd[10815]: Sent PADT
Wed Dec  2 08:20:02 2020 daemon.notice netifd: Interface '1und1' has lost the connection
Wed Dec  2 08:20:02 2020 daemon.info pppd[10815]: Exit.
Wed Dec  2 08:20:03 2020 daemon.notice netifd: Interface '1und1' is now down
Wed Dec  2 08:20:03 2020 daemon.err odhcp6c[10926]: Failed to send DHCPV6 message to ff02::1:2 (Permission denied)

Please, help me to figure out, how to bring the PPPoE back to the router.

I've thought, it may be useful to post:

Hostname: OpenWrt
Model: TP-Link Archer C7 v5
Architecture: Qualcomm Atheros QCA956X ver 1 rev 0
Firmware Version: OpenWrt 19.07.4 r11208-ce6496d796 / LuCI openwrt-19.07 branch git-20.334.47525-e9bfb12
Modell: FRITZ!Box 7412 (UI)
FRITZ!OS: 06.86

The ISP closed the connection.

I suggest to add

option pppd_options 'debug'

hoping this might reveal the reason for disconnecting.

In addition, you could try a factory reset of the Fritz!Box 7412, and patch the config as follows (German):

However, I am not sure it is necessary to fix the problem since pppoe negotiation seems to work initially, it is just unsuccessful in the end.

This is a follow-up error caused by the closed connection.

This config uses DS Lite, which you should replicate on OpenWrt.
I have no experience with DS Lite (using true dual stack here), so I can only refer you to the documentation. For example:

Thank you for your answer, Mirko.

So, one by one

After I've added debug, the log looks like:

Wed Dec  2 10:50:45 2020 daemon.notice netifd: Interface '1und1' has link connectivity loss
Wed Dec  2 10:50:45 2020 daemon.notice netifd: Interface '1und1' has link connectivity
Wed Dec  2 10:50:45 2020 daemon.notice netifd: Interface '1und1' is setting up now
Wed Dec  2 10:50:45 2020 daemon.err insmod: module is already loaded - slhc
Wed Dec  2 10:50:45 2020 daemon.err insmod: module is already loaded - ppp_generic
Wed Dec  2 10:50:45 2020 daemon.err insmod: module is already loaded - pppox
Wed Dec  2 10:50:45 2020 daemon.err insmod: module is already loaded - pppoe
Wed Dec  2 10:50:45 2020 daemon.info pppd[13962]: Plugin rp-pppoe.so loaded.
Wed Dec  2 10:50:45 2020 daemon.info pppd[13962]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Wed Dec  2 10:50:45 2020 daemon.notice pppd[13962]: pppd 2.4.7 started by root, uid 0
Wed Dec  2 10:50:45 2020 daemon.debug pppd[13962]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Wed Dec  2 10:50:45 2020 daemon.debug pppd[13962]:  dst ff:ff:ff:ff:ff:ff  src b0:95:75:48:e6:2c
Wed Dec  2 10:50:45 2020 daemon.debug pppd[13962]:  [service-name] [host-uniq  00 00 36 8a]
Wed Dec  2 10:50:45 2020 daemon.debug pppd[13962]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 30
Wed Dec  2 10:50:45 2020 daemon.debug pppd[13962]:  dst b0:95:75:48:e6:2c  src e8:df:70:9f:b1:34
Wed Dec  2 10:50:45 2020 daemon.debug pppd[13962]:  [service-name] [host-uniq  00 00 36 8a] [AC-name hhb0022aihk001]
Wed Dec  2 10:50:45 2020 daemon.debug pppd[13962]: Send PPPOE Discovery V1T1 PADR session 0x0 length 12
Wed Dec  2 10:50:45 2020 daemon.debug pppd[13962]:  dst e8:df:70:9f:b1:34  src b0:95:75:48:e6:2c
Wed Dec  2 10:50:45 2020 daemon.debug pppd[13962]:  [service-name] [host-uniq  00 00 36 8a]
Wed Dec  2 10:50:46 2020 daemon.debug pppd[13962]: Recv PPPOE Discovery V1T1 PADS session 0x6a5a length 12
Wed Dec  2 10:50:46 2020 daemon.debug pppd[13962]:  dst b0:95:75:48:e6:2c  src e8:df:70:9f:b1:34
Wed Dec  2 10:50:46 2020 daemon.debug pppd[13962]:  [service-name] [host-uniq  00 00 36 8a]
Wed Dec  2 10:50:46 2020 daemon.debug pppd[13962]: PADS: Service-Name: ''
Wed Dec  2 10:50:46 2020 daemon.info pppd[13962]: PPP session is 27226
Wed Dec  2 10:50:46 2020 daemon.warn pppd[13962]: Connected to e8:df:70:9f:b1:34 via interface eth0.7
Wed Dec  2 10:50:46 2020 daemon.debug pppd[13962]: using channel 158
Wed Dec  2 10:50:46 2020 kern.info kernel: [31039.080536] pppoe-1und1: renamed from ppp0
Wed Dec  2 10:50:46 2020 daemon.info pppd[13962]: Renamed interface ppp0 to pppoe-1und1
Wed Dec  2 10:50:46 2020 daemon.info pppd[13962]: Using interface pppoe-1und1
Wed Dec  2 10:50:46 2020 daemon.notice pppd[13962]: Connect: pppoe-1und1 <--> eth0.7
Wed Dec  2 10:50:46 2020 daemon.debug pppd[13962]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xb285c798>]
Wed Dec  2 10:50:46 2020 daemon.debug pppd[13962]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0xb285c798>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xb285c798>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0xb285c798>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0xf42e2cc8>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0xf42e2cc8>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: sent [LCP EchoReq id=0x0 magic=0xb285c798]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: sent [PAP AuthReq id=0x1 user="H1und1/id@online.de" password=<hidden>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: rcvd [LCP EchoRep id=0x0 magic=0xf42e2cc8]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: rcvd [PAP AuthAck id=0x1 "[UI-SBR:116797,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]"]
Wed Dec  2 10:50:49 2020 daemon.info pppd[13962]: Remote message: [UI-SBR:116797,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]
Wed Dec  2 10:50:49 2020 daemon.notice pppd[13962]: PAP authentication succeeded
Wed Dec  2 10:50:49 2020 daemon.notice pppd[13962]: peer from calling number E8:DF:70:9F:B1:34 authorized
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: sent [IPV6CP ConfReq id=0x1 <addr fe80::494b:f7df:95e2:a84b>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: rcvd [IPV6CP ConfReq id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: sent [IPV6CP ConfAck id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: rcvd [LCP ProtRej id=0x1 80 21 01 01 00 16 03 06 00 00 00 00 81 06 00 00 00 00 83 06 00 00 00 00]
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: Protocol-Reject for 'Internet Protocol Control Protocol' (0x8021) received
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: rcvd [IPV6CP ConfAck id=0x1 <addr fe80::494b:f7df:95e2:a84b>]
Wed Dec  2 10:50:49 2020 daemon.notice pppd[13962]: local  LL address fe80::494b:f7df:95e2:a84b
Wed Dec  2 10:50:49 2020 daemon.notice pppd[13962]: remote LL address fe80::9203:25ff:fe74:4e06
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: Script /lib/netifd/ppp6-up started (pid 13987)
Wed Dec  2 10:50:49 2020 daemon.notice netifd: Network device 'pppoe-1und1' link is up
Wed Dec  2 10:50:49 2020 daemon.notice netifd: Interface '1und1' is now up
Wed Dec  2 10:50:49 2020 daemon.notice netifd: Network alias 'pppoe-1und1' link is up
Wed Dec  2 10:50:49 2020 daemon.notice netifd: Interface '1und1_6' is enabled
Wed Dec  2 10:50:49 2020 daemon.notice netifd: Interface '1und1_6' has link connectivity
Wed Dec  2 10:50:49 2020 daemon.notice netifd: Interface '1und1_6' is setting up now
Wed Dec  2 10:50:49 2020 daemon.debug pppd[13962]: Script /lib/netifd/ppp6-up finished (pid 13987), status = 0x0
Wed Dec  2 10:50:49 2020 user.notice firewall: Reloading firewall due to ifup of 1und1 (pppoe-1und1)
Wed Dec  2 10:50:51 2020 daemon.debug pppd[13962]: rcvd [LCP TermReq id=0x2]
Wed Dec  2 10:50:51 2020 daemon.info pppd[13962]: LCP terminated by peer

When I've tried to set option ipv6 '1' for config interface '1und1', the logs are slightly different

Wed Dec  2 10:56:01 2020 daemon.notice netifd: Interface '1und1' is setting up now
Wed Dec  2 10:56:01 2020 daemon.err insmod: module is already loaded - slhc
Wed Dec  2 10:56:01 2020 daemon.err insmod: module is already loaded - ppp_generic
Wed Dec  2 10:56:01 2020 daemon.err insmod: module is already loaded - pppox
Wed Dec  2 10:56:01 2020 daemon.err insmod: module is already loaded - pppoe
Wed Dec  2 10:56:01 2020 daemon.info pppd[15632]: Plugin rp-pppoe.so loaded.
Wed Dec  2 10:56:01 2020 daemon.info pppd[15632]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Wed Dec  2 10:56:01 2020 daemon.notice pppd[15632]: pppd 2.4.7 started by root, uid 0
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]:  dst ff:ff:ff:ff:ff:ff  src b0:95:75:48:e6:2c
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]:  [service-name] [host-uniq  00 00 3d 10]
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 30
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]:  dst b0:95:75:48:e6:2c  src e8:df:70:9f:b1:34
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]:  [service-name] [host-uniq  00 00 3d 10] [AC-name hhb0022aihk001]
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]: Send PPPOE Discovery V1T1 PADR session 0x0 length 12
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]:  dst e8:df:70:9f:b1:34  src b0:95:75:48:e6:2c
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]:  [service-name] [host-uniq  00 00 3d 10]
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]: Recv PPPOE Discovery V1T1 PADS session 0x6a6e length 12
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]:  dst b0:95:75:48:e6:2c  src e8:df:70:9f:b1:34
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]:  [service-name] [host-uniq  00 00 3d 10]
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]: PADS: Service-Name: ''
Wed Dec  2 10:56:01 2020 daemon.info pppd[15632]: PPP session is 27246
Wed Dec  2 10:56:01 2020 daemon.warn pppd[15632]: Connected to e8:df:70:9f:b1:34 via interface eth0.7
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]: using channel 164
Wed Dec  2 10:56:01 2020 kern.info kernel: [31354.652607] pppoe-1und1: renamed from ppp0
Wed Dec  2 10:56:01 2020 daemon.info pppd[15632]: Renamed interface ppp0 to pppoe-1und1
Wed Dec  2 10:56:01 2020 daemon.info pppd[15632]: Using interface pppoe-1und1
Wed Dec  2 10:56:01 2020 daemon.notice pppd[15632]: Connect: pppoe-1und1 <--> eth0.7
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x240280cb>]
Wed Dec  2 10:56:01 2020 daemon.debug pppd[15632]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x240280cb>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x240280cb>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x240280cb>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0xd1691612>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0xd1691612>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: sent [LCP EchoReq id=0x0 magic=0x240280cb]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: sent [PAP AuthReq id=0x1 user="H1und1/id@online.de" password=<hidden>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: rcvd [LCP EchoRep id=0x0 magic=0xd1691612]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: rcvd [PAP AuthAck id=0x1 "[UI-SBR:116797,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]"]
Wed Dec  2 10:56:04 2020 daemon.info pppd[15632]: Remote message: [UI-SBR:116797,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]
Wed Dec  2 10:56:04 2020 daemon.notice pppd[15632]: PAP authentication succeeded
Wed Dec  2 10:56:04 2020 daemon.notice pppd[15632]: peer from calling number E8:DF:70:9F:B1:34 authorized
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: sent [IPV6CP ConfReq id=0x1 <addr fe80::9cce:a496:5066:9c36>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: rcvd [IPV6CP ConfReq id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: sent [IPV6CP ConfAck id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: rcvd [LCP ProtRej id=0x1 80 21 01 01 00 16 03 06 00 00 00 00 81 06 00 00 00 00 83 06 00 00 00 00]
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: Protocol-Reject for 'Internet Protocol Control Protocol' (0x8021) received
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: rcvd [IPV6CP ConfAck id=0x1 <addr fe80::9cce:a496:5066:9c36>]
Wed Dec  2 10:56:04 2020 daemon.notice pppd[15632]: local  LL address fe80::9cce:a496:5066:9c36
Wed Dec  2 10:56:04 2020 daemon.notice pppd[15632]: remote LL address fe80::9203:25ff:fe74:4e06
Wed Dec  2 10:56:04 2020 daemon.debug pppd[15632]: Script /lib/netifd/ppp6-up started (pid 15657)
Wed Dec  2 10:56:05 2020 daemon.notice netifd: Network device 'pppoe-1und1' link is up
Wed Dec  2 10:56:05 2020 daemon.notice netifd: Interface '1und1' is now up
Wed Dec  2 10:56:05 2020 daemon.debug pppd[15632]: Script /lib/netifd/ppp6-up finished (pid 15657), status = 0x0
Wed Dec  2 10:56:05 2020 user.notice firewall: Reloading firewall due to ifup of 1und1 (pppoe-1und1)
Wed Dec  2 10:56:07 2020 authpriv.warn dropbear[15757]: Failed listening on '22': Error listening: Invalid argument
Wed Dec  2 10:56:07 2020 authpriv.info dropbear[15757]: Early exit: No listening ports available.
Wed Dec  2 10:56:12 2020 authpriv.warn dropbear[15766]: Failed listening on '22': Error listening: Invalid argument
Wed Dec  2 10:56:12 2020 authpriv.info dropbear[15766]: Early exit: No listening ports available.
Wed Dec  2 10:56:17 2020 authpriv.warn dropbear[15769]: Failed listening on '22': Error listening: Invalid argument
Wed Dec  2 10:56:17 2020 authpriv.info dropbear[15769]: Early exit: No listening ports available.
Wed Dec  2 10:56:22 2020 authpriv.warn dropbear[15772]: Failed listening on '22': Error listening: Invalid argument
Wed Dec  2 10:56:22 2020 authpriv.info dropbear[15772]: Early exit: No listening ports available.
Wed Dec  2 10:56:27 2020 authpriv.warn dropbear[15775]: Failed listening on '22': Error listening: Invalid argument
Wed Dec  2 10:56:27 2020 authpriv.info dropbear[15775]: Early exit: No listening ports available.
Wed Dec  2 10:56:32 2020 authpriv.warn dropbear[15778]: Failed listening on '22': Error listening: Invalid argument
Wed Dec  2 10:56:32 2020 authpriv.info dropbear[15778]: Early exit: No listening ports available.

Just as a gut feeling, it should be something with ipv6.

Yes, indeed. But the error appears before ds-lite even should start. First, the router must get the public ipv6 from the ISP.

Yes, the bridge mode seems to work. If I misconfigure it on the modem with the wrong vlan or w/o the check against passthrough pppoe, the pppd on the router couldn't even send PAPD.

Check your firewall configuration for the wan zone, the interface 1und1 should be added to the list of networks there.
However, an interface that does not belong to a zone is handled by the default rule, which is ACCEPT for input, so this should not be the reason for the failure.

I suggest to post the relevant parts of your configuration (network, firewall, ...) and also some runtime information from ifstatus (wan, wan6, wan_6, 1und1).

I've reset both modem and router and set them from the scratch. No success so far...

Here are my openwrt settings

root@OpenWrt:~# uci show firewall
firewall.@defaults[0]=defaults
firewall.@defaults[0].syn_flood='1'
firewall.@defaults[0].input='ACCEPT'
firewall.@defaults[0].output='ACCEPT'
firewall.@defaults[0].forward='REJECT'
firewall.@zone[0]=zone
firewall.@zone[0].name='lan'
firewall.@zone[0].network='lan'
firewall.@zone[0].input='ACCEPT'
firewall.@zone[0].output='ACCEPT'
firewall.@zone[0].forward='ACCEPT'
firewall.@zone[1]=zone
firewall.@zone[1].name='wan'
firewall.@zone[1].input='REJECT'
firewall.@zone[1].output='ACCEPT'
firewall.@zone[1].forward='REJECT'
firewall.@zone[1].masq='1'
firewall.@zone[1].mtu_fix='1'
firewall.@zone[1].network='wan wan6 1u1'
firewall.@forwarding[0]=forwarding
firewall.@forwarding[0].src='lan'
firewall.@forwarding[0].dest='wan'
firewall.@rule[0]=rule
firewall.@rule[0].name='Allow-DHCP-Renew'
firewall.@rule[0].src='wan'
firewall.@rule[0].proto='udp'
firewall.@rule[0].dest_port='68'
firewall.@rule[0].target='ACCEPT'
firewall.@rule[0].family='ipv4'
firewall.@rule[1]=rule
firewall.@rule[1].name='Allow-Ping'
firewall.@rule[1].src='wan'
firewall.@rule[1].proto='icmp'
firewall.@rule[1].icmp_type='echo-request'
firewall.@rule[1].family='ipv4'
firewall.@rule[1].target='ACCEPT'
firewall.@rule[2]=rule
firewall.@rule[2].name='Allow-IGMP'
firewall.@rule[2].src='wan'
firewall.@rule[2].proto='igmp'
firewall.@rule[2].family='ipv4'
firewall.@rule[2].target='ACCEPT'
firewall.@rule[3]=rule
firewall.@rule[3].name='Allow-DHCPv6'
firewall.@rule[3].src='wan'
firewall.@rule[3].proto='udp'
firewall.@rule[3].src_ip='fc00::/6'
firewall.@rule[3].dest_ip='fc00::/6'
firewall.@rule[3].dest_port='546'
firewall.@rule[3].family='ipv6'
firewall.@rule[3].target='ACCEPT'
firewall.@rule[4]=rule
firewall.@rule[4].name='Allow-MLD'
firewall.@rule[4].src='wan'
firewall.@rule[4].proto='icmp'
firewall.@rule[4].src_ip='fe80::/10'
firewall.@rule[4].icmp_type='130/0' '131/0' '132/0' '143/0'
firewall.@rule[4].family='ipv6'
firewall.@rule[4].target='ACCEPT'
firewall.@rule[5]=rule
firewall.@rule[5].name='Allow-ICMPv6-Input'
firewall.@rule[5].src='wan'
firewall.@rule[5].proto='icmp'
firewall.@rule[5].icmp_type='echo-request' 'echo-reply' 'destination-unreachable' 'packet-too-big' 'time-exceeded' 'bad-header' 'unknown-header-type' 'router-solicitation' 'neighbour-solicitation' 'router-advertisement' 'neighbour-advertisement'
firewall.@rule[5].limit='1000/sec'
firewall.@rule[5].family='ipv6'
firewall.@rule[5].target='ACCEPT'
firewall.@rule[6]=rule
firewall.@rule[6].name='Allow-ICMPv6-Forward'
firewall.@rule[6].src='wan'
firewall.@rule[6].dest='*'
firewall.@rule[6].proto='icmp'
firewall.@rule[6].icmp_type='echo-request' 'echo-reply' 'destination-unreachable' 'packet-too-big' 'time-exceeded' 'bad-header' 'unknown-header-type'
firewall.@rule[6].limit='1000/sec'
firewall.@rule[6].family='ipv6'
firewall.@rule[6].target='ACCEPT'
firewall.@rule[7]=rule
firewall.@rule[7].name='Allow-IPSec-ESP'
firewall.@rule[7].src='wan'
firewall.@rule[7].dest='lan'
firewall.@rule[7].proto='esp'
firewall.@rule[7].target='ACCEPT'
firewall.@rule[8]=rule
firewall.@rule[8].name='Allow-ISAKMP'
firewall.@rule[8].src='wan'
firewall.@rule[8].dest='lan'
firewall.@rule[8].dest_port='500'
firewall.@rule[8].proto='udp'
firewall.@rule[8].target='ACCEPT'
firewall.@include[0]=include
firewall.@include[0].path='/etc/firewall.user'
root@OpenWrt:~# uci show network
network.loopback=interface
network.loopback.ifname='lo'
network.loopback.proto='static'
network.loopback.ipaddr='127.0.0.1'
network.loopback.netmask='255.0.0.0'
network.globals=globals
network.globals.ula_prefix='fd1f:45f5:172f::/48'
network.lan=interface
network.lan.type='bridge'
network.lan.ifname='eth0.1'
network.lan.proto='static'
network.lan.ipaddr='192.168.1.1'
network.lan.netmask='255.255.255.0'
network.lan.ip6assign='60'
network.wan=interface
network.wan.ifname='eth0.2'
network.wan.proto='dhcp'
network.wan_eth0_2_dev=device
network.wan_eth0_2_dev.name='eth0.2'
network.wan_eth0_2_dev.macaddr='b0:95:75:48:e6:2d'
network.wan6=interface
network.wan6.ifname='eth0.2'
network.wan6.proto='dhcpv6'
network.wan6.reqaddress='try'
network.wan6.reqprefix='auto'
network.@switch[0]=switch
network.@switch[0].name='switch0'
network.@switch[0].reset='1'
network.@switch[0].enable_vlan='1'
network.@switch_vlan[0]=switch_vlan
network.@switch_vlan[0].device='switch0'
network.@switch_vlan[0].vlan='1'
network.@switch_vlan[0].ports='2 3 4 5 0t'
network.@switch_vlan[1]=switch_vlan
network.@switch_vlan[1].device='switch0'
network.@switch_vlan[1].vlan='2'
network.@switch_vlan[1].ports='1 0t'
network.1und1=interface
network.1und1.ifname='eth0.2'
network.1und1.proto='pppoe'
network.1und1.password='password'
network.1und1.ipv6='auto'
network.1und1.username='H1und1/id@online.de'
network.1und1.pppd_options='debug'
network.1und1.auto='0'
root@OpenWrt:~# ifstatus wan
{
        "up": true,
        "pending": false,
        "available": true,
        "autostart": true,
        "dynamic": false,
        "uptime": 3129,
        "l3_device": "eth0.2",
        "proto": "dhcp",
        "device": "eth0.2",
        "updated": [
                "addresses",
                "routes",
                "data"
        ],
        "metric": 0,
        "dns_metric": 0,
        "delegation": true,
        "ipv4-address": [
                {
                        "address": "192.168.178.21",
                        "mask": 24
                }
        ],
        "ipv6-address": [

        ],
        "ipv6-prefix": [

        ],
        "ipv6-prefix-assignment": [

        ],
        "route": [
                {
                        "target": "0.0.0.0",
                        "mask": 0,
                        "nexthop": "192.168.178.1",
                        "source": "192.168.178.21/32"
                }
        ],
        "dns-server": [
                "192.168.178.1"
        ],
        "dns-search": [
                "fritz.box"
        ],
        "neighbors": [

        ],
        "inactive": {
                "ipv4-address": [

                ],
                "ipv6-address": [

                ],
                "route": [

                ],
                "dns-server": [

                ],
                "dns-search": [

                ],
                "neighbors": [

                ]
        },
        "data": {
                "leasetime": 864000,
                "ntpserver": "192.168.178.1"
        }
}
root@OpenWrt:~# ifstatus 1und1
{
        "up": true,
        "pending": false,
        "available": true,
        "autostart": true,
        "dynamic": false,
        "uptime": 0,
        "l3_device": "pppoe-1und1",
        "proto": "pppoe",
        "device": "eth0.2",
        "updated": [
                "addresses"
        ],
        "metric": 0,
        "dns_metric": 0,
        "delegation": true,
        "ipv4-address": [

        ],
        "ipv6-address": [
                {
                        "address": "fe80::391c:b451:aa29:50d5",
                        "mask": 128
                }
        ],
        "ipv6-prefix": [

        ],
        "ipv6-prefix-assignment": [

        ],
        "route": [

        ],
        "dns-server": [

        ],
        "dns-search": [

        ],
        "neighbors": [

        ],
        "inactive": {
                "ipv4-address": [

                ],
                "ipv6-address": [

                ],
                "route": [

                ],
                "dns-server": [

                ],
                "dns-search": [

                ],
                "neighbors": [

                ]
        },
        "data": {

        }
}
root@OpenWrt:~# ifstatus wan6
{
        "up": true,
        "pending": false,
        "available": true,
        "autostart": true,
        "dynamic": false,
        "uptime": 17,
        "l3_device": "eth0.2",
        "proto": "dhcpv6",
        "device": "eth0.2",
        "metric": 0,
        "dns_metric": 0,
        "delegation": true,
        "ipv4-address": [

        ],
        "ipv6-address": [
                {
                        "address": "2001:16b8:6844:8500:b295:75ff:fe48:e62d",
                        "mask": 64,
                        "preferred": 3585,
                        "valid": 7185
                },
                {
                        "address": "fd00::b295:75ff:fe48:e62d",
                        "mask": 64,
                        "preferred": 0,
                        "valid": 7185
                }
        ],
        "ipv6-prefix": [

        ],
        "ipv6-prefix-assignment": [

        ],
        "route": [
                {
                        "target": "2001:16b8:6844:8500::",
                        "mask": 64,
                        "nexthop": "::",
                        "metric": 256,
                        "valid": 7185,
                        "source": "::/0"
                },
                {
                        "target": "fd00::",
                        "mask": 64,
                        "nexthop": "::",
                        "metric": 256,
                        "valid": 7185,
                        "source": "::/0"
                },
                {
                        "target": "2001:16b8:6844:8500::",
                        "mask": 64,
                        "nexthop": "fe80::eadf:70ff:fe9f:b134",
                        "metric": 512,
                        "valid": 1785,
                        "source": "::/0"
                }
        ],
        "dns-server": [
                "fd00::eadf:70ff:fe9f:b134"
        ],
        "dns-search": [

        ],
        "neighbors": [

        ],
        "inactive": {
                "ipv4-address": [

                ],
                "ipv6-address": [

                ],
                "route": [

                ],
                "dns-server": [

                ],
                "dns-search": [

                ],
                "neighbors": [

                ]
        },
        "data": {
                "passthru": "00170010fd00000000000000eadf70fffe9fb1340038001400010010fd00000000000000eadf70fffe9fb13400560010200116b868448500eadf70fffe9fb134"
        }
}

It still doesn't work and fails after the ppp6-up

Looks like the router got a public IPv6 address now.
However, there does not seem to be a delegated IPv6 prefix yet.

Please run (on the router):

ping 2001:4860:4860::8888

The interface name does not match (1u1 vs. 1und1).

The Fritz!Box is handing out an IPv4 address and default route via DHCP.
Before you set up IPv4 with DS Lite, don't forget to disable the IPv4 DHCP client on OpenWrt.

Why do you believe it is failing? Are you referring to this dropbear message?

I wouldn't worry about it for now and get IPv6/IPv4 working first.

This address comes from the modem's DHCP server when the PPPoE is working there. So, it's the IP got from the prefix on modem. It appears because to have a working Internet connection I have to set the PPPoE on the fritz modem.

My bad. After the factory reset I called the interface 1u1 and renamed it later.

When the ds-lite used to work, I've had a 1und1_*4 (not sure if it was 1und1_4 of 1und1_6_4) interface, that had 192.0.0.1 address. And wan used to be a DHCP client of the fritz. I'll try to disable it, for sure.

Because of the experiment I've done:

  • set option ipv6 '1' for config interface '1und1' in /etc/config/network
  • start it
  • The connection is up and work, only local LL address is on the interface (see local LL address fe80::9cce:a496:5066:9c36 above)
  • Then I've added the dhcpv6 client for 1und1, and after trying to start it the router received a termination packet

And now something completely strange.

Yesterday before going to sleep, I've left the system in the state, when both fritz and OpenWrt has had the PPPoE set up. And now it's up on both devices.


# ifconfig
br-lan    Link encap:Ethernet  HWaddr B0:95:75:48:E6:2C
          inet addr:192.168.1.1  Bcast:192.168.1.255  Mask:255.255.255.0
          inet6 addr: 2001:16b8:680d:bc00::1/60 Scope:Global
          inet6 addr: fd1f:45f5:172f::1/60 Scope:Global
          inet6 addr: fe80::b295:75ff:fe48:e62c/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:1099973 errors:0 dropped:0 overruns:0 frame:0
          TX packets:1193952 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:596180267 (568.5 MiB)  TX bytes:860351724 (820.4 MiB)

ds-1und1_6_4 Link encap:UNSPEC  HWaddr 20-01-16-B8-65-00-EB-72-00-00-00-00-00-00-00-00
          inet addr:192.0.0.2  P-t-P:192.0.0.1  Mask:255.255.255.255
          inet6 addr: fe80::8841:14ff:fe54:7f1c/64 Scope:Link
          UP POINTOPOINT RUNNING NOARP  MTU:1280  Metric:1
          RX packets:666518 errors:0 dropped:0 overruns:0 frame:0
          TX packets:650436 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:358900165 (342.2 MiB)  TX bytes:513590114 (489.7 MiB)

eth0      Link encap:Ethernet  HWaddr B0:95:75:48:E6:2C
          inet6 addr: fe80::b295:75ff:fe48:e62c/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:1341143 errors:0 dropped:0 overruns:5 frame:0
          TX packets:1321824 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:900092047 (858.3 MiB)  TX bytes:814322048 (776.5 MiB)
          Interrupt:4

eth0.1    Link encap:Ethernet  HWaddr B0:95:75:48:E6:2C
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:131470 errors:0 dropped:0 overruns:0 frame:0
          TX packets:198434 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:14699736 (14.0 MiB)  TX bytes:167608551 (159.8 MiB)

eth0.2    Link encap:Ethernet  HWaddr B0:95:75:48:E6:2D
          inet addr:192.168.178.21  Bcast:192.168.178.255  Mask:255.255.255.0
          inet6 addr: fe80::b295:75ff:fe48:e62d/64 Scope:Link
          inet6 addr: 2001:16b8:68a4:8d00:b295:75ff:fe48:e62d/64 Scope:Global
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:1209633 errors:0 dropped:3057 overruns:0 frame:0
          TX packets:1123357 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:861248302 (821.3 MiB)  TX bytes:641423544 (611.7 MiB)

lo        Link encap:Local Loopback
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:24700 errors:0 dropped:0 overruns:0 frame:0
          TX packets:24700 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:2249183 (2.1 MiB)  TX bytes:2249183 (2.1 MiB)

pppoe-1und1 Link encap:Point-to-Point Protocol
          inet6 addr: fe80::192d:b891:c639:a015/10 Scope:Link
          inet6 addr: 2001:16b8:6500:eb72:192d:b891:c639:a015/64 Scope:Global
          UP POINTOPOINT RUNNING NOARP MULTICAST  MTU:1492  Metric:1
          RX packets:798724 errors:0 dropped:0 overruns:0 frame:0
          TX packets:734278 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:3
          RX bytes:528166218 (503.6 MiB)  TX bytes:562868514 (536.7 MiB)

wlan0     Link encap:Ethernet  HWaddr B0:95:75:48:E6:2B
          inet6 addr: fe80::b295:75ff:fe48:e62b/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:895608 errors:0 dropped:0 overruns:0 frame:0
          TX packets:1024115 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:584899507 (557.8 MiB)  TX bytes:696091103 (663.8 MiB)

wlan1     Link encap:Ethernet  HWaddr B0:95:75:48:E6:2C
          inet6 addr: fe80::b295:75ff:fe48:e62c/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:1040 errors:0 dropped:0 overruns:0 frame:0
          TX packets:23704 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:294171 (287.2 KiB)  TX bytes:3130379 (2.9 MiB)

And logs, when the connection was failing a couple of times, but then established

Wed Dec  2 21:53:09 2020 daemon.notice netifd: Interface '1und1' is setting up now
Wed Dec  2 21:53:09 2020 daemon.err odhcp6c[23505]: Failed to send DHCPV6 message to ff02::1:2 (Permission denied)
Wed Dec  2 21:53:09 2020 daemon.notice netifd: Interface '1und1_6' is now down
Wed Dec  2 21:53:09 2020 daemon.notice netifd: Interface '1und1_6' is disabled
Wed Dec  2 21:53:10 2020 daemon.err insmod: module is already loaded - slhc
Wed Dec  2 21:53:10 2020 daemon.err insmod: module is already loaded - ppp_generic
Wed Dec  2 21:53:10 2020 daemon.err insmod: module is already loaded - pppox
Wed Dec  2 21:53:10 2020 daemon.err insmod: module is already loaded - pppoe
Wed Dec  2 21:53:10 2020 daemon.info pppd[23594]: Plugin rp-pppoe.so loaded.
Wed Dec  2 21:53:10 2020 daemon.info pppd[23594]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Wed Dec  2 21:53:10 2020 daemon.notice pppd[23594]: pppd 2.4.7 started by root, uid 0
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]:  dst ff:ff:ff:ff:ff:ff  src b0:95:75:48:e6:2d
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]:  [service-name] [host-uniq  00 00 5c 2a]
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 30
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]:  dst b0:95:75:48:e6:2d  src e8:df:70:9f:b1:34
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]:  [service-name] [host-uniq  00 00 5c 2a] [AC-name hhb0022aihk001]
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]: Send PPPOE Discovery V1T1 PADR session 0x0 length 12
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]:  dst e8:df:70:9f:b1:34  src b0:95:75:48:e6:2d
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]:  [service-name] [host-uniq  00 00 5c 2a]
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]: Recv PPPOE Discovery V1T1 PADS session 0x7684 length 12
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]:  dst b0:95:75:48:e6:2d  src e8:df:70:9f:b1:34
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]:  [service-name] [host-uniq  00 00 5c 2a]
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]: PADS: Service-Name: ''
Wed Dec  2 21:53:10 2020 daemon.info pppd[23594]: PPP session is 30340
Wed Dec  2 21:53:10 2020 daemon.warn pppd[23594]: Connected to e8:df:70:9f:b1:34 via interface eth0.2
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]: using channel 2029
Wed Dec  2 21:53:10 2020 kern.info kernel: [14494.734571] pppoe-1und1: renamed from ppp0
Wed Dec  2 21:53:10 2020 daemon.info pppd[23594]: Renamed interface ppp0 to pppoe-1und1
Wed Dec  2 21:53:10 2020 daemon.info pppd[23594]: Using interface pppoe-1und1
Wed Dec  2 21:53:10 2020 daemon.notice pppd[23594]: Connect: pppoe-1und1 <--> eth0.2
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x71a2e658>]
Wed Dec  2 21:53:10 2020 daemon.debug pppd[23594]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x71a2e658>]
Wed Dec  2 21:53:10 2020 daemon.warn odhcpd[1254]: A default route is present but there is no public prefix on lan thus we don't announce a default route!
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x71a2e658>]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x71a2e658>]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0xb1920f30>]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0xb1920f30>]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: sent [LCP EchoReq id=0x0 magic=0x71a2e658]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: sent [PAP AuthReq id=0x1 user="H1und1/id@online.de" password=<hidden>]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: rcvd [LCP EchoRep id=0x0 magic=0xb1920f30]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: rcvd [PAP AuthAck id=0x1 "[UI-SBR:115045,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]"]
Wed Dec  2 21:53:13 2020 daemon.info pppd[23594]: Remote message: [UI-SBR:115045,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]
Wed Dec  2 21:53:13 2020 daemon.notice pppd[23594]: PAP authentication succeeded
Wed Dec  2 21:53:13 2020 daemon.notice pppd[23594]: peer from calling number E8:DF:70:9F:B1:34 authorized
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: sent [IPV6CP ConfReq id=0x1 <addr fe80::a952:8f9f:a407:c50b>]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: rcvd [IPV6CP ConfReq id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: sent [IPV6CP ConfAck id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: rcvd [LCP ProtRej id=0x1 80 21 01 01 00 16 03 06 00 00 00 00 81 06 00 00 00 00 83 06 00 00 00 00]
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: Protocol-Reject for 'Internet Protocol Control Protocol' (0x8021) received
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: rcvd [IPV6CP ConfAck id=0x1 <addr fe80::a952:8f9f:a407:c50b>]
Wed Dec  2 21:53:13 2020 daemon.notice pppd[23594]: local  LL address fe80::a952:8f9f:a407:c50b
Wed Dec  2 21:53:13 2020 daemon.notice pppd[23594]: remote LL address fe80::9203:25ff:fe74:4e06
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: Script /lib/netifd/ppp6-up started (pid 23647)
Wed Dec  2 21:53:13 2020 daemon.notice netifd: Network device 'pppoe-1und1' link is up
Wed Dec  2 21:53:13 2020 daemon.notice netifd: Interface '1und1' is now up
Wed Dec  2 21:53:13 2020 daemon.notice netifd: Network alias 'pppoe-1und1' link is up
Wed Dec  2 21:53:13 2020 daemon.notice netifd: Interface '1und1_6' is enabled
Wed Dec  2 21:53:13 2020 daemon.notice netifd: Interface '1und1_6' has link connectivity
Wed Dec  2 21:53:13 2020 daemon.notice netifd: Interface '1und1_6' is setting up now
Wed Dec  2 21:53:13 2020 daemon.debug pppd[23594]: Script /lib/netifd/ppp6-up finished (pid 23647), status = 0x0
Wed Dec  2 21:53:13 2020 user.notice firewall: Reloading firewall due to ifup of 1und1 (pppoe-1und1)
Wed Dec  2 21:53:14 2020 daemon.warn odhcpd[1254]: A default route is present but there is no public prefix on lan thus we don't announce a default route!
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]: rcvd [LCP TermReq id=0x2]
Wed Dec  2 21:53:15 2020 daemon.info pppd[23594]: LCP terminated by peer
Wed Dec  2 21:53:15 2020 daemon.err odhcp6c[23677]: Failed to send RS (Permission denied)
Wed Dec  2 21:53:15 2020 daemon.err odhcp6c[23677]: Failed to send DHCPV6 message to ff02::1:2 (Permission denied)
Wed Dec  2 21:53:15 2020 daemon.notice netifd: Network device 'pppoe-1und1' link is down
Wed Dec  2 21:53:15 2020 daemon.notice netifd: Network alias 'pppoe-1und1' link is down
Wed Dec  2 21:53:15 2020 daemon.notice netifd: Interface '1und1_6' has link connectivity loss
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]: Script /lib/netifd/ppp-down started (pid 23723)
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]: sent [LCP TermAck id=0x2]
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]: rcvd [LCP TermReq id=0x3 "2\003\001"]
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]: sent [LCP TermAck id=0x3]
Wed Dec  2 21:53:15 2020 daemon.notice pppd[23594]: Modem hangup
Wed Dec  2 21:53:15 2020 daemon.notice pppd[23594]: Connection terminated.
Wed Dec  2 21:53:15 2020 daemon.info pppd[23594]: Connect time 0.1 minutes.
Wed Dec  2 21:53:15 2020 daemon.info pppd[23594]: Sent 686 bytes, received 590 bytes.
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]: Send PPPOE Discovery V1T1 PADT session 0x7684 length 8
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]:  dst e8:df:70:9f:b1:34  src b0:95:75:48:e6:2d
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]:  [host-uniq  00 00 5c 2a]
Wed Dec  2 21:53:15 2020 daemon.info pppd[23594]: Sent PADT
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]: Waiting for 1 child processes...
Wed Dec  2 21:53:15 2020 daemon.debug pppd[23594]:   script /lib/netifd/ppp-down, pid 23723
Wed Dec  2 21:53:16 2020 daemon.notice netifd: Interface '1und1' has lost the connection
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23594]: Script /lib/netifd/ppp-down finished (pid 23723), status = 0x1
Wed Dec  2 21:53:16 2020 daemon.info pppd[23594]: Exit.
Wed Dec  2 21:53:16 2020 daemon.notice netifd: Interface '1und1' is now down
Wed Dec  2 21:53:16 2020 daemon.notice netifd: Interface '1und1' is setting up now
Wed Dec  2 21:53:16 2020 daemon.err insmod: module is already loaded - slhc
Wed Dec  2 21:53:16 2020 daemon.err insmod: module is already loaded - ppp_generic
Wed Dec  2 21:53:16 2020 daemon.err insmod: module is already loaded - pppox
Wed Dec  2 21:53:16 2020 daemon.err insmod: module is already loaded - pppoe
Wed Dec  2 21:53:16 2020 daemon.info pppd[23763]: Plugin rp-pppoe.so loaded.
Wed Dec  2 21:53:16 2020 daemon.info pppd[23763]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Wed Dec  2 21:53:16 2020 daemon.notice pppd[23763]: pppd 2.4.7 started by root, uid 0
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]:  dst ff:ff:ff:ff:ff:ff  src b0:95:75:48:e6:2d
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]:  [service-name] [host-uniq  00 00 5c d3]
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 30
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]:  dst b0:95:75:48:e6:2d  src e8:df:70:9f:b1:34
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]:  [service-name] [host-uniq  00 00 5c d3] [AC-name hhb0022aihk001]
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]: Send PPPOE Discovery V1T1 PADR session 0x0 length 12
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]:  dst e8:df:70:9f:b1:34  src b0:95:75:48:e6:2d
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]:  [service-name] [host-uniq  00 00 5c d3]
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]: Recv PPPOE Discovery V1T1 PADS session 0x7688 length 12
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]:  dst b0:95:75:48:e6:2d  src e8:df:70:9f:b1:34
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]:  [service-name] [host-uniq  00 00 5c d3]
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]: PADS: Service-Name: ''
Wed Dec  2 21:53:16 2020 daemon.info pppd[23763]: PPP session is 30344
Wed Dec  2 21:53:16 2020 daemon.warn pppd[23763]: Connected to e8:df:70:9f:b1:34 via interface eth0.2
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]: using channel 2030
Wed Dec  2 21:53:16 2020 kern.info kernel: [14501.032962] pppoe-1und1: renamed from ppp0
Wed Dec  2 21:53:16 2020 daemon.info pppd[23763]: Renamed interface ppp0 to pppoe-1und1
Wed Dec  2 21:53:16 2020 daemon.info pppd[23763]: Using interface pppoe-1und1
Wed Dec  2 21:53:16 2020 daemon.notice pppd[23763]: Connect: pppoe-1und1 <--> eth0.2
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xd8458549>]
Wed Dec  2 21:53:16 2020 daemon.debug pppd[23763]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0xd8458549>]
Wed Dec  2 21:53:16 2020 daemon.err odhcp6c[23677]: Failed to send DHCPV6 message to ff02::1:2 (Permission denied)
Wed Dec  2 21:53:16 2020 daemon.notice netifd: Interface '1und1_6' is now down
Wed Dec  2 21:53:16 2020 daemon.notice netifd: Interface '1und1_6' is disabled
Wed Dec  2 21:53:17 2020 daemon.warn odhcpd[1254]: A default route is present but there is no public prefix on lan thus we don't announce a default route!
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xd8458549>]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0xd8458549>]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x9d4c5146>]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x9d4c5146>]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: sent [LCP EchoReq id=0x0 magic=0xd8458549]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: sent [PAP AuthReq id=0x1 user="H1und1/id@online.de" password=<hidden>]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: rcvd [LCP EchoRep id=0x0 magic=0x9d4c5146]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: rcvd [PAP AuthAck id=0x1 "[UI-SBR:115045,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]"]
Wed Dec  2 21:53:19 2020 daemon.info pppd[23763]: Remote message: [UI-SBR:115045,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]
Wed Dec  2 21:53:19 2020 daemon.notice pppd[23763]: PAP authentication succeeded
Wed Dec  2 21:53:19 2020 daemon.notice pppd[23763]: peer from calling number E8:DF:70:9F:B1:34 authorized
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: sent [IPV6CP ConfReq id=0x1 <addr fe80::fd88:c23c:2550:5fe4>]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: rcvd [IPV6CP ConfReq id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 21:53:19 2020 daemon.debug pppd[23763]: sent [IPV6CP ConfAck id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 21:53:20 2020 daemon.debug pppd[23763]: rcvd [LCP ProtRej id=0x1 80 21 01 01 00 16 03 06 00 00 00 00 81 06 00 00 00 00 83 06 00 00 00 00]
Wed Dec  2 21:53:20 2020 daemon.debug pppd[23763]: Protocol-Reject for 'Internet Protocol Control Protocol' (0x8021) received
Wed Dec  2 21:53:20 2020 daemon.debug pppd[23763]: rcvd [IPV6CP ConfAck id=0x1 <addr fe80::fd88:c23c:2550:5fe4>]
Wed Dec  2 21:53:20 2020 daemon.notice pppd[23763]: local  LL address fe80::fd88:c23c:2550:5fe4
Wed Dec  2 21:53:20 2020 daemon.notice pppd[23763]: remote LL address fe80::9203:25ff:fe74:4e06
Wed Dec  2 21:53:20 2020 daemon.debug pppd[23763]: Script /lib/netifd/ppp6-up started (pid 23824)
Wed Dec  2 21:53:20 2020 daemon.notice netifd: Network device 'pppoe-1und1' link is up
Wed Dec  2 21:53:20 2020 daemon.notice netifd: Interface '1und1' is now up
Wed Dec  2 21:53:20 2020 daemon.notice netifd: Network alias 'pppoe-1und1' link is up
Wed Dec  2 21:53:20 2020 daemon.notice netifd: Interface '1und1_6' is enabled
Wed Dec  2 21:53:20 2020 daemon.notice netifd: Interface '1und1_6' has link connectivity
Wed Dec  2 21:53:20 2020 daemon.notice netifd: Interface '1und1_6' is setting up now
Wed Dec  2 21:53:20 2020 daemon.debug pppd[23763]: Script /lib/netifd/ppp6-up finished (pid 23824), status = 0x0
Wed Dec  2 21:53:20 2020 user.notice firewall: Reloading firewall due to ifup of 1und1 (pppoe-1und1)
Wed Dec  2 21:53:21 2020 daemon.warn odhcpd[1254]: A default route is present but there is no public prefix on lan thus we don't announce a default route!
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]: rcvd [LCP TermReq id=0x2]
Wed Dec  2 21:53:21 2020 daemon.info pppd[23763]: LCP terminated by peer
Wed Dec  2 21:53:21 2020 daemon.err odhcp6c[23855]: Failed to send RS (Permission denied)
Wed Dec  2 21:53:21 2020 daemon.err odhcp6c[23855]: Failed to send DHCPV6 message to ff02::1:2 (Permission denied)
Wed Dec  2 21:53:21 2020 daemon.notice netifd: Network device 'pppoe-1und1' link is down
Wed Dec  2 21:53:21 2020 daemon.notice netifd: Network alias 'pppoe-1und1' link is down
Wed Dec  2 21:53:21 2020 daemon.notice netifd: Interface '1und1_6' has link connectivity loss
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]: Script /lib/netifd/ppp-down started (pid 23900)
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]: sent [LCP TermAck id=0x2]
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]: rcvd [LCP TermReq id=0x3 "2\003\001"]
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]: sent [LCP TermAck id=0x3]
Wed Dec  2 21:53:21 2020 daemon.notice pppd[23763]: Modem hangup
Wed Dec  2 21:53:21 2020 daemon.notice pppd[23763]: Connection terminated.
Wed Dec  2 21:53:21 2020 daemon.info pppd[23763]: Connect time 0.1 minutes.
Wed Dec  2 21:53:21 2020 daemon.info pppd[23763]: Sent 686 bytes, received 590 bytes.
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]: Send PPPOE Discovery V1T1 PADT session 0x7688 length 8
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]:  dst e8:df:70:9f:b1:34  src b0:95:75:48:e6:2d
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]:  [host-uniq  00 00 5c d3]
Wed Dec  2 21:53:21 2020 daemon.info pppd[23763]: Sent PADT
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]: Waiting for 1 child processes...
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]:   script /lib/netifd/ppp-down, pid 23900
Wed Dec  2 21:53:21 2020 daemon.notice netifd: Interface '1und1' has lost the connection
Wed Dec  2 21:53:21 2020 daemon.debug pppd[23763]: Script /lib/netifd/ppp-down finished (pid 23900), status = 0x1
Wed Dec  2 21:53:21 2020 daemon.info pppd[23763]: Exit.
Wed Dec  2 21:53:21 2020 daemon.notice netifd: Interface '1und1' is now down
Wed Dec  2 21:53:21 2020 daemon.notice netifd: Interface '1und1' is setting up now
Wed Dec  2 21:53:21 2020 daemon.err insmod: module is already loaded - slhc
Wed Dec  2 21:53:21 2020 daemon.err insmod: module is already loaded - ppp_generic
Wed Dec  2 21:53:21 2020 daemon.err insmod: module is already loaded - pppox
Wed Dec  2 21:53:21 2020 daemon.err insmod: module is already loaded - pppoe
Wed Dec  2 21:53:21 2020 daemon.err odhcp6c[23855]: Failed to send DHCPV6 message to ff02::1:2 (Permission denied)
Wed Dec  2 21:53:21 2020 daemon.notice netifd: Interface '1und1_6' is now down
Wed Dec  2 21:53:21 2020 daemon.notice netifd: Interface '1und1_6' is disabled
Wed Dec  2 21:53:21 2020 daemon.info pppd[23945]: Plugin rp-pppoe.so loaded.
Wed Dec  2 21:53:21 2020 daemon.info pppd[23945]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Wed Dec  2 21:53:21 2020 daemon.notice pppd[23945]: pppd 2.4.7 started by root, uid 0
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]:  dst ff:ff:ff:ff:ff:ff  src b0:95:75:48:e6:2d
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]:  [service-name] [host-uniq  00 00 5d 89]
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 30
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]:  dst b0:95:75:48:e6:2d  src e8:df:70:9f:b1:34
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]:  [service-name] [host-uniq  00 00 5d 89] [AC-name hhb0022aihk001]
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]: Send PPPOE Discovery V1T1 PADR session 0x0 length 12
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]:  dst e8:df:70:9f:b1:34  src b0:95:75:48:e6:2d
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]:  [service-name] [host-uniq  00 00 5d 89]
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]: Recv PPPOE Discovery V1T1 PADS session 0x7685 length 12
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]:  dst b0:95:75:48:e6:2d  src e8:df:70:9f:b1:34
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]:  [service-name] [host-uniq  00 00 5d 89]
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]: PADS: Service-Name: ''
Wed Dec  2 21:53:22 2020 daemon.info pppd[23945]: PPP session is 30341
Wed Dec  2 21:53:22 2020 daemon.warn pppd[23945]: Connected to e8:df:70:9f:b1:34 via interface eth0.2
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]: using channel 2031
Wed Dec  2 21:53:22 2020 kern.info kernel: [14506.594805] pppoe-1und1: renamed from ppp0
Wed Dec  2 21:53:22 2020 daemon.info pppd[23945]: Renamed interface ppp0 to pppoe-1und1
Wed Dec  2 21:53:22 2020 daemon.info pppd[23945]: Using interface pppoe-1und1
Wed Dec  2 21:53:22 2020 daemon.notice pppd[23945]: Connect: pppoe-1und1 <--> eth0.2
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x550a03d2>]
Wed Dec  2 21:53:22 2020 daemon.debug pppd[23945]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x550a03d2>]
Wed Dec  2 21:53:22 2020 daemon.warn odhcpd[1254]: A default route is present but there is no public prefix on lan thus we don't announce a default route!
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x550a03d2>]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x550a03d2>]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0xe16d7da9>]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0xe16d7da9>]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: sent [LCP EchoReq id=0x0 magic=0x550a03d2]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: sent [PAP AuthReq id=0x1 user="H1und1/id@online.de" password=<hidden>]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: rcvd [LCP EchoRep id=0x0 magic=0xe16d7da9]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: rcvd [PAP AuthAck id=0x1 "[UI-SBR:115045,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]"]
Wed Dec  2 21:53:25 2020 daemon.info pppd[23945]: Remote message: [UI-SBR:115045,32000;UI-LINEID:1UND1.DEU.DTAG.KBBJV;]
Wed Dec  2 21:53:25 2020 daemon.notice pppd[23945]: PAP authentication succeeded
Wed Dec  2 21:53:25 2020 daemon.notice pppd[23945]: peer from calling number E8:DF:70:9F:B1:34 authorized
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: sent [IPV6CP ConfReq id=0x1 <addr fe80::192d:b891:c639:a015>]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: rcvd [IPV6CP ConfReq id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: sent [IPV6CP ConfAck id=0x1 <addr fe80::9203:25ff:fe74:4e06>]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: rcvd [LCP ProtRej id=0x1 80 21 01 01 00 16 03 06 00 00 00 00 81 06 00 00 00 00 83 06 00 00 00 00]
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: Protocol-Reject for 'Internet Protocol Control Protocol' (0x8021) received
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: rcvd [IPV6CP ConfAck id=0x1 <addr fe80::192d:b891:c639:a015>]
Wed Dec  2 21:53:25 2020 daemon.notice pppd[23945]: local  LL address fe80::192d:b891:c639:a015
Wed Dec  2 21:53:25 2020 daemon.notice pppd[23945]: remote LL address fe80::9203:25ff:fe74:4e06
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: Script /lib/netifd/ppp6-up started (pid 24001)
Wed Dec  2 21:53:25 2020 daemon.notice netifd: Network device 'pppoe-1und1' link is up
Wed Dec  2 21:53:25 2020 daemon.notice netifd: Interface '1und1' is now up
Wed Dec  2 21:53:25 2020 daemon.notice netifd: Network alias 'pppoe-1und1' link is up
Wed Dec  2 21:53:25 2020 daemon.notice netifd: Interface '1und1_6' is enabled
Wed Dec  2 21:53:25 2020 daemon.notice netifd: Interface '1und1_6' has link connectivity
Wed Dec  2 21:53:25 2020 daemon.notice netifd: Interface '1und1_6' is setting up now
Wed Dec  2 21:53:25 2020 daemon.debug pppd[23945]: Script /lib/netifd/ppp6-up finished (pid 24001), status = 0x0
Wed Dec  2 21:53:25 2020 user.notice firewall: Reloading firewall due to ifup of 1und1 (pppoe-1und1)
Wed Dec  2 21:53:26 2020 daemon.notice netifd: Interface '1und1_6' is now up
Wed Dec  2 21:53:26 2020 user.notice firewall: Reloading firewall due to ifup of 1und1_6 (pppoe-1und1)
Wed Dec  2 21:53:26 2020 daemon.err odhcp6c[24031]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted)
Wed Dec  2 21:53:27 2020 daemon.warn odhcpd[1254]: A default route is present but there is no public prefix on lan thus we don't announce a default route!
Wed Dec  2 21:53:38 2020 daemon.notice netifd: Interface '1und1_6' has lost the connection
Wed Dec  2 21:53:38 2020 daemon.notice netifd: Interface '1und1_6' is now up
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: reading /tmp/resolv.conf.auto
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using local addresses only for domain test
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using local addresses only for domain onion
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using local addresses only for domain localhost
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using local addresses only for domain local
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using local addresses only for domain invalid
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using local addresses only for domain bind
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using local addresses only for domain lan
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using nameserver 2001:1438:2:4::8#53
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using nameserver 2001:1438:2:3::8#53
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using nameserver 192.168.178.1#53
Wed Dec  2 21:53:38 2020 daemon.info dnsmasq[1791]: using nameserver fd00::eadf:70ff:fe9f:b134#53
Wed Dec  2 21:53:38 2020 daemon.notice netifd: Interface '1und1_6_4' is setting up now
Wed Dec  2 21:53:38 2020 user.notice firewall: Reloading firewall due to ifup of 1und1_6 (pppoe-1und1)
Wed Dec  2 21:53:39 2020 daemon.notice netifd: Interface '1und1_6_4' is now up
Wed Dec  2 21:53:39 2020 daemon.notice netifd: tunnel 'ds-1und1_6_4' link is up
Wed Dec  2 21:53:39 2020 user.notice firewall: Reloading firewall due to ifup of 1und1_6_4 (ds-1und1_6_4)
Wed Dec  2 21:53:39 2020 daemon.info dnsmasq[1791]: read /etc/hosts - 4 addresses
Wed Dec  2 21:53:39 2020 daemon.info dnsmasq[1791]: read /tmp/hosts/odhcpd - 6 addresses
Wed Dec  2 21:53:39 2020 daemon.info dnsmasq[1791]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Wed Dec  2 21:53:39 2020 daemon.info dnsmasq-dhcp[1791]: read /etc/ethers - 0 addresses

And now I'm completely lost..

Remove the PPPoE credentials from the Fritz!Box to make sure the PPPoE connection from OpenWrt is the only one. If the ISP only allows one connection, this may well be the reason for the unreliability.

There is an 1&1 configuration example in the OpenWrt wiki, which you could compare to your own config (refer to subsection "1&1 VDSL DS-Lite"):

The WAN port on OpenWrt is currently untagged (VLAN2 used only internally), and this is fine as long as the Fritz!Box takes care of adding/removing the VLAN 7 tag.

You could also take a PPPoE packet capture with the Fritz!Box (when it logs in with PPPoE to the ISP), as described under the heading "1&1 or O² on a Telekom line with Annex J", and compare it to the pppd debug log when OpenWrt runs the PPPoE.

1 Like

Thank you. I'd like to underscore, that I'm juggling around with setting up the back and forth pppoe and bridge mode on the fritzbox modem, so it's from time to time there. And at the moment PPPoE appears on the modem, wan6 gets the IPv6 address.

Nevertheless, the hint to take packets on the modem was very good. Here you can find it. The problem looks to me in the 24-25 frame with id 0x00fa2a9f. But what exactly the provider doesn't like and how to fix it?

upd: btw, it looks to me like I'm not the only one with the issue:

upd2: I suspect that it could be a OPTION_CLIENT_FQDN (39) from rfc4704. Is there way to try to disable it? No, It's not the option. Tuned up the script by adding -f to odhcp6c, still broken.

Another wild guess, is two different solicitation packets. The modem sends it only once, but the router duplicates the first by some reason:

tcpdump: listening on pppoe-1und1, link-type LINUX_SLL (Linux cooked v1), capture size 262144 bytes
23:09:28.373709 IP6 (class 0xa0, hlim 255, next-header ICMPv6 (58) payload length: 64) fe80::9203:25ff:fe74:4e06 > ff02::1: [icmp6 sum ok] ICMP6, router advertisement, length 64
  hop limit 0, Flags [other stateful], pref medium, router lifetime 1800s, reachable time 0ms, retrans timer 0ms
    source link-address option (1), length 8 (1): 90:03:25:74:4e:06
      0x0000:  9003 2574 4e06
    mtu option (5), length 8 (1):  1492
      0x0000:  0000 0000 05d4
    prefix info option (3), length 32 (4): 2001:16b8:6503:6954::/64, Flags [onlink, auto], valid time 259200s, pref. time 172800s
      0x0000:  40c0 0003 f480 0002 a300 0000 0000 2001
      0x0010:  16b8 6503 6954 0000 0000 0000 0000
23:09:28.693874 IP6 (flowlabel 0x6dc2b, hlim 255, next-header ICMPv6 (58) payload length: 8) fe80::d0da:11b1:e22e:52b5 > ff02::2: [icmp6 sum ok] ICMP6, router solicitation, length 8
23:09:28.720501 IP6 (flowlabel 0x9f113, hlim 1, next-header UDP (17) payload length: 110) fe80::d0da:11b1:e22e:52b5.546 > ff02::1:2.547: [udp sum ok] dhcp6 solicit (xid=218ed5 (elapsed-time 0) (option-request SIP-servers-domain SIP-servers-address DNS-server DNS-search-list SNTP-servers NTP-server AFTR-Name opt_67 opt_94 opt_95 opt_96 opt_82) (client-ID hwaddr type 1 b0957548e62c) (reconfigure-accept) (Client-FQDN) (IA_NA IAID:1 T1:0 T2:0) (IA_PD IAID:1 T1:0 T2:0))
23:09:28.727280 IP6 (class 0xa0, hlim 255, next-header ICMPv6 (58) payload length: 64) fe80::9203:25ff:fe74:4e06 > ff02::1: [icmp6 sum ok] ICMP6, router advertisement, length 64
  hop limit 0, Flags [other stateful], pref medium, router lifetime 1800s, reachable time 0ms, retrans timer 0ms
    source link-address option (1), length 8 (1): 90:03:25:74:4e:06
      0x0000:  9003 2574 4e06
    mtu option (5), length 8 (1):  1492
      0x0000:  0000 0000 05d4
    prefix info option (3), length 32 (4): 2001:16b8:6503:6954::/64, Flags [onlink, auto], valid time 259200s, pref. time 172800s
      0x0000:  40c0 0003 f480 0002 a300 0000 0000 2001
      0x0010:  16b8 6503 6954 0000 0000 0000 0000
23:09:28.788437 IP6 (class 0xa0, hlim 255, next-header UDP (17) payload length: 214) fe80::9203:25ff:fe74:4e06.547 > fe80::d0da:11b1:e22e:52b5.546: [udp sum ok] dhcp6 advertise (xid=218ed5 (client-ID hwaddr type 1 b0957548e62c) (server-ID type 47836) (preference 255) (IA_NA IAID:1 T1:0 T2:0 (status-code NoAddrsAvail)) (IA_PD IAID:1 T1:86400 T2:138240 (IA_PD-prefix 2001:16b8:6888:7300::/56 pltime:172800 vltime:259200) (status-code Success)) (DNS-server 2001:1438:2:4::8 2001:1438:2:3::8) (AFTR-Name aftr.hhb1000cihr001.versatel.de))
23:09:28.823255 IP6 (hlim 1, next-header Options (0) payload length: 36) fe80::d0da:11b1:e22e:52b5 > ff02::16: HBH (rtalert: 0x0000) (padn) [icmp6 sum ok] ICMP6, multicast listener report v2, 1 group record(s) [gaddr ff02::2 to_ex { }]
23:09:28.843262 IP6 (hlim 1, next-header Options (0) payload length: 36) fe80::d0da:11b1:e22e:52b5 > ff02::16: HBH (rtalert: 0x0000) (padn) [icmp6 sum ok] ICMP6, multicast listener report v2, 1 group record(s) [gaddr ff02::2 to_ex { }]
23:09:29.092518 IP6 (flowlabel 0x9f113, hlim 1, next-header UDP (17) payload length: 94) fe80::d0da:11b1:e22e:52b5.546 > ff02::1:2.547: [udp sum ok] dhcp6 solicit (xid=a41aa3 (elapsed-time 0) (option-request SIP-servers-domain SIP-servers-address DNS-server DNS-search-list SNTP-servers NTP-server AFTR-Name opt_67 opt_94 opt_95 opt_96 opt_82) (client-ID hwaddr type 1 b0957548e62c) (reconfigure-accept) (Client-FQDN) (IA_PD IAID:1 T1:0 T2:0))
23:09:29.142948 IP6 (class 0xa0, hlim 254, next-header UDP (17) payload length: 60) fe80::9203:25ff:fe74:4e06.547 > fe80::d0da:11b1:e22e:52b5.546: [udp sum ok] dhcp6 advertise (xid=a41aa3 (client-ID hwaddr type 1 b0957548e62c) (server-ID type 47836) (status-code UnspecFail))
tcpdump: pcap_loop: The interface went down
9 packets captured
10 packets received by filter
0 packets dropped by kernel

openwrt.pcap vs fritzbox.pcap

I've surrendered in trying to set-up the PPPoE on the router for now and now it works as an action point in the Fritz!Box LAN.

Hopefully, if anybody has an idea how to fix it, you'll poke me. I'd like to figure out, how to fix the 1und1 (1&1) PPPoE connection on the OpenWRT.

OpenWrt requests an IPv4 address via IPCP (which fails), but the Fritz!Box does not.

OpenWrt pppd log says:

This might be the reason for the ISP to terminate the connection later.
In openwrt.pcap, look at the following packets:

# OpenWrt sends:
17 IPCP configuration request

# ISP reponds:
20 LCP protocol reject: IPCP
33 LCP termination request
35 PPPoED active discovery terminate (PADT)

I suggest to disable IPCP (IPv4) negotiation on OpenWrt, but I do not know the proper way to do this.
Perhaps it can be achieved with the pppd option noip (untested).

1 Like

Thank you very much for getting back!

Unfortunately, I won't have time to check it until a weekend, but I'll come back to it in any case.

I've tried the option, without luck so far..

The strange thing, after some time the connection is eventually established. Between failed and successful connections are seconds. And the config is the same.

I am planning to try different odhcp6c options. Maybe it will help.