DHCP-Probleme bei älteren Androids (OpenWrt One/pfSense)

[gehrke]

Moin *

Ich möchte mein WLAN aktualisieren und habe mir dazu eine OpenWrt One zugelegt. Diese soll in Zukunft zwei ältere FritzBoxen ersetzen und Dank OpenSource langfristigen Support bieten.

Mein Setup ist so, dass tatsächlich nur WLAN aufgezogen werden soll, nichts anderes. Sonstige Netzwerkdienste werden von pfSense übernommen, wie beispielsweise auch der zentrale DHCP-Server oder PPPoE raus.

Das ganze läuft jetzt seit ca. 3 Wochen im stabilen Testbetrieb und ich bin vor allem mit der Performance sehr zufrieden. Ich sehe eine Steigerung der realen Performance um Faktor bis zu 10 (natürlich auch abhängig vom Client).

Aber ich habe ein Problem mit älteren Android-Clients: Authentifizierung am WLAN klappt, aber sie bekommen scheinbar keine IP vom DHCP zugewiesen.
Problemlos läuft das Ganze mit aktuellen Fedora-Clients und mindestens drei jüngeren Androids.

Bin noch ganz am Anfang der Suche nach den Ursachen, aber es scheint nicht an WPA2/WPA3 zu liegen.

Beispielsweise scheitert die IP-Vergabe bei einem Android 9. Als einzigen Fehlerhinweis sehe ich dort clientseitig nur:

Gespeichert, verschlüsselt (kein Internetzugang)

Ich würde ja den Fehler irgendwo zwischen der OpenWrt One und der pfSense verorten, wenn das Problem immer auftreten würde. Da es aber offenbar nur bei älteren Androids auftritt, tappe ich aktuell noch im Dunkeln.

Glückauf,
gehrke

 

[gehrke]

Bin vielleicht einen wichtigen Schritt weiter gekommen: Habe noch einen weiteren Client gefunden, bei dem es nicht funktioniert.

Dabei handelt es sich mit dem librem5 um ein echtes GNU/linux und dort habe ich vollen Zugriff für das Debugging (bei Android bin ich recht hilflos).

