[gelöst] Fhem crasht nach 30 Sekunden

Begonnen von oldscout, 05 Juli 2022, 06:47:30

Vorheriges Thema - Nächstes Thema

oldscout

Hallo liebe Leute,
ich brauche dringend ein paar Tipps von Euch.
Seit gestern Morgen crasht Fhem nach ca 30 Sekunden, die Webseite ist danach nicht mehr erreichbar, in der Prozessliste stehen noch 4 Prozesse, so wie immer.
Der Start erfolgt noch, ich sehe kurzzeitig ca 25 Prozesse von perl, das sind die DBREP Inits.
Es ist seit dem Morgen nichts passiert an der Installation, kein Update usw.
Hab mein komplettes Netzwerk schon neu gestartet, auch den FHem -server mehrfach selbst.
Im Logfile steht bis zum Crash auch alles wie immer drin.
Das ist eine Debian10 mit Mysql-Maschine. Die SQL DB ist auch erreichbar und (hoffentlich) i.O.
Wie kann man ein Device von "aussen" in der DB deaktivieren? Ich habe den Verdacht, das das Apt-Get-Device Probleme machen könnte, obwohl dieses schon lange drin steht.
Den apt-get-Upgrade-Start sieht man auch kurz in der Prozessliste.
Fhem lauscht nicht auf den Ports, laut nmap, aber die Prozesse laufen.l
Weiß grad nicht weiter.
Danke.
FHEM 5.8 auf Intel Celeron CPU
HM-.*, 1-Wire DS18B20, FritzDect 200, HMLAN, HMUSB, Arduino Uno, ESP8266, Enigma2, FB7490, MySql-DB,TP-Link HS100, RaspiCCU

rudolfkoenig


oldscout

#2
Hallo,
danke für den Hinweis mit der debug-Möglichkeit, kannte ich noch nicht. Im Rescue-modus lief es.
Das Debugfile ist 2MB
Der Abbruch erfolgt nicht immer an der gleichen Stelle, die Webseite ist kurz erreichbar, dann nicht mehr.
ich habe im Log der Mysql folgenden Eintrag:
Aborted connection xx to db: "fhem" user: xx ... (Got an error reading communication packets)

Vielleicht hatte diesen schon mal jemand?
Habe zwei Einstellungen in der my.cnf versucht ohne Erfolg:
wait_timeout=60
max_allowed_packet= 500M
An den DB Einstellungen habe ich bisher nichts verändert, die lief seit ewig mit den Standardeinstellungen.

Der letzte Eintrag im debug-log ist ein ping auf ein zu testendes Netzwerkgerät.

Gibt es weitere Tipps bitte?
FHEM 5.8 auf Intel Celeron CPU
HM-.*, 1-Wire DS18B20, FritzDect 200, HMLAN, HMUSB, Arduino Uno, ESP8266, Enigma2, FB7490, MySql-DB,TP-Link HS100, RaspiCCU

rudolfkoenig

ZitatIm Rescue-modus lief es.
Daraus folgere ich, dass es sich um eine configDB Installation handelt.
Als Naechstes wuerde ich im Terminal "perl fhem.pl -d configDB" starten, und die letzten 10-20 Zeilen der Terminal-Ausgabe vor dem Abbruch hier anhaengen.



oldscout

Die letzten ca 50 Zeilen sind hier nur normale Ping Ausgaben, die man auch auf der console hat. Je nach Anwesenheit des Netzwerkgerätes mit echo oder ohne.
Was mich etwas stutzig macht, warum die uniqueID gefühlt 100x gelesen wird innerhalb 13 Sekunden. Und später im Log erneut ein paar Mal.
FHEM 5.8 auf Intel Celeron CPU
HM-.*, 1-Wire DS18B20, FritzDect 200, HMLAN, HMUSB, Arduino Uno, ESP8266, Enigma2, FB7490, MySql-DB,TP-Link HS100, RaspiCCU

Wernieman

configDb und LogDB? oder nur eines davon?

Ist die DB auf dem gleichen System? Bzw. was ist es überhaupt für ein System?
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html

betateilchen

Zitat von: Wernieman am 05 Juli 2022, 20:12:07
Ist die DB auf dem gleichen System?
Bzw. was ist es überhaupt für ein System?

Diese beiden Antworten würden mich auch interessieren...

Zitat von: oldscout am 05 Juli 2022, 06:47:30
Seit gestern Morgen crasht Fhem nach ca 30 Sekunden,
..
Es ist seit dem Morgen nichts passiert an der Installation, kein Update usw.

Da wette ich spontan dagegen. So ein Verhalten kommt nicht "von alleine" oder "aus heiterem Himmel".
Gibt es Fehlermeldungen im Log des Datenbankservers?

