WDR4900 Fehler mit FW 20211224 #203

Closed
opened 2022-01-08 08:00:10 +01:00 by FreiFunkBerglesdorf · 6 comments

bei 2 meiner WDR4900 traten nach dem Update auf 20211224 Fehler auf:

bei dem hier: https://monitoring.freifunk-franken.de/mac/f81a67a5febe
hatte ich einen Tag nach dem Update von node-20211220 -> node-20211224
lediglich die GPS Koordinaten geändert und das Gerät neu gestartet
worauf das Gerät in der Trainstation landete und die
"allgemeinen Einstellungen" komplett gelöscht waren
seit dem ich die Daten wieder eingepflegt habe, läuft dieser Router ohne Probleme

der andere hier: https://monitoring.freifunk-franken.de/mac/f81a675a9004
ging 2 Tage nach dem Update von node-20210226 -> node-20211224
und nachdem ich ihn per WebUI neu gestartet habe (wieso?: #siehe weiter unten)
laut Monitoring "offline" und war über das WebUI nicht mehr erreichbar
er kann zu* diesem Zeitpunkt aber nicht wirklich "offline" gewesen sein
da der Router https://monitoring.freifunk-franken.de/mac/18d6c7515b4a
(der keinen eigenen WAN Uplink hat, sondern nur über den o.g. Router per WLAN mesht)
noch per WebUI erreichbar war UND die Clients darüber noch Zugriff aufs www hatten

vor Ort habe ich den Router zunächst nur kurz vom Stromnetz genommen was keine Änderung/Besserung brachte
danach habe ich den Router über die RESET-Taste resetet
auch keine Besserung/Änderung -> Gerät getauscht, damit die Geflüchteten in dem Haus wieder Zugriff aufs www haben
-> aktuell bekomme ich keinen Zugriff auf diesen Router, er ist weiterhing "offline" im Monitoring
er sendet nach Neustart immer nur das TRAINSTATION WLAN aus
(hing auch schon länger am WAN Uplink)
-> aktuell habe ich auch keinen Plan, wie ich diesen Router wieder zum Laufen bekomme
(Tipps nehme ich gerne an)

#bei allen bisherigen FW war es so, dass zufällig (und nicht nachvollziehbar) irgendwann die per WLAN meshenden Router (egal welcher Typ und Hersteller und FW) im Monitoring "offline" gehen und die Clients keinen Zugriff mehr aufs www haben
der Fehler kann (nur) durch Neustart des Routers (WDR4900) der den WAN Uplink hat, behoben werden

bei 2 meiner WDR4900 traten nach dem Update auf 20211224 Fehler auf: bei dem hier: https://monitoring.freifunk-franken.de/mac/f81a67a5febe hatte ich einen Tag nach dem Update von node-20211220 -> node-20211224 lediglich die GPS Koordinaten geändert und das Gerät neu gestartet worauf das Gerät in der Trainstation landete und die "allgemeinen Einstellungen" komplett gelöscht waren seit dem ich die Daten wieder eingepflegt habe, läuft dieser Router ohne Probleme der andere hier: https://monitoring.freifunk-franken.de/mac/f81a675a9004 ging 2 Tage nach dem Update von node-20210226 -> node-20211224 und nachdem ich ihn per WebUI neu gestartet habe *(wieso?: #siehe weiter unten)* laut Monitoring "offline" und war über das WebUI nicht mehr erreichbar er kann zu* diesem Zeitpunkt aber nicht wirklich "offline" gewesen sein da der Router https://monitoring.freifunk-franken.de/mac/18d6c7515b4a (der keinen eigenen WAN Uplink hat, sondern nur über den o.g. Router per WLAN mesht) noch per WebUI erreichbar war UND die Clients darüber noch Zugriff aufs www hatten vor Ort habe ich den Router zunächst nur kurz vom Stromnetz genommen was keine Änderung/Besserung brachte danach habe ich den Router über die RESET-Taste resetet auch keine Besserung/Änderung -> Gerät getauscht, damit die Geflüchteten in dem Haus wieder Zugriff aufs www haben -> aktuell bekomme ich keinen Zugriff auf diesen Router, er ist weiterhing "offline" im Monitoring er sendet nach Neustart immer nur das TRAINSTATION WLAN aus (hing auch schon länger am WAN Uplink) -> aktuell habe ich auch keinen Plan, wie ich diesen Router wieder zum Laufen bekomme (Tipps nehme ich gerne an) #*bei allen bisherigen FW war es so, dass zufällig (und nicht nachvollziehbar) irgendwann die per WLAN meshenden Router (egal welcher Typ und Hersteller und FW) im Monitoring "offline" gehen und die Clients keinen Zugriff mehr aufs www haben der Fehler kann (nur) durch Neustart des Routers (WDR4900) der den WAN Uplink hat, behoben werden*
fbl added the
bug
more details required
labels 2022-01-08 11:19:27 +01:00

weiter gehts:
der Router https://monitoring.freifunk-franken.de/mac/f81a67a5febe
der zunächst noch fehlerfrei lief (siehe letzte Fehlerbeschreibung)
ist nach nem Neustart auch nicht mehr ereichbar (über WebUI)

er sendet ein WLAN "freifunk.frankenwald" aus

ist "offline" im Monitoring

trotz WAN Uplink ist kein Zugriff aufs www möglich (als Client, es wird keine IP Adresse vergeben)

weiter gehts: der Router https://monitoring.freifunk-franken.de/mac/f81a67a5febe der zunächst noch fehlerfrei lief (siehe letzte Fehlerbeschreibung) ist nach nem Neustart auch nicht mehr ereichbar (über WebUI) er sendet ein WLAN "freifunk.frankenwald" aus ist "offline" im Monitoring trotz WAN Uplink ist kein Zugriff aufs www möglich (als Client, es wird keine IP Adresse vergeben)

btw
dieser WDR4900 mit der aktuellen Node-FW
https://monitoring.freifunk-franken.de/routers/3416
ist im Monitoring auch "offline"
die meshenden Router (ohne WAN Uplink) sehen aber im Monitorin normal aus

btw dieser WDR4900 mit der aktuellen Node-FW https://monitoring.freifunk-franken.de/routers/3416 ist im Monitoring auch "offline" die meshenden Router (ohne WAN Uplink) sehen aber im Monitorin normal aus
Member

Ich kann das bestaetigen. Nach dem firstboot und dem Einrichten laeuft alles.
Nach einem reboot kommt br-client nicht hoch.

br-client: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000

Die Bridge sieht so aus:

bridge l
35: w2ap: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 master br-client state disabled priority 32 cost 100 
36: w5ap: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 master br-client state disabled priority 32 cost 100 

Der netifd ist ja nicht sonderlich gespraechig. Die letzten Zeilen, die was mit br-client zu tun haben:

Thu Jan 27 16:32:03 2022 daemon.notice netifd: Interface 'client' is enabled
Thu Jan 27 16:32:03 2022 daemon.notice netifd: Interface 'client' is setting up now
Thu Jan 27 16:32:03 2022 daemon.notice netifd: Interface 'client' is now up
Thu Jan 27 16:32:03 2022 daemon.notice netifd: bridge 'br-client' link is up
Thu Jan 27 16:32:03 2022 daemon.notice netifd: Interface 'client' has link connectivity
Thu Jan 27 16:32:04 2022 kern.info kernel: [   25.198976] br-client: port 2(bat0) entered blocking state
Thu Jan 27 16:32:04 2022 kern.info kernel: [   25.204540] br-client: port 2(bat0) entered disabled state
Thu Jan 27 16:32:04 2022 kern.info kernel: [   25.215098] br-client: port 2(bat0) entered blocking state
Thu Jan 27 16:32:04 2022 kern.info kernel: [   25.220608] br-client: port 2(bat0) entered forwarding state
Thu Jan 27 16:32:04 2022 kern.info kernel: [   25.444098] IPv6: ADDRCONF(NETDEV_CHANGE): br-client: link becomes ready
Thu Jan 27 16:32:06 2022 kern.info kernel: [   27.398442] br-client: port 3(w5ap) entered blocking state
Thu Jan 27 16:32:06 2022 kern.info kernel: [   27.404036] br-client: port 3(w5ap) entered disabled state
Thu Jan 27 16:32:06 2022 kern.info kernel: [   27.414708] br-client: port 3(w5ap) entered blocking state
Thu Jan 27 16:32:06 2022 kern.info kernel: [   27.420225] br-client: port 3(w5ap) entered forwarding state
Thu Jan 27 16:32:07 2022 kern.info kernel: [   28.537306] br-client: port 4(w2ap) entered blocking state
Thu Jan 27 16:32:07 2022 kern.info kernel: [   28.542857] br-client: port 4(w2ap) entered disabled state
Thu Jan 27 16:32:07 2022 kern.warn kernel: [   28.553325] br-client: adding interface w2ap with same address as a received packet (addr:66:66:b3:c7:19:93, vlan:0)
Thu Jan 27 16:32:07 2022 kern.info kernel: [   28.563908] br-client: port 4(w2ap) entered blocking state
Thu Jan 27 16:32:07 2022 kern.info kernel: [   28.569411] br-client: port 4(w2ap) entered forwarding state
Thu Jan 27 16:32:08 2022 kern.info kernel: [   29.847767] br-client: port 4(w2ap) entered disabled state
Thu Jan 27 16:32:08 2022 kern.info kernel: [   29.863181] br-client: port 3(w5ap) entered disabled state
Thu Jan 27 16:32:08 2022 kern.info kernel: [   29.868786] br-client: port 2(bat0) entered disabled state
Thu Jan 27 16:32:08 2022 kern.info kernel: [   29.874332] br-client: port 1(eth0.1) entered disabled state
Thu Jan 27 16:32:08 2022 daemon.notice netifd: bridge 'br-client' link is down
Thu Jan 27 16:32:08 2022 daemon.notice netifd: Interface 'client' has link connectivity loss
Thu Jan 27 16:32:08 2022 kern.info kernel: [   29.902569] br-client: port 3(w5ap) entered disabled state
Thu Jan 27 16:32:08 2022 kern.info kernel: [   29.912714] br-client: port 2(bat0) entered disabled state
Thu Jan 27 16:32:08 2022 kern.info kernel: [   29.923183] br-client: port 1(eth0.1) entered disabled state
Thu Jan 27 16:32:09 2022 daemon.notice procd: /etc/rc.d/S95done: cat: can't open '/sys/class/net/br-client/address': No such file or directory
Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Device "br-client" does not exist.
Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Device "br-client" does not exist.
Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Set ULA address to br-client:
Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Device "br-client" does not exist.
Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Set ULA EUI-64 address to br-client:
Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Cannot find device "br-client"
Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Cannot find device "br-client"
Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Set ULA route to br-client.
Thu Jan 27 16:32:11 2022 kern.info kernel: [   32.451605] br-client: port 1(w2ap) entered blocking state
Thu Jan 27 16:32:11 2022 kern.info kernel: [   32.457232] br-client: port 1(w2ap) entered disabled state
Thu Jan 27 16:32:20 2022 daemon.notice hostapd: nl80211: Failed to remove interface w5ap from bridge br-client: Invalid argument
Thu Jan 27 16:32:20 2022 kern.info kernel: [   41.371671] br-client: port 2(w5ap) entered blocking state
Thu Jan 27 16:32:20 2022 kern.info kernel: [   41.377197] br-client: port 2(w5ap) entered disabled state
Thu Jan 27 16:33:10 2022 daemon.info micrond[2436]: Set ULA address to br-client: fd43:5602:29bd:16:0:6666:b3c7:1993/64
Thu Jan 27 16:33:10 2022 daemon.info micrond[2436]: Set ULA EUI-64 address to br-client: fd43:5602:29bd:16:6466:b3ff:fec7:1993/64
Thu Jan 27 16:33:10 2022 daemon.info micrond[2436]: Set ULA route to br-client.
Thu Jan 27 16:33:12 2022 kern.info kernel: [   93.501726] br-client: port 1(w2ap) entered disabled state
Thu Jan 27 16:33:12 2022 kern.info kernel: [   93.515954] br-client: port 2(w5ap) entered disabled state
Thu Jan 27 16:33:12 2022 daemon.notice hostapd: nl80211: Failed to remove interface w5ap from bridge br-client: No such device
Thu Jan 27 16:33:14 2022 kern.info kernel: [   96.013475] br-client: port 1(w2ap) entered blocking state
Thu Jan 27 16:33:14 2022 kern.info kernel: [   96.018996] br-client: port 1(w2ap) entered disabled state
Thu Jan 27 16:33:15 2022 kern.info kernel: [   96.153515] br-client: port 2(w5ap) entered blocking state
Thu Jan 27 16:33:15 2022 kern.info kernel: [   96.159030] br-client: port 2(w5ap) entered disabled state
Thu Jan 27 16:35:19 2022 daemon.err micrond[2436]: nodewatcher: Collecting information about connected clients
Thu Jan 27 16:38:07 2022 daemon.info micrond[2436]: Set ULA address to br-client: fd43:5602:29bd:16:0:6666:b3c7:1993/64
Thu Jan 27 16:38:07 2022 daemon.info micrond[2436]: Set ULA EUI-64 address to br-client: fd43:5602:29bd:16:6466:b3ff:fec7:1993/64
Thu Jan 27 16:38:07 2022 daemon.info micrond[2436]: Set ULA route to br-client.

Ich sehe es nicht woran das liegt.

Ich kann das bestaetigen. Nach dem firstboot und dem Einrichten laeuft alles. Nach einem reboot kommt br-client nicht hoch. ``` br-client: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000 ``` Die Bridge sieht so aus: ``` bridge l 35: w2ap: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 master br-client state disabled priority 32 cost 100 36: w5ap: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 master br-client state disabled priority 32 cost 100 ``` Der netifd ist ja nicht sonderlich gespraechig. Die letzten Zeilen, die was mit br-client zu tun haben: ``` Thu Jan 27 16:32:03 2022 daemon.notice netifd: Interface 'client' is enabled Thu Jan 27 16:32:03 2022 daemon.notice netifd: Interface 'client' is setting up now Thu Jan 27 16:32:03 2022 daemon.notice netifd: Interface 'client' is now up Thu Jan 27 16:32:03 2022 daemon.notice netifd: bridge 'br-client' link is up Thu Jan 27 16:32:03 2022 daemon.notice netifd: Interface 'client' has link connectivity Thu Jan 27 16:32:04 2022 kern.info kernel: [ 25.198976] br-client: port 2(bat0) entered blocking state Thu Jan 27 16:32:04 2022 kern.info kernel: [ 25.204540] br-client: port 2(bat0) entered disabled state Thu Jan 27 16:32:04 2022 kern.info kernel: [ 25.215098] br-client: port 2(bat0) entered blocking state Thu Jan 27 16:32:04 2022 kern.info kernel: [ 25.220608] br-client: port 2(bat0) entered forwarding state Thu Jan 27 16:32:04 2022 kern.info kernel: [ 25.444098] IPv6: ADDRCONF(NETDEV_CHANGE): br-client: link becomes ready Thu Jan 27 16:32:06 2022 kern.info kernel: [ 27.398442] br-client: port 3(w5ap) entered blocking state Thu Jan 27 16:32:06 2022 kern.info kernel: [ 27.404036] br-client: port 3(w5ap) entered disabled state Thu Jan 27 16:32:06 2022 kern.info kernel: [ 27.414708] br-client: port 3(w5ap) entered blocking state Thu Jan 27 16:32:06 2022 kern.info kernel: [ 27.420225] br-client: port 3(w5ap) entered forwarding state Thu Jan 27 16:32:07 2022 kern.info kernel: [ 28.537306] br-client: port 4(w2ap) entered blocking state Thu Jan 27 16:32:07 2022 kern.info kernel: [ 28.542857] br-client: port 4(w2ap) entered disabled state Thu Jan 27 16:32:07 2022 kern.warn kernel: [ 28.553325] br-client: adding interface w2ap with same address as a received packet (addr:66:66:b3:c7:19:93, vlan:0) Thu Jan 27 16:32:07 2022 kern.info kernel: [ 28.563908] br-client: port 4(w2ap) entered blocking state Thu Jan 27 16:32:07 2022 kern.info kernel: [ 28.569411] br-client: port 4(w2ap) entered forwarding state Thu Jan 27 16:32:08 2022 kern.info kernel: [ 29.847767] br-client: port 4(w2ap) entered disabled state Thu Jan 27 16:32:08 2022 kern.info kernel: [ 29.863181] br-client: port 3(w5ap) entered disabled state Thu Jan 27 16:32:08 2022 kern.info kernel: [ 29.868786] br-client: port 2(bat0) entered disabled state Thu Jan 27 16:32:08 2022 kern.info kernel: [ 29.874332] br-client: port 1(eth0.1) entered disabled state Thu Jan 27 16:32:08 2022 daemon.notice netifd: bridge 'br-client' link is down Thu Jan 27 16:32:08 2022 daemon.notice netifd: Interface 'client' has link connectivity loss Thu Jan 27 16:32:08 2022 kern.info kernel: [ 29.902569] br-client: port 3(w5ap) entered disabled state Thu Jan 27 16:32:08 2022 kern.info kernel: [ 29.912714] br-client: port 2(bat0) entered disabled state Thu Jan 27 16:32:08 2022 kern.info kernel: [ 29.923183] br-client: port 1(eth0.1) entered disabled state Thu Jan 27 16:32:09 2022 daemon.notice procd: /etc/rc.d/S95done: cat: can't open '/sys/class/net/br-client/address': No such file or directory Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Device "br-client" does not exist. Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Device "br-client" does not exist. Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Set ULA address to br-client: Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Device "br-client" does not exist. Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Set ULA EUI-64 address to br-client: Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Cannot find device "br-client" Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Cannot find device "br-client" Thu Jan 27 16:32:10 2022 daemon.notice procd: /etc/rc.d/S95done: Set ULA route to br-client. Thu Jan 27 16:32:11 2022 kern.info kernel: [ 32.451605] br-client: port 1(w2ap) entered blocking state Thu Jan 27 16:32:11 2022 kern.info kernel: [ 32.457232] br-client: port 1(w2ap) entered disabled state Thu Jan 27 16:32:20 2022 daemon.notice hostapd: nl80211: Failed to remove interface w5ap from bridge br-client: Invalid argument Thu Jan 27 16:32:20 2022 kern.info kernel: [ 41.371671] br-client: port 2(w5ap) entered blocking state Thu Jan 27 16:32:20 2022 kern.info kernel: [ 41.377197] br-client: port 2(w5ap) entered disabled state Thu Jan 27 16:33:10 2022 daemon.info micrond[2436]: Set ULA address to br-client: fd43:5602:29bd:16:0:6666:b3c7:1993/64 Thu Jan 27 16:33:10 2022 daemon.info micrond[2436]: Set ULA EUI-64 address to br-client: fd43:5602:29bd:16:6466:b3ff:fec7:1993/64 Thu Jan 27 16:33:10 2022 daemon.info micrond[2436]: Set ULA route to br-client. Thu Jan 27 16:33:12 2022 kern.info kernel: [ 93.501726] br-client: port 1(w2ap) entered disabled state Thu Jan 27 16:33:12 2022 kern.info kernel: [ 93.515954] br-client: port 2(w5ap) entered disabled state Thu Jan 27 16:33:12 2022 daemon.notice hostapd: nl80211: Failed to remove interface w5ap from bridge br-client: No such device Thu Jan 27 16:33:14 2022 kern.info kernel: [ 96.013475] br-client: port 1(w2ap) entered blocking state Thu Jan 27 16:33:14 2022 kern.info kernel: [ 96.018996] br-client: port 1(w2ap) entered disabled state Thu Jan 27 16:33:15 2022 kern.info kernel: [ 96.153515] br-client: port 2(w5ap) entered blocking state Thu Jan 27 16:33:15 2022 kern.info kernel: [ 96.159030] br-client: port 2(w5ap) entered disabled state Thu Jan 27 16:35:19 2022 daemon.err micrond[2436]: nodewatcher: Collecting information about connected clients Thu Jan 27 16:38:07 2022 daemon.info micrond[2436]: Set ULA address to br-client: fd43:5602:29bd:16:0:6666:b3c7:1993/64 Thu Jan 27 16:38:07 2022 daemon.info micrond[2436]: Set ULA EUI-64 address to br-client: fd43:5602:29bd:16:6466:b3ff:fec7:1993/64 Thu Jan 27 16:38:07 2022 daemon.info micrond[2436]: Set ULA route to br-client. ``` Ich sehe es nicht woran das liegt.

Hallo Robert,
Danke Dir für deine Bemühungen und die Info,
dass du den Fehler bestätigen konntest !

Hallo Robert, Danke Dir für deine Bemühungen und die Info, dass du den Fehler bestätigen konntest !
Owner

Ich hab mir das ganze jetzt mal kurz ein bisschen genauer angesehen. Das Problem tritt bei mir ebenfalls auf, allerdings nur ganz sporadisch. Es scheint sich hier um eine ganz fiese Race Condition zu handeln, die wohl irgendwie mit der Bridge und WLAN zusammen hängt.

Möglicherweise, wie @rohammer auch schon angemert hat, hängt das mit den /etc/init.d/network restart in configurenetwork zusammen.

Ich hab mir das ganze jetzt mal kurz ein bisschen genauer angesehen. Das Problem tritt bei mir ebenfalls auf, allerdings nur ganz sporadisch. Es scheint sich hier um eine ganz fiese Race Condition zu handeln, die wohl irgendwie mit der Bridge und WLAN zusammen hängt. Möglicherweise, wie @rohammer auch schon angemert hat, hängt das mit den /etc/init.d/network restart in configurenetwork zusammen.
fbl removed the
more details required
label 2022-02-27 01:28:21 +01:00
fbl added this to the next-bugfix milestone 2022-02-27 01:28:24 +01:00
fbl modified the milestone from next-bugfix to 20220405-beta 2022-03-03 15:43:04 +01:00
Owner

Sollte mit #209 gefixt sein.

Sollte mit #209 gefixt sein.
fbl closed this issue 2022-03-05 19:29:20 +01:00
Sign in to join this conversation.
No Milestone
No Assignees
3 Participants
Notifications
Due Date
The due date is invalid or out of range. Please use the format 'yyyy-mm-dd'.

No due date set.

Dependencies

No dependencies set.

Reference: freifunk-franken/firmware#203
No description provided.