Jun 19 10:44:20 librem5 wpa_supplicant[968]: wlan0: Trying to associate with SSID 'dirty old town'
Jun 19 10:44:20 librem5 NetworkManager[919]: <info>  [1750322660.7905] device (wlan0): supplicant interface state: scanning -> associating
Jun 19 10:44:20 librem5 NetworkManager[919]: <info>  [1750322660.7906] device (p2p-dev-wlan0): supplicant management interface state: scanning -> associating
Jun 19 10:44:20 librem5 wpa_supplicant[968]: wlan0: Associated with 20:05:b7:00:76:83
Jun 19 10:44:20 librem5 wpa_supplicant[968]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jun 19 10:44:20 librem5 wpa_supplicant[968]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE
Jun 19 10:44:20 librem5 NetworkManager[919]: <info>  [1750322660.9940] device (wlan0): supplicant interface state: associating -> 4way_handshake
Jun 19 10:44:20 librem5 NetworkManager[919]: <info>  [1750322660.9941] device (p2p-dev-wlan0): supplicant management interface state: associating -> 4way_handshake
Jun 19 10:44:20 librem5 wpa_supplicant[968]: wlan0: WPA: Key negotiation completed with 20:05:b7:00:76:83 [PTK=CCMP GTK=CCMP]
Jun 19 10:44:20 librem5 wpa_supplicant[968]: wlan0: CTRL-EVENT-CONNECTED - Connection to 20:05:b7:00:76:83 completed [id=0 id_str=]
Jun 19 10:44:21 librem5 systemd-networkd[534]: wlan0: Gained carrier
Jun 19 10:44:21 librem5 NetworkManager[919]: <info>  [1750322661.0065] device (wlan0): supplicant interface state: 4way_handshake -> completed
Jun 19 10:44:21 librem5 NetworkManager[919]: <info>  [1750322661.0066] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "dirty old town"
Jun 19 10:44:21 librem5 NetworkManager[919]: <info>  [1750322661.0067] device (p2p-dev-wlan0): supplicant management interface state: 4way_handshake -> completed
Jun 19 10:44:21 librem5 NetworkManager[919]: <info>  [1750322661.0071] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jun 19 10:44:21 librem5 NetworkManager[919]: <info>  [1750322661.0195] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Jun 19 10:44:21 librem5 systemd-networkd[534]: wlan0: Connected WiFi access point: dirty old town (20:05:b7:00:76:83)
Jun 19 10:44:21 librem5 avahi-daemon[910]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::8eb2:8d11:de9f:97c6.
Jun 19 10:44:21 librem5 avahi-daemon[910]: New relevant interface wlan0.IPv6 for mDNS.
Jun 19 10:44:21 librem5 avahi-daemon[910]: Registering new address record for fe80::8eb2:8d11:de9f:97c6 on wlan0.*.
Jun 19 10:44:22 librem5 systemd-networkd[534]: wlan0: Gained IPv6LL
Jun 19 10:44:22 librem5 systemd-timesyncd[894]: Network configuration changed, trying to establish connection.
Jun 19 10:45:06 librem5 NetworkManager[919]: <warn>  [1750322706.5436] dhcp4 (wlan0): request timed out
Jun 19 10:45:06 librem5 NetworkManager[919]: <info>  [1750322706.5437] dhcp4 (wlan0): state changed unknown -> timeout
Jun 19 10:45:06 librem5 NetworkManager[919]: <info>  [1750322706.5438] device (wlan0): state change: ip-config -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed')
Jun 19 10:45:06 librem5 NetworkManager[919]: <info>  [1750322706.5452] manager: NetworkManager state is now DISCONNECTED
Jun 19 10:45:06 librem5 wpa_supplicant[968]: wlan0: CTRL-EVENT-DISCONNECTED bssid=20:05:b7:00:76:83 reason=3 locally_generated=1
Jun 19 10:45:07 librem5 systemd-networkd[534]: wlan0: Link DOWN
Jun 19 10:45:07 librem5 avahi-daemon[910]: Interface wlan0.IPv6 no longer relevant for mDNS.
Jun 19 10:45:07 librem5 systemd-networkd[534]: wlan0: Lost carrier

Kann es sein, dass es ein Problem mit IPv6 gibt? Das wäre plausibel, denn auf der pfSense konnte ich mich bislang nicht zu IPv6-Support durchringen.

 

[gehrke]

Hhmm, IPv6 auf der Büchse einfach nur versuchsweise abzuschalten, hat das Problem scheinbar noch nicht gelöst.

$ ssh root@openwrt-one


BusyBox v1.36.1 (2024-12-03 11:41:08 UTC) built-in shell (ash)

  _______                     ________        __
 |       |.-----.-----.-----.|  |  |  |.----.|  |_
 |   -   ||  _  |  -__|     ||  |  |  ||   _||   _|
 |_______||   __|_____|__|__||________||__|  |____|
          |__| W I R E L E S S   F R E E D O M
 -----------------------------------------------------
 OpenWrt 24.10.0-rc2, r28161-ea17e958b9
 -----------------------------------------------------