-----------------------
Formuliere die Aufgabe möglichst einfach und
setze die Lösung richtig um - dann wird es auch funktionieren.
-----------------------
Lesen gefährdet die Unwissenheit!

oldscout

#7
Hallo,
ich habe weiter oben geschrieben dass es ein debian10 mit mysql ist, ja die Db läuft auf dem selben System, beides configDb und logDB.
Und es war ein spontaner Ausfall morgens um 7.30 Uhr, da war ich auf Arbeit, das letzte Update war am 7.6.22
Das steht meistens dann an, wenn  die fhem.pl Datei aktualisiert wurde.
Eine Warnung des SQL-Server habe ich gestern im Beitrag formuliert, ist aber nur(??) eine Warnung.
Einen Check der Datenbank habe ich durchgeführt, alles OK.
Werde heute nachmittag eine Speichertest machen....
Und wenn der I.O. ist????
fhem -Prozesse laufen noch, siehe Anhang....
FHEM 5.8 auf Intel Celeron CPU
HM-.*, 1-Wire DS18B20, FritzDect 200, HMLAN, HMUSB, Arduino Uno, ESP8266, Enigma2, FB7490, MySql-DB,TP-Link HS100, RaspiCCU

betateilchen

Zitat von: oldscout am 06 Juli 2022, 06:37:42
das letzte Update war am 7.6.22
Das steht meistens dann an, wenn  die fhem.pl Datei aktualisiert wurde.

Das ist eine ziemlich abstruse Taktik. Die Aktualisierung der verwendeten Module erfolgt in der Regel häufiger als die Aktualisierung der fhem.pl. Warum man ein Update davon abhängig macht, ob eine willkürlich ausgewählte Datei aktualisiert wurde, erschließt sich mir in keiner Weise.

Die beiden Aussagen

Zitat von: oldscout am 05 Juli 2022, 17:45:41
Der Abbruch erfolgt nicht immer an der gleichen Stelle, die Webseite ist kurz erreichbar, dann nicht mehr.
ich habe im Log der Mysql folgenden Eintrag:
Aborted connection xx to db: "fhem" user: xx ... (Got an error reading communication packets)

und

Zitat von: oldscout am 06 Juli 2022, 06:37:42
Und es war ein spontaner Ausfall morgens um 7.30 Uhr

erhärten den Verdacht, dass das Problem nicht von FHEM selbst verursacht wird, sondern von Deiner Systemumgebung.
Zur verwendeten Hardware, auf der das Debian 10 läuft, hast Du noch nichts geschrieben.




Das hier irritiert mich auch ein bisschen:

Zitat von: oldscout am 05 Juli 2022, 06:47:30
Der Start erfolgt noch, ich sehe kurzzeitig ca 25 Prozesse von perl, das sind die DBREP Inits.

25 Prozesse für DbRep?
-----------------------
Formuliere die Aufgabe möglichst einfach und
setze die Lösung richtig um - dann wird es auch funktionieren.
-----------------------
Lesen gefährdet die Unwissenheit!

oldscout

#9
Hmmm, zur 1. Frage hardware:
Intel Atom 1,4GHz, 4GB RAM, 8GB SSD, 3x LAN
was die 25 prozesse angehen, da könnt ich auch falsch liegen:
Beim starten von fhem sind in der prozesstabelle (ps -efa) ca 25 "perl fhem.pl config.DB" prozesse, nach kiurzer Zeit sind diese dann weg.
Ich war/bin der Meinung das könnte mit der Anfangsinitialisierung der DBRep Devices zusammen hängen, die ja beim Start nur "angelegt" werden um den Start zu beschleunigen.
War sinngemäß irgendwo mal zu lesen. Kann  gut sein dass ich mich da irre. War aber schon immer so zu beobachten.
Was das updaten betrifft: never touch a running system!! Daher eher sparsame Updates, heißt ja nicht keine. Bin zu lange in der Materie, auch bei fhem trat das ja schon auf, dass nach einem update nicht mehr alles ging. fhem nutze ich seit 2013. Nun stehe ich eben mal vor einem größeren Problem und komme nicht weiter.
Aber warum läuft der fhem-prozess und hat Port 8083 nicht mehr offen? Gibt es noch eine andere debug-Möglichkeit?
Sicher ist ein Hardwareproblem denkbar, aber ftp, ssh, http, smtp läuft alles noch, auch OWServer, HMLAN alles schienbar ok.

Korrektur: SSD ist 128GB
FHEM 5.8 auf Intel Celeron CPU
HM-.*, 1-Wire DS18B20, FritzDect 200, HMLAN, HMUSB, Arduino Uno, ESP8266, Enigma2, FB7490, MySql-DB,TP-Link HS100, RaspiCCU

