Neues YouTube-Video: Visualisierung im Devices-Adapter

[hg6806]

Hallo zusammen,

ich leide momentan an sporadischen Abstützen meines Systems auf einem RasPi4 4GB. Das passiert so alle paar Tage mal.
Der Pi freezed dann einfach, man kommt auch nicht mehr über die Konsole an ihn ran. Es hilft nur noch Spannung wegnehmen und wieder anstecken. Wenn die SSD gerade in einem Schreibzyklus war, kann man sich ja auch vorstellen was im schlimmsten Fall passieren kann...

Ich logge auch die Pi Hardware mit, Speicher ist zu dem Zeitpunkt noch reichlich vorhanden, CPU Temp. ok, CPU Last auch.

Node JS v12.22.6
NPM 6.14.15
JS controller 3.3.18

Hier mal das Log der letzten Sekunden vor Stillstand, vielleicht findet ja jemand einen Bug.

2021-10-08 06:00:10.732  - info: daswetter.0 (20075) starting. Version 3.0.8 in /opt/iobroker/node_modules/iobroker.daswetter, node: v12.22.6, js-controller: 3.3.18
2021-10-08 06:00:13.485  - info: fhem.0 (1196) State value to set for "fhem.0.myHPSU.HPSU~Wartezeit_BOH" has to be type "string" but received type "number" 
2021-10-08 06:00:14.561  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.MESSAGE_ANSWER" has to be type "integer" but received type "string" 
2021-10-08 06:00:14.810  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.EVENTDURATION" has to be type "number" but received type "string" 
2021-10-08 06:00:14.811  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.EVENTREMAINING" has to be type "number" but received type "string" 
2021-10-08 06:00:15.514  - info: ical.0 (20055) Terminated (NO_ERROR): Without reason
2021-10-08 06:00:15.600  - info: smartmeter.0 (1401) Received 10 values, 2 updated
2021-10-08 06:00:15.951  - info: tvspielfilm.0 (20023) Terminated (NO_ERROR): Without reason
2021-10-08 06:00:16.049  - info: host.raspberrypi instance system.adapter.ical.0 terminated with code 0 (NO_ERROR)
2021-10-08 06:00:16.495  - info: host.raspberrypi instance system.adapter.tvspielfilm.0 terminated with code 0 (NO_ERROR)
2021-10-08 06:00:18.359  - info: fhem.0 (1196) State value to set for "fhem.0.myHPSU.HPSU~Soll_T_Raum_1" has to be type "string" but received type "number" 
2021-10-08 06:00:19.350  - info: smartmeter.1 (1413) Received 10 values, 3 updated
2021-10-08 06:00:24.510  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.MESSAGE_ANSWER" has to be type "integer" but received type "string" 
2021-10-08 06:00:24.514  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.EVENTDURATION" has to be type "number" but received type "string" 
2021-10-08 06:00:24.515  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.EVENTREMAINING" has to be type "number" but received type "string" 
2021-10-08 06:00:27.388  - info: smartmeter.0 (1401) Received 10 values, 3 updated
2021-10-08 06:00:29.721  - info: daswetter.0 (20075) still wrong data structure from server received! repaired...
2021-10-08 06:00:30.383  - info: smartmeter.1 (1413) Received 10 values, 3 updated
2021-10-08 06:00:33.329  - info: fhem.0 (1196) State value to set for "fhem.0.myHPSU.HPSU~Raumthermostat" has to be type "string" but received type "boolean" 
2021-10-08 06:00:34.370  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.MESSAGE_ANSWER" has to be type "integer" but received type "string" 
2021-10-08 06:00:34.866  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.EVENTDURATION" has to be type "number" but received type "string" 
2021-10-08 06:00:34.867  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.EVENTREMAINING" has to be type "number" but received type "string" 
2021-10-08 06:00:35.635  - info: daswetter.0 (20075) Terminated (ADAPTER_REQUESTED_TERMINATION): All data handled, adapter stopped until next scheduled moment
2021-10-08 06:00:36.164  - info: daswetter.0 (20075) cleaned everything up...
2021-10-08 06:00:36.188  - info: host.raspberrypi instance system.adapter.daswetter.0 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION)
2021-10-08 06:00:39.135  - info: smartmeter.0 (1401) Received 10 values, 3 updated
2021-10-08 06:00:41.460  - info: smartmeter.1 (1413) Received 10 values, 3 updated
2021-10-08 06:00:44.554  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.MESSAGE_ANSWER" has to be type "integer" but received type "string" 
2021-10-08 06:00:44.559  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.EVENTDURATION" has to be type "number" but received type "string" 
2021-10-08 06:00:44.560  - info: enigma2.0 (16645) State value to set for "enigma2.0.enigma2.EVENTREMAINING" has to be type "number" but received type "string" 
2021-10-08 06:00:28.265  - info: host.raspberrypi iobroker.js-controller version 3.3.18 js-controller starting
2021-10-08 06:00:28.271  - info: host.raspberrypi Copyright (c) 2014-2021 bluefox, 2014 hobbyquaker
2021-10-08 06:00:28.272  - info: host.raspberrypi hostname: raspberrypi, node: v12.22.6
2021-10-08 06:00:28.273  - info: host.raspberrypi ip addresses: 
2021-10-08 06:00:29.387  - error: host.raspberrypi-Server Cannot load /opt/iobroker/iobroker-data/states.json: /opt/iobroker/iobroker-data/states.json: Unexpected end of JSON input. We try last Backup!
2021-10-08 06:00:30.142  - info: host.raspberrypi connected to Objects and States
2021-10-08 06:00:30.598  - info: host.raspberrypi added notifications configuration of host
2021-10-08 06:00:31.690  - info: host.raspberrypi 52 instances found
2021-10-08 06:00:31.802  - info: host.raspberrypi starting 30 instances
2021-10-08 06:00:32.030  - info: host.raspberrypi instance system.adapter.admin.0 started with pid 903
2021-10-08 06:00:34.029  - error: admin.0 (903) admin.0 already running
2021-10-08 06:00:34.032  - warn: admin.0 (903) Terminated (ADAPTER_ALREADY_RUNNING): Without reason
2021-10-08 06:00:34.727  - error: host.raspberrypi instance system.adapter.admin.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING)
2021-10-08 06:00:34.729  - info: host.raspberrypi Restart adapter system.adapter.admin.0 because enabled
2021-10-08 06:00:35.866  - info: host.raspberrypi instance system.adapter.email.0 started with pid 983
2021-10-08 06:00:37.454  - error: email.0 (983) email.0 already running
2021-10-08 06:00:37.457  - warn: email.0 (983) Terminated (ADAPTER_ALREADY_RUNNING): Without reason
2021-10-08 06:00:38.080  - error: host.raspberrypi instance system.adapter.email.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING)
2021-10-08 06:00:38.081  - info: host.raspberrypi Restart adapter system.adapter.email.0 because enabled
2021-10-08 06:00:39.974  - info: host.raspberrypi instance system.adapter.javascript.0 started with pid 1045
2021-10-08 06:00:42.541  - error: javascript.0 (1045) javascript.0 already running
2021-10-08 06:00:42.544  - warn: javascript.0 (1045) Terminated (ADAPTER_ALREADY_RUNNING): Without reason
2021-10-08 06:00:43.176  - error: host.raspberrypi instance system.adapter.javascript.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING)
2021-10-08 06:00:43.177  - info: host.raspberrypi Restart adapter system.adapter.javascript.0 because enabled
2021-10-08 06:00:43.964  - info: host.raspberrypi instance system.adapter.scenes.0 started with pid 1103
2021-10-08 06:00:45.211  - error: scenes.0 (1103) scenes.0 already running
2021-10-08 06:00:45.214  - warn: scenes.0 (1103) Terminated (ADAPTER_ALREADY_RUNNING): Without reason
2021-10-08 06:00:45.838  - error: host.raspberrypi instance system.adapter.scenes.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING)
2021-10-08 06:00:45.838  - info: host.raspberrypi Restart adapter system.adapter.scenes.0 because enabled
2021-10-08 06:00:47.976  - info: host.raspberrypi instance system.adapter.telegram.0 started with pid 1114
2021-10-08 06:00:49.999  - error: telegram.0 (1114) telegram.0 already running
2021-10-08 06:00:50.002  - warn: telegram.0 (1114) Terminated (ADAPTER_ALREADY_RUNNING): Without reason
2021-10-08 06:00:50.627  - error: host.raspberrypi instance system.adapter.telegram.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING)
2021-10-08 06:00:50.628  - info: host.raspberrypi Restart adapter system.adapter.telegram.0 because enabled
2021-10-08 06:00:51.859  - info: host.raspberrypi instance system.adapter.fhem.0 started with pid 1125
2021-10-08 06:00:53.115  - error: fhem.0 (1125) fhem.0 already running
2021-10-08 06:00:53.118  - warn: fhem.0 (1125) Terminated (ADAPTER_ALREADY_RUNNING): Without reason
2021-10-08 06:00:53.743  - error: host.raspberrypi instance system.adapter.fhem.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING)
2021-10-08 06:00:53.744  - info: host.raspberrypi Restart adapter system.adapter.fhem.0 because enabled
2021-10-08 07:04:09.615  - info: host.raspberrypi instance system.adapter.hm-rega.0 started with pid 1153
2021-10-08 07:04:11.374  - error: hm-rega.0 (1153) hm-rega.0 already running
2021-10-08 07:04:11.377  - warn: hm-rega.0 (1153) Terminated (ADAPTER_ALREADY_RUNNING): Without reason
2021-10-08 07:04:12.074  - error: host.raspberrypi instance system.adapter.hm-rega.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING)
2021-10-08 07:04:12.075  

