Neuer ioBroker-Blog online: Monatsrückblick März/April 2026

[myol]

Hallo zusammen,
ich hatte heute Nachmittag plötzlich, dass alle meine Rolläden herunterfuhren, obwohl es noch lange nicht Zeit dafür war.
Als ich dann ins iobroker log geschaut habe, hatten dort alle Einträge eine andere Uhrzeit und ein Datum in 1979.
Nach einem reboot lief iobroker gar nicht mehr.
Ich konnte das Problem lösen, indem ich ein Update von vor ein paar Tagen eingespielt habe und jetzt läuft alles erstmal wieder.
Trotzdem wundere ich mich, was da passiert ist.
Hat jemand eine Idee?
Gruß
Oliver

[arteck]

@myol warum schaust du dann nicht dirket ins LOG rein..

sollen wir jetzt raten ???

[wendy2702]

Wie sind denn deine Einstellungen für Zeit, Datum, NTP.....

[OliverIO]

Wie alt ist der Rechner?
Pufferbatterie?

[myol]

Hallo zusammen,
danke für die Rückmeldungen und sorry für den geringen Input.
Ich dachte, dass das Problem evtl. bekannt ist.

Mein logfile gestern was soweit nromal, bis um 16:17 folgendes losging:

2020-04-05 16:17:12.110  - info: host.ioBroker-OPiplus2e iobroker.js-controller version 2.2.9 js-controller starting
2020-04-05 16:17:12.138  - info: host.ioBroker-OPiplus2e Copyright (c) 2014-2020 bluefox, 2014 hobbyquaker
2020-04-05 16:17:12.141  - info: host.ioBroker-OPiplus2e hostname: ioBroker-OPiplus2e, node: v10.17.0
2020-04-05 16:17:12.148  - info: host.ioBroker-OPiplus2e ip addresses: 
2020-04-05 16:17:14.073  - error: host.ioBroker-OPiplus2e Cannot parse /opt/iobroker/iobroker-data/objects.json: SyntaxError: Unexpected end of JSON input
2020-04-05 16:17:15.702  - error: host.ioBroker-OPiplus2e Cannot parse /opt/iobroker/iobroker-data/objects.json.bak: SyntaxError: Unexpected end of JSON input
2020-04-05 16:17:15.946  - error: host.ioBroker-OPiplus2e Cannot parse /opt/iobroker/iobroker-data/states.json: SyntaxError: Unexpected end of JSON input
2020-04-05 16:17:16.131  - error: host.ioBroker-OPiplus2e Cannot parse /opt/iobroker/iobroker-data/states.json.bak: SyntaxError: Unexpected end of JSON input
2020-04-05 16:17:16.238  - info: host.ioBroker-OPiplus2e connected to Objects and States
2020-04-05 16:17:16.243  - error: Cannot find view "system"
2020-04-05 16:17:16.285  - error: Cannot find view "system"
2020-04-05 16:17:16.287  - error: Cannot find view "system"
2020-04-05 16:17:16.317  - error: host.ioBroker-OPiplus2e uncaught exception: Cannot read property 'rows' of undefined
2020-04-05 16:17:16.322  - error: host.ioBroker-OPiplus2e TypeError: Cannot read property 'rows' of undefined
    at objects.getObjectView (/opt/iobroker/node_modules/iobroker.js-controller/main.js:1529:34)
    at Immediate.setImmediate (/opt/iobroker/node_modules/iobroker.js-controller/lib/objects/objectsInMemFileDB.js:1993:32)
    at runCallback (timers.js:705:18)
    at tryOnImmediate (timers.js:676:5)
    at processImmediate (timers.js:658:5)
2020-04-05 16:17:16.734  - info: host.ioBroker-OPiplus2e iobroker _restart
2020-04-05 16:17:16.756  - error: host.ioBroker-OPiplus2e _design/system missing - call node iobroker.js setup
2020-04-05 16:17:16.777  - info: host.ioBroker-OPiplus2e terminated
2020-04-05 16:17:19.908  - info: host.ioBroker-OPiplus2e iobroker.js-controller version 2.2.9 js-controller starting
2020-04-05 16:17:19.927  - info: host.ioBroker-OPiplus2e Copyright (c) 2014-2020 bluefox, 2014 hobbyquaker
2020-04-05 16:17:19.930  - info: host.ioBroker-OPiplus2e hostname: ioBroker-OPiplus2e, node: v10.17.0
2020-04-05 16:17:19.935  - info: host.ioBroker-OPiplus2e ip addresses: 192.168.xxx.xxx fd00::54a1:2154:cccc:xxxx fe80::3239:8774:cccc:xxxx
2020-04-05 16:17:20.088  - info: host.ioBroker-OPiplus2e connected to Objects and States
2020-04-05 16:17:20.092  - error: Cannot find view "system"
2020-04-05 16:17:20.135  - error: Cannot find view "system"
2020-04-05 16:17:20.137  - error: Cannot find view "system"
2020-04-05 16:17:20.168  - error: host.ioBroker-OPiplus2e uncaught exception: Cannot read property 'rows' of undefined
2020-04-05 16:17:20.172  - error: host.ioBroker-OPiplus2e TypeError: Cannot read property 'rows' of undefined