DS_Starter

Zitat
Ich war/bin der Meinung das könnte mit der Anfangsinitialisierung der DBRep Devices zusammen hängen, die ja beim Start nur "angelegt" werden um den Start zu beschleunigen.
Kurze Erläuterung dazu.
Ist das Attribut fastStart nicht oder auf "1" gesetzt, erfolgt beim Start von FHEM keine Verbindung der DbRep Devices zur DB und der state verbleibt auf "initialized". Die Verbindung erfolgt in dem Fall wenn das Device seine erste Aufgabe bekommt.
Ist das Attr explizit auf "0" gesetzt, erfolgt beim Start von FHEM eine sofortige Verbindung zur DB.

Du kannst/solltest mal prüfen ob du das Attr in den DbRep Devices evtl. auf 0 gesetzt hast, oder die Devices durch ein notify o.ä. veranlasst werden sofortige Auswertungen aus der DB zu ziehen.

Ich glaube aber nicht dass damit ein Problem vorliegt. Typischerweise würden im Log Meldungen wie "Cannot fork: Cannot allocate memory" wenn dein Speicher knapp werden würde.
Schaden kann es aber dennoch nicht diese Einstellungen zu prüfen/optimieren.
 
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

rudolfkoenig

ZitatAber warum läuft der fhem-prozess und hat Port 8083 nicht mehr offen?
Womoeglich laeuft einer der geforkten Prozesse noch.

ZitatGibt es noch eine andere debug-Möglichkeit?
Ich warte immer noch auf die letzten 50 Zeilen von "perl fhem.pl -d configDB".

oldscout