[paul53]

@hg6806 sagte: sporadischen Abstützen meines Systems auf einem RasPi4

Wird USB 3 verwendet?

[Homoran]

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Es hilft nur noch Spannung wegnehmen und wieder anstecken

niemals!

Geduld (auch etwas länger!) hilft eigentlich immer.
Ursache ist meist, dass der Server aufgrund irgendeines Anlasses ins swappen gerät und dadurch in eine Load average >4, was den Zugriff (bis zum timeout) verlängert.
Dieser Zustand wird irgendwann wieder glattgebügelt.

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Ich logge auch die Pi Hardware mit, Speicher ist zu dem Zeitpunkt noch reichlich vorhanden

tatsächlich?
oder war das die letzte Meldung vor dem Freeze?

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Hier mal das Log der letzten Sekunden vor Stillstand,

sinnvoller wäre wahrscheinlich das syslog.
Was mir aber auffällt ist, dass es genau 06:00 losgeht, was auf einen Trigger um diese Zeit schließen lässt

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

vielleicht findet ja jemand einen Bug.

ein Bug wird das nicht sein!

[paul53]

@homoran sagte: genau 06:00 losgeht, was auf einen Trigger um diese Zeit schließen lässt

"daswetter.0" wird scheduled gestartet (SSD-Zugriff).