root@OpenWrt:~# uci set 'network.lan.ipv6=0'
root@OpenWrt:~# uci set 'network.wan.ipv6=0'
root@OpenWrt:~# uci set 'dhcp.lan.dhcpv6=disabled'
root@OpenWrt:~# /etc/init.d/odhcpd disable
root@OpenWrt:~# uci commit
root@OpenWrt:~#
root@OpenWrt:~# uci -q delete dhcp.lan.dhcpv6
root@OpenWrt:~# uci -q delete dhcp.lan.ra
root@OpenWrt:~# uci commit dhcp
root@OpenWrt:~# /etc/init.d/odhcpd restart
root@OpenWrt:~#
root@OpenWrt:~# uci set network.lan.delegate="0"
root@OpenWrt:~# uci commit network
root@OpenWrt:~# /etc/init.d/network restart
root@OpenWrt:~#
root@OpenWrt:~# /etc/init.d/odhcpd disable
root@OpenWrt:~# /etc/init.d/odhcpd stop
root@OpenWrt:~#
root@OpenWrt:~# uci -q delete network.globals.ula_prefix
root@OpenWrt:~# uci commit network
root@OpenWrt:~# /etc/init.d/network restart

Disable IPV6 - 3os

Client:

Jun 19 11:45:04 librem5 systemd-networkd[534]: wlan0: Connected WiFi access point: dirty old town (20:05:b7:00:76:83)
Jun 19 11:45:04 librem5 NetworkManager[919]: <info>  [1750326304.2014] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Jun 19 11:45:04 librem5 avahi-daemon[910]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::8eb2:8d11:de9f:97c6.
Jun 19 11:45:04 librem5 avahi-daemon[910]: New relevant interface wlan0.IPv6 for mDNS.
Jun 19 11:45:04 librem5 avahi-daemon[910]: Registering new address record for fe80::8eb2:8d11:de9f:97c6 on wlan0.*.
Jun 19 11:45:05 librem5 systemd-networkd[534]: wlan0: Gained IPv6LL
Jun 19 11:45:05 librem5 systemd-timesyncd[894]: Network configuration changed, trying to establish connection.
Jun 19 11:45:10 librem5 systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Jun 19 11:45:49 librem5 NetworkManager[919]: <warn>  [1750326349.4057] dhcp4 (wlan0): request timed out
Jun 19 11:45:49 librem5 NetworkManager[919]: <info>  [1750326349.4059] dhcp4 (wlan0): state changed unknown -> timeout
Jun 19 11:45:49 librem5 NetworkManager[919]: <info>  [1750326349.4062] device (wlan0): state change: ip-config -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed>
Jun 19 11:45:49 librem5 NetworkManager[919]: <info>  [1750326349.4111] manager: NetworkManager state is now DISCONNECTED
Jun 19 11:45:49 librem5 wpa_supplicant[968]: wlan0: CTRL-EVENT-DISCONNECTED bssid=20:05:b7:00:76:83 reason=3 locally_generated=1
Jun 19 11:45:49 librem5 avahi-daemon[910]: Interface wlan0.IPv6 no longer relevant for mDNS.
Jun 19 11:45:49 librem5 avahi-daemon[910]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::8eb2:8d11:de9f:97c6.
Jun 19 11:45:49 librem5 systemd-networkd[534]: wlan0: Link DOWN
Jun 19 11:45:49 librem5 systemd-networkd[534]: wlan0: Lost carrier
Jun 19 11:45:49 librem5 avahi-daemon[910]: Withdrawing address record for fe80::8eb2:8d11:de9f:97c6 on wlan0.
Jun 19 11:45:49 librem5 NetworkManager[919]: <info>  [1750326349.9562] device (wlan0): set-hw-addr: set MAC address to 5E:FC:8E:F7:30:84 (scanning)
Jun 19 11:45:49 librem5 systemd-networkd[534]: wlan0: Link UP
Jun 19 11:45:49 librem5 NetworkManager[919]: <warn>  [1750326349.9651] device (wlan0): Activation: failed for connection 'dirty old town'
Jun 19 11:45:49 librem5 NetworkManager[919]: <info>  [1750326349.9667] device (wlan0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Jun 19 11:45:49 librem5 NetworkManager[919]: <info>  [1750326349.9890] dhcp4 (wlan0): canceled DHCP transaction
Jun 19 11:45:49 librem5 NetworkManager[919]: <info>  [1750326349.9891] dhcp4 (wlan0): state changed timeout -> terminated