#12
Ok, hier ein paar mehr, weil die Pings in den letzte Zeilen interessierten sicher nicht:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: MESS_1_Pwr, Type: CUL_HM, Event: energy: 14096, Reading: energy, Value: 14096, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: MESS_1_Pwr, Type: CUL_HM, Event: power: 1.69, Reading: power, Value: 1.69, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: MESS_1_SenPwr, Type: CUL_HM, Event: state: 1.69, Reading: state, Value: 1.69, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:30, Device: OW_BUSMASTER, Type: OWDEVICE, Event: present: 1, Reading: present, Value: 1, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: SZ_Heizung, Type: CUL_HM, Event: desired-temp: off, Reading: desired-temp, Value: off, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: SZ_Heizung, Type: CUL_HM, Event: measured-temp: 25.2, Reading: measured-temp, Value: 25.2, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: moni_MotorErr, Type: MONITORING, Event: state: warning remove: SZ_Thermostat, Reading: state, Value: warning remove: SZ_Thermostat, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: moni_MotorErr, Type: MONITORING, Event: warning: AZ_Thermostat,BAD_o_Handtuch_Thermostat,BK_Thermostat,BZ_Thermostat,FO_Thermostat,GZ_Thermostat,WZ_li_Thermostat, Reading: warning, Value: AZ_Thermostat,BAD_o_Handtuch_Thermostat,BK_Thermostat,BZ_Thermostat,FO_Thermostat,GZ_Thermostat,WZ_li_Thermostat, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: moni_MotorErr, Type: MONITORING, Event: warningCount: 7, Reading: warningCount, Value: 7, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: moni_MotorErr, Type: MONITORING, Event: allCount: 7, Reading: allCount, Value: 7, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: SZ_Thermostat, Type: CUL_HM, Event: battery: ok, Reading: battery, Value: ok, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: SZ_Thermostat, Type: CUL_HM, Event: batteryLevel: 2.9, Reading: batteryLevel, Value: 2.9, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: SZ_Thermostat, Type: CUL_HM, Event: motorErr: ok, Reading: motorErr, Value: ok, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: MESS_3_Pwr, Type: CUL_HM, Event: current: 0, Reading: current, Value: 0, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:30, Device: OW_BUSMASTER, Type: OWDEVICE, Event: location: bus.0, Reading: location, Value: bus.0, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: MESS_4_Pwr, Type: CUL_HM, Event: current: 421, Reading: current, Value: 421, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: MESS_4_Pwr, Type: CUL_HM, Event: energy: 13782.6, Reading: energy, Value: 13782.6, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: MESS_4_Pwr, Type: CUL_HM, Event: power: 58.89, Reading: power, Value: 58.89, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: BK_Heizung, Type: CUL_HM, Event: desired-temp: off, Reading: desired-temp, Value: off, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: BK_Heizung, Type: CUL_HM, Event: measured-temp: 27.3, Reading: measured-temp, Value: 27.3, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: moni_MotorErr, Type: MONITORING, Event: state: warning remove: BK_Thermostat, Reading: state, Value: warning remove: BK_Thermostat, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: moni_MotorErr, Type: MONITORING, Event: warning: AZ_Thermostat,BAD_o_Handtuch_Thermostat,BZ_Thermostat,FO_Thermostat,GZ_Thermostat,WZ_li_Thermostat, Reading: warning, Value: AZ_Thermostat,BAD_o_Handtuch_Thermostat,BZ_Thermostat,FO_Thermostat,GZ_Thermostat,WZ_li_Thermostat, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: moni_MotorErr, Type: MONITORING, Event: warningCount: 6, Reading: warningCount, Value: 6, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: moni_MotorErr, Type: MONITORING, Event: allCount: 6, Reading: allCount, Value: 6, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: BK_Thermostat, Type: CUL_HM, Event: battery: ok, Reading: battery, Value: ok, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:31, Device: esp1_BM1, Type: ESPEASY, Event: state: absent, Reading: state, Value: absent, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: BK_Thermostat, Type: CUL_HM, Event: batteryLevel: 2.7, Reading: batteryLevel, Value: 2.7, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: BK_Thermostat, Type: CUL_HM, Event: motorErr: ok, Reading: motorErr, Value: ok, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: vccu, Type: CUL_HM, Event: IOopen: 1, Reading: IOopen, Value: 1, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: vccu, Type: CUL_HM, Event: state: HM_zentrale:init,HM_usb:ok, Reading: state, Value: HM_zentrale:init,HM_usb:ok, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:34, Device: 4Relais, Type: CUL_HM, Event: commState: CMDs_processing..., Reading: commState, Value: CMDs_processing..., Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: BAD_o_Handtuch, Type: CUL_HM, Event: desired-temp: off, Reading: desired-temp, Value: off, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: moni_MotorErr, Type: MONITORING, Event: state: warning remove: BAD_o_Handtuch_Thermostat, Reading: state, Value: warning remove: BAD_o_Handtuch_Thermostat, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: moni_MotorErr, Type: MONITORING, Event: warning: AZ_Thermostat,BZ_Thermostat,FO_Thermostat,GZ_Thermostat,WZ_li_Thermostat, Reading: warning, Value: AZ_Thermostat,BZ_Thermostat,FO_Thermostat,GZ_Thermostat,WZ_li_Thermostat, Unit:
2022.07.05 19:06:27 5: End notify loop for MESS_4_SenPwr
2022.07.05 19:06:27 5: Starting notify loop for MESS_4_Sw, 6 event(s), first is commState: CMDs_done
2022.07.05 19:06:27 5: HM_Components: not on any display, ignoring notify
2022.07.05 19:06:27 5: HM_RSSI: not on any display, ignoring notify
2022.07.05 19:06:27 5: SYSMON SYSMON: Exec_Local.4186 Result '511'
2022.07.05 19:06:27 5: SYSMON SYSMON: Exec_Local.4173 Execute 'cat /sys/class/thermal/thermal_zone0/temp 2>&1'
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: moni_MotorErr, Type: MONITORING, Event: warningCount: 5, Reading: warningCount, Value: 5, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: moni_MotorErr, Type: MONITORING, Event: allCount: 5, Reading: allCount, Value: 5, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:31, Device: esp4_RSSI, Type: ESPEASY, Event: state: absent, Reading: state, Value: absent, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: BAD_o_Handtuch_Thermostat, Type: CUL_HM, Event: battery: ok, Reading: battery, Value: ok, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: BAD_o_Handtuch_Thermostat, Type: CUL_HM, Event: batteryLevel: 2.8, Reading: batteryLevel, Value: 2.8, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: BAD_o_Handtuch_Thermostat, Type: CUL_HM, Event: motorErr: ok, Reading: motorErr, Value: ok, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: KE_THSensor, Type: CUL_HM, Event: battery: ok, Reading: battery, Value: ok, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: KE_THSensor, Type: CUL_HM, Event: humidity: 79, Reading: humidity, Value: 79, Unit: %
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: KE_THSensor, Type: CUL_HM, Event: temperature: 19.4, Reading: temperature, Value: 19.4, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: KE_THSensor, Type: CUL_HM, Event: 1: 13.2, Reading: 1, Value: 13.2, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: KE_THSensor, Type: CUL_HM, Event: dewpoint: 15.7, Reading: dewpoint, Value: 15.7, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: var_KE_lueften, Type: DUMMY, Event: dewpoint_KE: 15.7, Reading: dewpoint_KE, Value: 15.7, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: var_KE_lueften, Type: DUMMY, Event: dewpoint_GA: 7.5, Reading: dewpoint_GA, Value: 7.5, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:31, Device: 4Relais, Type: CUL_HM, Event: commState: CMDs_pending, Reading: commState, Value: CMDs_pending, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: var_KE_lueften, Type: DUMMY, Event: state: 0, Reading: state, Value: 0, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: MESS_1_Pwr, Type: CUL_HM, Event: power: 1.68, Reading: power, Value: 1.68, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: MESS_1_SenPwr, Type: CUL_HM, Event: state: 1.68, Reading: state, Value: 1.68, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: MESS_4_Pwr, Type: CUL_HM, Event: current: 424, Reading: current, Value: 424, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: MESS_4_Pwr, Type: CUL_HM, Event: energy: 13779.7, Reading: energy, Value: 13779.7, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: MESS_4_Pwr, Type: CUL_HM, Event: power: 59.59, Reading: power, Value: 59.59, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: MESS_2_Pwr, Type: CUL_HM, Event: current: 0, Reading: current, Value: 0, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: MESS_2_SenPwr, Type: CUL_HM, Event: state: 0, Reading: state, Value: 0, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: FO_Heizung, Type: CUL_HM, Event: measured-temp: 24.5, Reading: measured-temp, Value: 24.5, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: moni_MotorErr, Type: MONITORING, Event: state: warning remove: FO_Thermostat, Reading: state, Value: warning remove: FO_Thermostat, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:31, Device: 4Relais, Type: CUL_HM, Event: state: CMDs_pending, Reading: state, Value: CMDs_pending, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: moni_MotorErr, Type: MONITORING, Event: warning: AZ_Thermostat,BZ_Thermostat,GZ_Thermostat,WZ_li_Thermostat, Reading: warning, Value: AZ_Thermostat,BZ_Thermostat,GZ_Thermostat,WZ_li_Thermostat, Unit:
2022.07.05 19:06:27 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: moni_MotorErr, Type: MONITORING, Event: warningCount: 4, Reading: warningCount, Value: 4, Unit:
2022.07.05 19:06:28 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: moni_MotorErr, Type: MONITORING, Event: allCount: 4, Reading: allCount, Value: 4, Unit:
2022.07.05 19:06:28 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: FO_Thermostat, Type: CUL_HM, Event: battery: ok, Reading: battery, Value: ok, Unit:
2022.07.05 19:06:28 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: FO_Thermostat, Type: CUL_HM, Event: batteryLevel: 2.8, Reading: batteryLevel, Value: 2.8, Unit:
2022.07.05 19:06:28 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: FO_Thermostat, Type: CUL_HM, Event: motorErr: ok, Reading: motorErr, Value: ok, Unit:
2022.07.05 19:06:28 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: GA_Beweg1, Type: CUL_HM, Event: brightness: 172, Reading: brightness, Value: 172, Unit:
2022.07.05 19:06:28 5: System_Activity: not on any display, ignoring notify
2022.07.05 19:06:28 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: WZ_li_Heizung, Type: CUL_HM, Event: desired-temp: off, Reading: desired-temp, Value: off, Unit:
2022.07.05 19:06:28 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:35, Device: WZ_li_Heizung, Type: CUL_HM, Event: measured-temp: 23.1, Reading: measured-temp, Value: 23.1, Unit:
2022.07.05 19:06:28 5: DbLog myDbLog -> processing event Timestamp: 2022-07-05 19:05:36, Device: moni_MotorErr, Type: MONITORING, Event: state: warning remove: WZ_li_Thermostat, Reading: state, Value: warning remove: WZ_li_Thermostat, Unit:
2022.07.05 19:06:28 5: End notify loop for MESS_4_Sw
2022.07.05 19:06:28 5: AMADCommBridge (AMADCommBridge_192.168.1.79_50096) - Connection closed for AMADCommBridge_192.168.1.79_50096
2022.07.05 19:06:28 5: Cmd: >{BlockingStart('4')}<
2022.07.05 19:06:28 5: Cmd: >{PRESENCE_ProcessLocalScan('nw_arduino_uno|0|absent')}<
2022.07.05 19:06:28 5: PRESENCE (nw_arduino_uno) - blocking scan result: nw_arduino_uno|0|absent
2022.07.05 19:06:28 5: Starting notify loop for nw_arduino_uno, 3 event(s), first is absent
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4186 Result '48000'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4173 Execute '[ -f /sys/class/hwmon/hwmon0/device/temp1_input ] && echo 1 || echo 0'
2022.07.05 19:06:28 5: HM_RSSI: not on any display, ignoring notify
2022.07.05 19:06:28 5: System_Activity: not on any display, ignoring notify
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4186 Result '0'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4173 Execute 'cat /sys/class/hwmon/hwmon0/device/hwmon/hwmon0/temp2_input 2>&1'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4186 Result '48000'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4173 Execute 'cat /proc/loadavg'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4186 Result '0.51 0.25 0.13 4/185 3537'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4173 Execute 'cat /proc/stat'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4178 Result '$VAR1 = 'cpu  173532 1435 48629 8522504 1869 0 1603 0 0 0
';
$VAR2 = 'cpu0 173532 1435 48629 8522504 1869 0 1603 0 0 0
';
$VAR3 = 'intr 9058714 10 4 0 0 0 0 0 0 0 0 0 0 6 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 2563781 2528333 1 82500 81824 0 43901 43901 70771 235652 17152 58 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
';
$VAR4 = 'ctxt 7864254
';
$VAR5 = 'btime 1656952973
';
$VAR6 = 'processes 35972
';
$VAR7 = 'procs_running 2
';
$VAR8 = 'procs_blocked 0
';
$VAR9 = 'softirq 6974079 1 1087923 32642 5534270 0 0 4153 0 223 314867
';
'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4173 Execute 'free -V'
2022.07.05 19:06:28 4: DbLog myDbLog -> 302 of 302 events inserted into table history using PK on columns id
2022.07.05 19:06:28 4: DbLog myDbLog -> insert table history committed by autocommit
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4186 Result 'free from procps-ng 3.3.15'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4173 Execute 'LANG=en free -w'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4178 Result '$VAR1 = '              total        used        free      shared     buffers       cache   available
';
$VAR2 = 'Mem:        3930480      910100     1716620       14560       81764     1221996     2761692
';
$VAR3 = 'Swap:        999420           0      999420
';
'
2022.07.05 19:06:28 5: SYSMON SYSMON: getNetworkInfo.2710 get eth0
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4173 Execute '/sbin/ifconfig eth0 2>&1'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4186 Result 'eth0: Fehler beim Auslesen der Schnittstelleninformation: Gerät nicht gefunden'
2022.07.05 19:06:28 5: SYSMON SYSMON: getNetworkInfo.2722 SYSMON_getNetworkInfo>>>>>>>>>>>>>>>>$VAR1 = 'eth0: Fehler beim Auslesen der Schnittstelleninformation: Gerät nicht gefunden';