[Homoran]

@paul53 sagte:

@homoran sagte: genau 06:00 losgeht, was auf einen Trigger um diese Zeit schließen lässt

"daswetter.0" wird scheduled gestartet (SSD-Zugriff).

aber auch tv Spielfilm kurz vorher.

Ich dachte aber eher an ein Skript, was um diese Zeit möglicherweise auch noch getriggert würde

[hg6806]

Die Abstürze finden NICHT immer morgens um 6Uhr statt. Auch viel tagsüber.

Ich habe auch schon Stunden gewartet, das System fängt sich nicht wieder.

Wie geschrieben, kurz vor dem Freeze lag die CPU Load bei 1,7, nicht ansteigend

[hg6806]

@paul53
Ja, beide USB3 wird verwendet.
Daran ist die SSD angeschlossen, sowie ein USB2.0 Hub mit 3 USB2.0Sticks

[Homoran]

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

beide USB3 wird verwendet.
Daran ist die SSD angeschlossen, sowie ein USB2.0 Hub mit 3 USB2.0Sticks

Abgesehen von der USB3-Problematik stellt sich dann die Frage ob die Spannungsversorgung für so viele Verbraucher reicht

[hg6806]

Ich habe nochmal nachgeschaut. Durch den RPI Adapter kann man das gut sehen, wann das System mal "weg" war.
Es ist grob 1x die Woche aber immer zu unterschiedlichen Uhrzeiten und Tageszeiten, deshalb würde ich einen zeitlichen Trigger hier ausschließen.

