AVM FritzBox 7490 nach dem 6.03-Update Toast :-(

Gestern gegen 23 Uhr habe ich meine Fritz!Box 7490 auf die quasi in Nachtschichten erstellte »Hotfix«-Firware Fritz!OS 6.03 aktualisiert; ich nutze zwar keinen der AVM-Dienste Fernzugang oder MyFritz, aber da unklar ist, welchen Bock AVM genau geschossen hat, der es Dritten erlaubte – nach aktuellem Kenntnisstand auch ohne Zugangsdaten zur Fritz!Box – Zugang zur Fritz!Box und gespeicherten Zugangsdaten zu gelangen, muß Vorsicht die Mutter der Porzelankiste sein.

Doch leider ist zumindest meine 7490 seit diesem Update ziemlich im Arsch, sporadische Reboots, gefolgt von langen Phasen, wo angeblich der PPPoE-Server des ISP nicht antwortet, Antwortzeiten von über 1 Minute bei Zugriff auf das Web-UI, …

Wie der Graph zeigt, ist seit dem gestigen Firmwareupgrade meine 7490 alles andere als stabil. Schon heute morgen kurz nach 8 Uhr rebootete sich die »Fritte«, und in der Tat macht sie derzeit keinen wirklich guten, stabilen Eindruck.

Probleme mit angeblich nicht funktionierendem PPPoE kenne ich schon von Fritz!OS auf der 7360, bei der 7490 hat es sich heute morgen immerhin wieder von selbst berappelt, heute abend allerdings habe ich kurzerhand – statt Reboot, der die Logs gelöscht hätte – kurz das DSL-Kabel aus der Box gezogen und dann wieder gesteckt — wie durch Zauberhand war PPPoE plötzlich wieder funktional.

Derzeit hängt das Web-UI wieder, und ein Blick per telnet auf die Box zeigt auch, warum: »ctlmgr« beschäftigt einen der beiden CPU-Kerne vollends, das klingt nicht wirklich vertrauenserweckend:

Mem: 128480K used, 117772K free, 0K shrd, 15772K buff, 58872K cached
CPU0:  2.4% usr  2.9% sys  0.0% nic 94.1% idle  0.0% io  0.0% irq  0.4% sirq
CPU1: 99.0% usr  0.9% sys  0.0% nic  0.0% idle  0.0% io  0.0% irq  0.0% sirq
Load average: 1.89 1.64 1.46 2/122 4076
  PID  PPID USER     STAT   VSZ %VSZ CPU %CPU COMMAND
 1128     1 root     R    16416  6.6   1 49.7 ctlmgr
 4076  4033 root     R     1240  0.5   0  1.4 top -d 2
 1275     1 root     S     4740  1.9   0  0.4 multid
 2352     1 root     S     3432  1.3   0  0.2 usermand
 2879     1 root     S     1416  0.5   1  0.2 hostapd -B /etc/wpa2/WSC_ath1.conf
[…]

Auch andere Meldungen auf der telnet-Console sind nicht vertrauenserweckend:

Feb  9 22:08:00 multid[1275]: csem_p(config_file-S,32768): waiting: last user 1128 (/usr/bin/ctlmgr)
Feb  9 22:08:09 multid[1275]: csem_p(config_file-S,32768): waiting: last user 1348 (/sbin/ddnsd)
Feb  9 22:08:10 multid[1275]: csem_p(config_file-S,32768): waiting: last user 1348 (/sbin/ddnsd)
Feb  9 22:08:11 multid[1275]: csem_p(config_file-S,32768): waiting: last user 1348 (/sbin/ddnsd)
Feb  9 22:08:12 multid[1275]: csem_p(config_file-S,32768): waiting: last user 1348 (/sbin/ddnsd)
Feb  9 22:08:13 multid[1275]: csem_p(config_file-S,32768): waiting: last user 1348 (/sbin/ddnsd)
Feb  9 22:08:14 multid[1275]: csem_p(config_file-S,32768): waiting: last user 1348 (/sbin/ddnsd)
Feb  9 22:08:14 usermand[2352]: csem_p(config_file-S,32768): waiting: last user 1348 (/sbin/ddnsd)
Feb  9 22:08:15 usermand[2352]: csem_p(config_file-S,32768): waiting: last user 1275 (/sbin/multid)

Diese Überlastung eines CPU-Kerns dürfte mit ein Grund sein, warum die Antwortzeiten auf HTTP-Anfragen teilweise in den 20-Sekunden-Bereich (und höher) gehen — siehe Grafik oben.
Nur zum Vergleich, das gleiche Script, welches alle 5 Minuten die Leitungsparameter abfragt, läuft auch gegen die – deutlich schlechter ausgestattete – 7570, die noch den Alice-ADSL2+-Anschluß bedient. Hier sind die Antwortzeiten im Normbereich — die 7570 hat aber auch seit 2 Jahren kein Firmwareupdate von AVM mehr erfahren …

Dank »Smokeping« kann ich recht gut nachvollziehen, daß es nicht nur ein lokales Anzeigeproblem ist. Ein kleiner Lichtblick ist ja, daß die 7490 selbst erkannt hat, daß etwas im Argen liegt: »09.02.14 21:19:06 Es wurde ein Fehlerbericht versendet«. Ich wüßte ja gerne, was dort drin steht, aber DAS verrät AVM nicht; somit werde ich nun, sobald ich wieder Zugriff auf das Web-UI der 7490 habe, den Hinweisen von AVM folgen und den automatischen Berichtsversand deaktivieren. Ich bin mir im Übrigen recht sicher, daß ich dem bei der Inbetriebnahme nicht zugestimmt habe — betrachtet AVM Fritz!OS 6.03 als Beta-/Laborversion (wo derlei üblich/nachvollziehbar ist)?!
Auch bei meiner 7360 war dies aktiviert — und ist nun deaktiviert. Hoffentlich schickt sich AVM nun wirklich keine Mails mehr, überprüfen läßt sich das ja nur schwer …

tl;dr: meine vormals weitgehend zufriedenstellend funktionierende 7490 (für einige Tests kommen die benötigten Teile erst in den nächsten Wochen) ist seit dem gestern durchgeführten Update auf Fritz!OS 6.03 mehr oder minder Toast. Sporadische Reboots, ctlmgr als CPU-Hog, langsam bis gar nicht reagierendes Web-UI — sie ist praktisch unbrauchbar :(

Zum Abschluß noch das Log, was ich von der Box kratzen konnte; kann man das FritzBox-Systemlog eigentlich auch an einen Syslog-Daemon im LAN schicken lassen (um auch Einträge vor dem letzten, sporadischen, Reboot zu sichern)?

Datum
Zeit
Meldung
09.02.14
22:00:02
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
22:00:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:55:02
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:55:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:51:05
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:50:15
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:50:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:45:02
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:45:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:40:02
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:40:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:37:04
WLAN-Gerät wird abgemeldet (2,4 GHz): WLAN-Gerät antwortet nicht. MAC-Adresse: XX:XX:XX:XX:XX:XX. (#0302).
09.02.14
21:35:31
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:35:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:30:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:30:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:25:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:25:01
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:19:38
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:19:38
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:19:09
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:19:06
Es wurde ein Fehlerbericht versendet
09.02.14
21:18:58
Filter-Liste der von der BPjM indizierten Internetseiten erfolgreich aktualisiert.
09.02.14
21:18:49
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:18:43
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:18:38
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:18:31
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:18:07
VPN-Verbindung zu hostname2.dyn.uu.org wurde erfolgreich hergestellt.
09.02.14
21:18:05
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:18:04
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.251.xxx.xxx.
09.02.14
21:18:03
VPN-Verbindung zu hostname1.dyn.uu.org wurde erfolgreich hergestellt.
09.02.14
21:17:59
Verwendetes IPv6-Präfix: 2002:xxxx:xxxx::/56.
09.02.14
21:17:59
Internetverbindung IPv6 (Tunnel) wurde erfolgreich hergestellt.
09.02.14
21:17:59
Internetverbindung wurde erfolgreich hergestellt. IP-Adresse: xxx.xxx.xxx.xxx, DNS-Server: 217.237.151.51 und 217.237.149.205, Gateway: xxx.xxx.xxx.xxx, Breitband-PoP: BERR75-se800-B2244460703306
09.02.14
21:16:55
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:16:48
DSL ist verfügbar (DSL-Synchronisierung besteht mit 51390/10048 kbit/s).
09.02.14
21:15:35
DSL antwortet nicht (Keine DSL-Synchronisierung).
09.02.14
21:15:30
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:14:33
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:14:24
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:13:42
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:13:19
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:13:19
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:12:30
Neues WLAN-Gerät erstmalig angemeldet (5 GHz). Geschwindigkeit 150 Mbit/s. MAC-Adresse: XX:XX:XX:XX:XX:XX.
09.02.14
21:12:30
WLAN-Gerät angemeldet, WLAN wird mit voller Leistung reaktiviert (5 GHz).
09.02.14
21:12:13
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:11:08
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:10:02
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:09:33
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:08:57
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:07:51
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:06:46
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:05:40
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:04:35
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:04:33
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
21:03:29
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:02:24
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:01:18
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
21:00:13
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:59:33
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
20:59:07
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:58:26
Neues WLAN-Gerät erstmalig angemeldet (2,4 GHz). Geschwindigkeit 144 Mbit/s. MAC-Adresse: XX:XX:XX:XX:XX:XX.
09.02.14
20:58:02
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:56:56
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:55:51
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:54:45
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:54:33
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
20:53:40
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:52:34
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:51:29
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:50:23
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:49:33
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
20:49:18
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:48:12
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:47:07
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:47:01
Kein WLAN-Gerät mehr angemeldet, Stromverbrauch wird reduziert (5 GHz).
09.02.14
20:46:26
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
20:46:22
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4 gescheitert (ungültige Sitzungskennung). Zur Sicherheit werden alle noch gültigen Sitzungen zur IP-Adresse 192.168.176.4 beendet.
09.02.14
20:46:21
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4 gescheitert (ungültige Sitzungskennung). Zur Sicherheit werden alle noch gültigen Sitzungen zur IP-Adresse 192.168.176.4 beendet.
09.02.14
20:46:15
Neues WLAN-Gerät erstmalig angemeldet (2,4 GHz). Geschwindigkeit 72 Mbit/s. MAC-Adresse: XX:XX:XX:XX:XX:XX.
09.02.14
20:46:01
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:44:56
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:44:34
Anmeldung an der FRITZ!Box Benutzeroberfläche von IP-Adresse 192.168.176.4.
09.02.14
20:43:50
PPPoE-Fehler: Zeitüberschreitung.
09.02.14
20:43:44
DSL ist verfügbar (DSL-Synchronisierung besteht mit 51386/10048 kbit/s).
09.02.14
20:43:21
UMTS-Modem initialisiert.
09.02.14
20:43:09
USB-Gerät 1002, Klasse 'USB 2.0 (hi-speed) usbgsm', angesteckt
09.02.14
20:43:05
USB-Gerät 1002 abgezogen
09.02.14
20:43:03
USB-Gerät 1002, Klasse 'USB 2.0 (hi-speed) usbgsm', angesteckt