2022.07.05 19:06:28 5: SYSMON SYSMON: getNetworkInfo.2710 get wlan0
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4173 Execute '/sbin/ifconfig wlan0 2>&1'
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4186 Result 'wlan0: Fehler beim Auslesen der Schnittstelleninformation: Gerät nicht gefunden'
2022.07.05 19:06:28 5: SYSMON SYSMON: getNetworkInfo.2722 SYSMON_getNetworkInfo>>>>>>>>>>>>>>>>$VAR1 = 'wlan0: Fehler beim Auslesen der Schnittstelleninformation: Gerät nicht gefunden';

2022.07.05 19:06:28 5: SYSMON SYSMON: getFileSystemInfo.2608 get root:/
2022.07.05 19:06:28 5: SYSMON SYSMON: getFileSystemInfo.2619 exec df / -m 2>/dev/null
2022.07.05 19:06:28 5: SYSMON SYSMON: Exec_Local.4173 Execute 'df / -m 2>/dev/null'
2022.07.05 19:06:29 5: DbLog myDbLog -> DbLog_PushAsync finished
2022.07.05 19:06:29 5: SYSMON SYSMON: Exec_Local.4178 Result '$VAR1 = 'Dateisystem    1M-Blöcke Benutzt Verfügbar Verw% Eingehängt auf
';
$VAR2 = '/dev/sda1         118712    8119    104521    8% /
';
'
2022.07.05 19:06:29 5: SYSMON SYSMON: getFileSystemInfo.2624 recieved 2 lines
2022.07.05 19:06:29 5: SYSMON SYSMON: getFileSystemInfo.2641 recieved line0 Dateisystem    1M-Blöcke Benutzt Verfügbar Verw% Eingehängt auf