[hg6806]

@homoran
Hatte ich mir auch schon überlegt.
Ich habe ein 2,4A Meanwell Hutschienen-Netzteil dran, dass ich auf 5,25V eingestellt habe um evtl. Kabelverluste auszukompensieren, jedoch sind die Kabel recht dick.
An dem Pi sind neben einer SSD keine Stromfresser dran.
Zudem würde das ja vermehrt bei Last abstürzen was nicht der Fall ist.

Kann man in irgendeinem Log eine Unterspannung sehen?

[Homoran]

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Ich habe ein 2,4A Meanwell Hutschienen-Netzteil dran

das reicht nicht! der Pi4 braucht viel Strom

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

An dem Pi sind neben einer SSD keine Stromfresser dran.

und was ist mit den USB-Sticks und dem Hub?

[hg6806]

@homoran
Verbrauchen nur wenige mA, deswegen sehe ich das nicht als Stromfresser.

OK, es sind noch zwei IR-USB-Leseköpfe für Stromzähler dran (Volkszähler)
Weiß jemand was so ein Teil an Strom zieht?

[Homoran]

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Verbrauchen nur wenige mA

auch der Hub?

und selbst wenn es so wäre, es summiert sich

[paul53]

@hg6806 sagte: beide USB3 wird verwendet.

Der Anschluss von USB-3-Geräten kann einen so hohen Störpegel verursachen, dass das RPi OS abstürzt. Wechsle mal auf USB 2.

EDIT: Hat der RPi 4 ein abschirmendes Gehäuse?

[hg6806]

@homoran sagte in Sporadische Abstürtze Raspberry Pi4:

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Verbrauchen nur wenige mA

auch der Hub?

und selbst wenn es so wäre, es summiert sich

Ist ein passiver Hub. Die PowerConsumption von einem Hub-IC ist marginal gering.

OK, alles in Allem sollte ich doch mal ein gr. Netzteil nehmen, da halt immer sukzessive mehr dazugekommen ist.

[Homoran]

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Ist ein passiver Hub

aktiv hätte ja eine eigene Spannungsversorgung und wäre somit besser.

du kannst aber mal im Syslog nach under-voltage suchen

[hg6806]

@paul53 sagte in Sporadische Abstürtze Raspberry Pi4:

@hg6806 sagte: beide USB3 wird verwendet.

Der Anschluss von USB-3-Geräten kann einen so hohen Störpegel verursachen, dass das RPi OS abstürzt. Wechsle mal auf USB 2.

EDIT: Hat der RPi 4 ein abschirmendes Gehäuse?

Habe ich jetzt das erst Mal davon gehört, dass es hier Störungen auf USB3 geben soll.
Nein, Gehäuse ist nicht geschirmt.

[hg6806]

@homoran sagte in Sporadische Abstürtze Raspberry Pi4:

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Ist ein passiver Hub

aktiv hätte ja eine eigene Spannungsversorgung und wäre somit besser.

du kannst aber mal im Syslog nach under-voltage suchen

Wo finde ich auf dem Pi das Syslog?

[hg6806]

Hier mal das Syslog vom Pi zu der Zeit, im Anhang auf Grund der Größe
Ab 6.00Uhr schient das System neu hochgefahren zu sein?

Und was sollen die häufigen Meldungen wie:

Oct  8 07:10:42 raspberrypi dhcpcd[510]: eth0: Router Advertisement from fe80::464e:6dff:fe3a:e01c
Oct  8 07:11:08 raspberrypi bash[548]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Keine Berechtigung

syslog_partial.txt

[Homoran]

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Wo finde ich auf dem Pi das Syslog?

was sagt: tac /var/log/kern.log | grep -i under

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Ab 6.00Uhr schient das System neu hochgefahren zu sein?

das sieht man ja im iobroker log (zumindest dass iobroker da neu startet)

@hg6806 sagte in Sporadische Abstürtze Raspberry Pi4:

Und was sollen die häufigen Meldungen wie:

google sagt:
https://unix.stackexchange.com/questions/273818/can-i-stop-ipv6-router-advertisement-filling-syslog-without-disabling-ipv6