Mod-Edit: Code in code-tags gesetzt!

Der zweite Block mit "at objects ..." wiederholt sich ab da alle paar Sekunden.
Die geänderte Sytemzeit, die mir gestern angezeigt wurde, kann ich im log nicht finden. Vermutlich war der Rechner nur völlig ausgelastet mit sich selbst...

Weiss jemand, was da passiert ist?

[wendy2702]

Du musst in das Linux SYslog gucken was da um diese Zeit drin steht.

Zu finden unter

/var/log/

[myol]

OK, danke. Ich bin in Unix nicht so firm...

Die ersten Einträge nach längerer Pause sind um 16:17 der hier:

Apr  5 16:17:05 localhost fake-hwclock[233]: So 5. Apr 14:17:01 UTC 2020
Apr  5 16:17:05 localhost resolvconf[265]: /etc/resolvconf/update.d/libc: Warning: /etc/resolv.conf is not a symbolic link to /etc/resolvconf/run/resolv.conf
Apr  5 16:17:05 localhost systemd[1]: Starting Flush Journal to Persistent Storage...
Apr  5 16:17:05 localhost systemd[1]: Started Create Static Device Nodes in /dev.
Apr  5 16:17:05 localhost systemd[1]: Starting udev Kernel Device Manager...
Apr  5 16:17:05 localhost systemd-modules-load[240]: Inserted module '8189fs'
Apr  5 16:17:05 localhost systemd[1]: Started Load Kernel Modules.
Apr  5 16:17:05 localhost systemd[1]: Starting Apply Kernel Variables...
Apr  5 16:17:05 localhost systemd[1]: Mounting Configuration File System...
Apr  5 16:17:05 localhost systemd[1]: Started Flush Journal to Persistent Storage.
Apr  5 16:17:05 localhost systemd[1]: Mounted Configuration File System.
Apr  5 16:17:05 localhost systemd[1]: Started udev Kernel Device Manager.
Apr  5 16:17:05 localhost systemd[1]: Started Set the console keyboard layout.
Apr  5 16:17:05 localhost systemd[1]: Started Apply Kernel Variables.

Un dann folgen noch ca. 1000 weitere Zeilen um 16:17:05, mit denen ich euch hier nicht zumüllen möchte.
Kann jemand mit dem Startfehler was anfangen?

[myol]

Ach so, auf die Frage von oben:
Ist ein OrangePi, ca. ein halbes Jahr alt.
Der hat zwar keine Pufferbatterie, hängt aber an einer USV.

[wendy2702]

Kannst das log als txt anhängen oder selber nach „error“ , „kill“, „fault“ durchsuchen.

[myol]

Hmm, selbst suchen hilft eher nicht....
Ich lade mal dal log des gestrigen nachmittags/Abends hoch.
Vielleicht sieht ja jemand was.
Im Moment läuft zwar alles wieder, aber ich wüsste schon gerne, ob ich damit jetzt öfter rechnen muss.
log.txt

[Ein ehemaliger Benutzer]

@myol
Hi,
hab mal schnell drüber geschaut, kann es sein, dass du gestern um 16:17:24 die Wifi Schnittstelle aktiviert hast, und danach war der Zeitunterschied von 5h34min - ich folgere, dass da die locale Einstellungen nicht vollständig sind?

Also mal das komplette config durchgehen und schauen, dass wifi ländereinstellungen und alles was mit der locale Zeitzone zu tun hat, ok ist.
Dann generell mal die ntp einstellungen checken, welche Server, wie sieht die ntp.conf aus?

[myol]

Hi, danke für deine Mühe.
Um 16:17 stand ich am Grill und habe definitiv gar nichts aktiviert.
Wo prüfe ich denn die locale und die ntp Einstellungen?
Die ntp.conf enthält nicht viel:

# /etc/ntp.conf, configuration for ntpd; see ntp.conf(5) for help

driftfile /var/lib/ntp/ntp.drift

# Enable this if you want statistics to be logged.
#statsdir /var/log/ntpstats/

statistics loopstats peerstats clockstats
filegen loopstats file loopstats type day enable
filegen peerstats file peerstats type day enable
filegen clockstats file clockstats type day enable


# You do need to talk to an NTP server or two (or three).
#server ntp.your-provider.example

# pool.ntp.org maps to about 1000 low-stratum NTP servers.  Your server will
# pick a different set every time it starts up.  Please consider joining the
# pool: <http://www.pool.ntp.org/join.html>
pool 0.debian.pool.ntp.org iburst
pool 1.debian.pool.ntp.org iburst
pool 2.debian.pool.ntp.org iburst
pool 3.debian.pool.ntp.org iburst


# Access control configuration; see /usr/share/doc/ntp-doc/html/accopt.html for
# details.  The web page <http://support.ntp.org/bin/view/Support/AccessRestrictions>
# might also be helpful.
#
# Note that "restrict" applies to both servers and clients, so a configuration
# that might be intended to block requests from certain clients could also end
# up blocking replies from your own upstream servers.

# By default, exchange time with everybody, but don't allow configuration.
restrict -4 default kod notrap nomodify nopeer noquery limited
restrict -6 default kod notrap nomodify nopeer noquery limited

# Local users may interrogate the ntp server more closely.
restrict 127.0.0.1
restrict ::1

# Needed for adding pool entries
restrict source notrap nomodify noquery

# Clients from this (example!) subnet have unlimited access, but only if
# cryptographically authenticated.
#restrict 192.168.123.0 mask 255.255.255.0 notrust


# If you want to provide time to your local subnet, change the next line.
# (Again, the address is an example only.)
#broadcast 192.168.123.255

# If you want to listen to time broadcasts on your local subnet, de-comment the
# next lines.  Please do this only if you trust everybody on the network!
#disable auth
#broadcastclient

[myol]

locale ergibt folgendes:

# locale
LANG=de_DE.UTF-8
LANGUAGE=de_DE.UTF-8
LC_CTYPE="de_DE.UTF-8"
LC_NUMERIC="de_DE.UTF-8"
LC_TIME="de_DE.UTF-8"
LC_COLLATE="de_DE.UTF-8"
LC_MONETARY="de_DE.UTF-8"
LC_MESSAGES=en_US.UTF-8
LC_PAPER="de_DE.UTF-8"
LC_NAME="de_DE.UTF-8"
LC_ADDRESS="de_DE.UTF-8"
LC_TELEPHONE="de_DE.UTF-8"
LC_MEASUREMENT="de_DE.UTF-8"
LC_IDENTIFICATION="de_DE.UTF-8"
LC_ALL=

[myol]

Ich muss das Thema nochmal hochholen, da der Fehler jetzt schon zweimal erneut aufgetreten ist.
Heute morgen wieder Systemdatum 1979.

Das Systemlog für den entsprechenden Zeitbereich ist hier:
syslog.txt

Es fängt immer mit dieser Meldung "fake-hwclock" an, heute morgen um 01:17:05.
Dann kommen ganz viele Meldungen, die mir nichts sagen und 06:10 heute morgen dann "Time has been changed".

Iobroker lief heute morgen noch und hat auch auf Befehle reagiert. Alle zeitlichen Abläufe waren natürlich daneben.
Ein Zugriff über Putty war nicht mehr möglich: "Connection refused".
Somit ging nur noch Stecker ziehen und danach war alles hin.
Nach Neuinstallation und Backup einspielen lief dann alles wieder.
Da das jetzt aber schon zum dritten mal passiert ist: Hat jemand ne Idee?

Danke und Gruß
Oliver

[myol]

Hmm, ich glaube das "Time has been changed" heute morgen war eher die Meldung nach dem reboot.
Ich finde aber leider keine Logeinträge mit 1979.

[myol]

Ich hab noch nen Screenshot von des Systeminfo bei dem gleichen Fehler vor ein paar Tagen:

[Thomas Braun]

@myol Da wirst du wohl gerade Zeuge wie sich dein Dateisystem verabschiedet.

[myol]

Hmmm, was meinst du damit?
Die SD-Karte?

[Thomas Braun]

@myol Ja, zum Beispiel.

[myol]

Hmmm, wäre zumindest nen Versuch wert...
Danke.