2022.07.05 19:06:29 5: SYSMON SYSMON: getFileSystemInfo.2660 analyse line /dev/sda1         118712    8119    104521    8% /
for /
2022.07.05 19:06:29 5: SYSMON SYSMON: getFileSystemInfo.2669 pattern (/) found
2022.07.05 19:06:29 5: SYSMON SYSMON: getFileSystemInfo.2675 use line /dev/sda1         118712    8119    104521    8% /

2022.07.05 19:06:29 5: PRESENCE (nw_yamaha_mc) - ping command returned with output:
PING 192.168.1.180 (192.168.1.180) 56(84) bytes of data.
64 bytes from 192.168.1.180: icmp_seq=1 ttl=64 time=0.309 ms
64 bytes from 192.168.1.180: icmp_seq=2 ttl=64 time=0.304 ms
64 bytes from 192.168.1.180: icmp_seq=3 ttl=64 time=0.363 ms
64 bytes from 192.168.1.180: icmp_seq=4 ttl=64 time=0.339 ms

--- 192.168.1.180 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 10ms
rtt min/avg/max/mdev = 0.304/0.328/0.363/0.032 ms
2022.07.05 19:06:29 5: PRESENCE (pr_tp_wlan1) - ping command returned with output:
PING 192.168.1.130 (192.168.1.130) 56(84) bytes of data.
64 bytes from 192.168.1.130: icmp_seq=1 ttl=64 time=2.98 ms
64 bytes from 192.168.1.130: icmp_seq=2 ttl=64 time=2.34 ms
64 bytes from 192.168.1.130: icmp_seq=3 ttl=64 time=1.52 ms
64 bytes from 192.168.1.130: icmp_seq=4 ttl=64 time=1.56 ms

--- 192.168.1.130 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 5ms
rtt min/avg/max/mdev = 1.522/2.101/2.984/0.606 ms
2022.07.05 19:06:29 5: PRESENCE (pr_tp_wlan2) - ping command returned with output:
PING 192.168.1.131 (192.168.1.131) 56(84) bytes of data.
64 bytes from 192.168.1.131: icmp_seq=1 ttl=64 time=1.81 ms
64 bytes from 192.168.1.131: icmp_seq=2 ttl=64 time=1.39 ms
64 bytes from 192.168.1.131: icmp_seq=3 ttl=64 time=1.34 ms
64 bytes from 192.168.1.131: icmp_seq=4 ttl=64 time=2.69 ms

--- 192.168.1.131 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 5ms
rtt min/avg/max/mdev = 1.338/1.808/2.692/0.542 ms
2022.07.05 19:06:29 5: PRESENCE (pr_tp_wlan3) - ping command returned with output:
PING 192.168.1.132 (192.168.1.132) 56(84) bytes of data.
64 bytes from 192.168.1.132: icmp_seq=1 ttl=64 time=1.60 ms
64 bytes from 192.168.1.132: icmp_seq=2 ttl=64 time=1.73 ms
64 bytes from 192.168.1.132: icmp_seq=3 ttl=64 time=8.04 ms
64 bytes from 192.168.1.132: icmp_seq=4 ttl=64 time=1.88 ms

--- 192.168.1.132 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 5ms
rtt min/avg/max/mdev = 1.595/3.311/8.044/2.734 ms
2022.07.05 19:06:29 5: PRESENCE (pr_tp_wlan4) - ping command returned with output:
PING 192.168.1.133 (192.168.1.133) 56(84) bytes of data.
From 192.168.1.212 icmp_seq=1 Destination Host Unreachable
From 192.168.1.212 icmp_seq=2 Destination Host Unreachable
From 192.168.1.212 icmp_seq=3 Destination Host Unreachable
From 192.168.1.212 icmp_seq=4 Destination Host Unreachable

--- 192.168.1.133 ping statistics ---
4 packets transmitted, 0 received, +4 errors, 100% packet loss, time 32ms
pipe 4
2022.07.05 19:06:30 5: PRESENCE (pr_tp_wlan5) - ping command returned with output:
PING 192.168.1.87 (192.168.1.87) 56(84) bytes of data.
From 192.168.1.212 icmp_seq=1 Destination Host Unreachable
From 192.168.1.212 icmp_seq=2 Destination Host Unreachable
From 192.168.1.212 icmp_seq=3 Destination Host Unreachable
From 192.168.1.212 icmp_seq=4 Destination Host Unreachable

--- 192.168.1.87 ping statistics ---
4 packets transmitted, 0 received, +4 errors, 100% packet loss, time 18ms
pipe 4
2022.07.05 19:06:30 5: PRESENCE (pr_tp_wlan7) - ping command returned with output:
PING 192.168.1.89 (192.168.1.89) 56(84) bytes of data.
64 bytes from 192.168.1.89: icmp_seq=1 ttl=255 time=1.93 ms
64 bytes from 192.168.1.89: icmp_seq=2 ttl=255 time=2.94 ms
64 bytes from 192.168.1.89: icmp_seq=3 ttl=255 time=1.60 ms
64 bytes from 192.168.1.89: icmp_seq=4 ttl=255 time=1.69 ms

--- 192.168.1.89 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 5ms
rtt min/avg/max/mdev = 1.598/2.038/2.935/0.533 ms
2022.07.05 19:06:30 5: PRESENCE (pr_tp_wlan6) - ping command returned with output:
PING 192.168.1.69 (192.168.1.69) 56(84) bytes of data.
From 192.168.1.212 icmp_seq=1 Destination Host Unreachable
From 192.168.1.212 icmp_seq=2 Destination Host Unreachable
From 192.168.1.212 icmp_seq=3 Destination Host Unreachable
From 192.168.1.212 icmp_seq=4 Destination Host Unreachable

--- 192.168.1.69 ping statistics ---
4 packets transmitted, 0 received, +4 errors, 100% packet loss, time 21ms
pipe 4
2022.07.05 19:06:30 5: PRESENCE (pr_tp_wlan8) - ping command returned with output:
PING 192.168.1.90 (192.168.1.90) 56(84) bytes of data.
From 192.168.1.212 icmp_seq=1 Destination Host Unreachable
From 192.168.1.212 icmp_seq=2 Destination Host Unreachable
From 192.168.1.212 icmp_seq=3 Destination Host Unreachable
From 192.168.1.212 icmp_seq=4 Destination Host Unreachable

--- 192.168.1.90 ping statistics ---
4 packets transmitted, 0 received, +4 errors, 100% packet loss, time 34ms
pipe 4
2022.07.05 19:06:30 5: PRESENCE (handy_u) - ping command returned with output:
PING 192.168.1.79 (192.168.1.79) 56(84) bytes of data.
64 bytes from 192.168.1.79: icmp_seq=1 ttl=64 time=2.70 ms
64 bytes from 192.168.1.79: icmp_seq=2 ttl=64 time=4.11 ms
64 bytes from 192.168.1.79: icmp_seq=3 ttl=64 time=71.7 ms
64 bytes from 192.168.1.79: icmp_seq=4 ttl=64 time=96.6 ms

--- 192.168.1.79 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 4ms
rtt min/avg/max/mdev = 2.698/43.764/96.575/41.312 ms
2022.07.05 19:06:30 5: PRESENCE (handy_k) - ping command returned with output:
PING 192.168.1.77 (192.168.1.77) 56(84) bytes of data.
64 bytes from 192.168.1.77: icmp_seq=1 ttl=64 time=371 ms
64 bytes from 192.168.1.77: icmp_seq=2 ttl=64 time=259 ms
64 bytes from 192.168.1.77: icmp_seq=3 ttl=64 time=77.5 ms
64 bytes from 192.168.1.77: icmp_seq=4 ttl=64 time=408 ms

--- 192.168.1.77 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 5ms
rtt min/avg/max/mdev = 77.463/278.927/408.047/128.518 ms


Bei Sysmon nicht beachten, dass das Device nicht erkannt wird, Wlan ist nicht vorhanden und eth0 ist ein br0 geworden, hab ich nicht geändert, ging bisher so.
Bei den pings ist das Gerät einfach nur offline.

Der Speichertest des PCs ist ohne Fehler beendet worden.
FHEM 5.8 auf Intel Celeron CPU
HM-.*, 1-Wire DS18B20, FritzDect 200, HMLAN, HMUSB, Arduino Uno, ESP8266, Enigma2, FB7490, MySql-DB,TP-Link HS100, RaspiCCU

oldscout

#13
Ergänzung:
Ich habe den MQTT-Server-Broker am Laufen, einige ESP8266 Clients dran, auch ein paar Homematic-Devices publishen ein paar Werte an einen Handy-Client, dieses publisht auch ein paar Sachen. Ich nutze hier das retain-Flag des Brokers, damit die Nachrichten auch nachträglich ankommen, wenn das Handy mal nicht im WLAN ist.
Könnte es nun sein, dass das Reading des Brokers "retain" oder so ähnlich, habs nicht im Kopf, überläuft? Denn da kommt ja doch was zusammen. 

@DS_Starter:
Ich habe dies Attribut gesetzt, wie Du es beschrieben hast, schon vor dem Crash, dann war ich mit meiner Annahme auf dem Holzweg.
Ich könnte es auch momentan kaum überprüfen, 30 Sekunden sind einfach zu wenig :=((

FHEM 5.8 auf Intel Celeron CPU
HM-.*, 1-Wire DS18B20, FritzDect 200, HMLAN, HMUSB, Arduino Uno, ESP8266, Enigma2, FB7490, MySql-DB,TP-Link HS100, RaspiCCU

Happy Fhem User

Zitat von: oldscout am 06 Juli 2022, 06:37:42
fhem -Prozesse laufen noch, siehe Anhang....

Hast Du mal sichergestellt, daß absolut alle alten perl Fhem Prozesse beendet sind, bevor Du Dein Fhem neu startest? Ich frag nur, weil der Screenshot Prozesse vom Jul05 anzeigt - wir sind aber schon am 6.Juli angekommen...