FHEM langsam und verliert immer wieder Verbindung im Webinterface

Begonnen von eddi79, 29 November 2021, 20:26:32

Vorheriges Thema - Nächstes Thema

eddi79

Hallo zusammen,
unser FHEM ist seit einiger Zeit ziemlich träge und benötigt für einen Befehl auch mal 10 Sek um zu schalten.
die Prozesse auf Systemebene mit top liegen alle bei 0,7 bis 3 % CPU also denke ich kanns das nicht sein.
Im Eventmonitor ist mir folgendes aufgefallen:
2021-11-29 20:21:31.664 ESPEasy ESPEasy_newdevice_Sensor_2 absent
2021-11-29 20:21:36.383 alexa alexa alexaFHEM: stopping
2021-11-29 20:21:36.394 Global global DELAYEDSHUTDOWN
2021-11-29 20:21:36.505 alexa alexa alexaFHEM.ProxyConnection: stopping; alexa-fhem terminating
2021-11-29 20:21:36.520 alexa alexa alexaFHEM.ProxyConnection: stopping; alexa-fhem terminating
2021-11-29 20:21:36.528 alexa alexa alexaFHEM.ProxyConnection: stopped
2021-11-29 20:21:38.458 alexa alexa alexaFHEM: stopped; shutdown


Immer dann kommt auch im Browser connection lost - trying reconnection in 5 Sec
Wo könnte man hier noch ansetzten ?

Bin für jeden Tip dankbar.
Markus

MadMax-FHEM

Da startet wohl fhem immer wieder?
Allerdings ist das mit dem kurzen Ausschnitt nur eine Vermutung...

Hast du einen watchdog?
Also auf Systemebene...

Evtl. macht das auch Systemd...

Und: Prozessorlast ist das eine ABER wenn fhem wartet, dann ist die Last niedrig aber fhem trotzdem blockiert...

Mal freezemon aktivieren...

Und auch mal im Log schauen, ob fhem tatsächlich immer wieder startet...

Gruß, Joachim
FHEM PI3B+ Bullseye: HM-CFG-USB, 40x HM, ZWave-USB, 13x ZWave, EnOcean-PI, 15x EnOcean, HUE/deCONZ, CO2, ESP-Multisensor, Shelly, alexa-fhem, ...
FHEM PI2 Buster: HM-CFG-USB, 25x HM, ZWave-USB, 4x ZWave, EnOcean-PI, 3x EnOcean, Shelly, ha-bridge, ...
FHEM PI3 Buster (Test)

eddi79

Danke für den Tipp.
Watchdog hab ich keinen (zumindest nicht wissentlich). Könnte ich das durch eine Befehl rausfinden ?

freezemon ? Ist das ein FHEM Befehl ? (hab da leider wenig plan).

VG
MArkus

GoldenAmerican

Hi Markus,

bei mir wars die Verbindung zu MariaDB. Da die DB gecrasht war und nicht mehr ansprechbar, konnte sich FHEM nicht verbinden und hat mit reconnectDB immer wieder eine Neuverbindung versucht. Ging nicht, also alle anderen Tasks in FHEM ausgebremst.

Vielleicht hilfts Dir bei der Fehlersuche.

Grüße Ralf

Wernieman

Du kannst rausfinden, wie lange Dein FHEM läuft:
In der Konsole: "uptime"

Steht wirklich nicht mehr im Logfile, das Du geschrieben hast?
Wie sieht Dein Eventmonitor (in FHEM) aus?
Wie sieht Der Speicherverbrauch aus? (Linux: free)

Und dann noch die üblichen Verdächtigen: Was steht in /var/log/syslog und /var/log/kern.log zu der Zeit drin?
- 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

eddi79

Danke für den Tipp mit uptime.

Hab ich grad mehrere Male versucht.
das längste war mal 1Min, 19 Sek

Dann kommt Connection lost im browser und beim nächsten Abrufen der uptime sinds wieder 10 oder 20 Sekunden.
Sieht also wirklich so aus als würde mein FEHM alle ca. 80 Sekunden neu starten :-( (würde den Ausstieg erklären und auch, dass manchmal nichts geht.
Wie gibts denn das ? Kann man das wo einstellen ?

Wo finde ich denn das Logfile ? unter opt/FHEM/log/ steht jeden Menge Alexa aber kein fhem.log

Dafür unter FHEM diverse Dateien 0, 0.1, 0.101 (mit 100 Größe). Dies hatte ich vor Wochen mal gelöscht, werden aber scheinbar wieder angelegt.

Hier der Eventmomitor bis Connection lost kam:
2021-11-30 20:12:38.551 CUL_HM Funksch_Kueche commState: CMDs_done
2021-11-30 20:12:38.551 CUL_HM Funksch_Kueche CMDs_done
2021-11-30 20:12:38.556 CUL_HM Licht_Kueche_gross commState: CMDs_done
2021-11-30 20:12:38.585 CUL_HM Licht_Kueche_klein commState: CMDs_done
2021-11-30 20:12:38.585 CUL_HM Licht_Kueche_klein deviceMsg: on (to HMLAN1)
2021-11-30 20:12:38.585 CUL_HM Licht_Kueche_klein level: 100
2021-11-30 20:12:38.585 CUL_HM Licht_Kueche_klein pct: 100
2021-11-30 20:12:38.585 CUL_HM Licht_Kueche_klein on
2021-11-30 20:12:38.585 CUL_HM Licht_Kueche_klein timedOn: off
2021-11-30 20:12:38.601 Pushover pushmsg lastResult: ok
2021-11-30 20:12:38.630 CUL_HM Funksch_Kueche commState: CMDs_pending
2021-11-30 20:12:38.636 CUL_HM Licht_Kueche_gross commState: CMDs_pending
2021-11-30 20:12:38.643 CUL_HM Licht_Kueche_klein commState: CMDs_pending
2021-11-30 20:12:38.646 CUL_HM Funksch_Kueche CMDs_pending
2021-11-30 20:12:38.690 CUL_HM Funksch_Kueche commState: CMDs_processing...
2021-11-30 20:12:38.697 CUL_HM Licht_Kueche_gross commState: CMDs_processing...
2021-11-30 20:12:38.703 CUL_HM Licht_Kueche_klein commState: CMDs_processing...
2021-11-30 20:12:38.890 CUL_HM Funksch_Kueche commState: CMDs_done
2021-11-30 20:12:38.890 CUL_HM Funksch_Kueche CMDs_done
2021-11-30 20:12:38.918 CUL_HM Licht_Kueche_gross commState: CMDs_done
2021-11-30 20:12:38.918 CUL_HM Licht_Kueche_gross deviceMsg: off (to HMLAN1)
2021-11-30 20:12:38.918 CUL_HM Licht_Kueche_gross level: 0
2021-11-30 20:12:38.918 CUL_HM Licht_Kueche_gross pct: 0
2021-11-30 20:12:38.918 CUL_HM Licht_Kueche_gross off
2021-11-30 20:12:38.918 CUL_HM Licht_Kueche_gross timedOn: off
2021-11-30 20:12:38.925 CUL_HM Licht_Kueche_klein commState: CMDs_done
2021-11-30 20:12:39.619 alexa alexa alexaFHEM.ProxyConnection: starting; starting SSH
2021-11-30 20:12:39.731 CUL_HM Funkschalter commState: CMDs_pending
2021-11-30 20:12:39.740 CUL_HM Funkschalter commState: CMDs_processing...
2021-11-30 20:12:40.138 alexa alexa alexaFHEM.ProxyConnection: running; SSH connected
2021-11-30 20:12:40.767 CUL_HM Funkschalter_Wohnzimmer commState: CMDs_pending
2021-11-30 20:12:40.795 CUL_HM Licht_Kamin_Pilz commState: CMDs_pending
2021-11-30 20:12:40.805 CUL_HM Licht_Wohnzimmer commState: CMDs_pending
2021-11-30 20:12:40.810 CUL_HM Funkschalter_Wohnzimmer CMDs_pending
2021-11-30 20:12:40.818 CUL_HM Funkschalter_Wohnzimmer commState: CMDs_processing...
2021-11-30 20:12:40.827 CUL_HM Licht_Kamin_Pilz commState: CMDs_processing...
2021-11-30 20:12:40.838 CUL_HM Licht_Wohnzimmer commState: CMDs_processing...
2021-11-30 20:12:41.014 CUL_HM Funkschalter_Wohnzimmer commState: CMDs_done
2021-11-30 20:12:41.014 CUL_HM Funkschalter_Wohnzimmer CMDs_done
2021-11-30 20:12:41.019 CUL_HM Licht_Kamin_Pilz commState: CMDs_done
2021-11-30 20:12:41.047 CUL_HM Licht_Wohnzimmer commState: CMDs_done
2021-11-30 20:12:41.047 CUL_HM Licht_Wohnzimmer deviceMsg: on (to HMLAN1)
2021-11-30 20:12:41.047 CUL_HM Licht_Wohnzimmer level: 100
2021-11-30 20:12:41.047 CUL_HM Licht_Wohnzimmer pct: 100
2021-11-30 20:12:41.047 CUL_HM Licht_Wohnzimmer on
2021-11-30 20:12:41.047 CUL_HM Licht_Wohnzimmer timedOn: off
2021-11-30 20:12:41.849 CUL_HM Funkschalter_Wohnzimmer commState: CMDs_pending
2021-11-30 20:12:41.859 CUL_HM Licht_Kamin_Pilz commState: CMDs_pending
2021-11-30 20:12:41.869 CUL_HM Licht_Wohnzimmer commState: CMDs_pending
2021-11-30 20:12:41.874 CUL_HM Funkschalter_Wohnzimmer CMDs_pending
2021-11-30 20:12:41.882 CUL_HM Funkschalter_Wohnzimmer commState: CMDs_processing...
2021-11-30 20:12:41.890 CUL_HM Licht_Kamin_Pilz commState: CMDs_processing...
2021-11-30 20:12:41.898 CUL_HM Licht_Wohnzimmer commState: CMDs_processing...
2021-11-30 20:12:42.096 CUL_HM Funkschalter_Wohnzimmer commState: CMDs_done
2021-11-30 20:12:42.096 CUL_HM Funkschalter_Wohnzimmer CMDs_done
2021-11-30 20:12:42.134 CUL_HM Licht_Kamin_Pilz commState: CMDs_done
2021-11-30 20:12:42.134 CUL_HM Licht_Kamin_Pilz deviceMsg: off (to HMLAN1)
2021-11-30 20:12:42.134 CUL_HM Licht_Kamin_Pilz level: 0
2021-11-30 20:12:42.134 CUL_HM Licht_Kamin_Pilz pct: 0
2021-11-30 20:12:42.134 CUL_HM Licht_Kamin_Pilz off
2021-11-30 20:12:42.134 CUL_HM Licht_Kamin_Pilz timedOn: off
2021-11-30 20:12:42.143 CUL_HM Licht_Wohnzimmer commState: CMDs_done
2021-11-30 20:12:45.340 HMLAN HMLAN1 loadLvl: batchLevel
2021-11-30 20:12:57.320 CUL_HM Funkschalter ResndFail
2021-11-30 20:12:57.326 CUL_HM Funkschalter commState: CMDs_done_Errors:1
2021-11-30 20:12:57.333 CUL_HM Funkschalter MISSING ACK
2021-11-30 20:12:59.750 CUL_HM Funkschalter unreachable
2021-11-30 20:13:10.342 HMLAN HMLAN1 loadLvl: batchLevel
2021-11-30 20:13:12.826 HTTPMOD rutenbeck1 temperatur: 3
2021-11-30 20:13:22.008 CUL_HM ELW_Kinderzimmer_links actuator: 5
2021-11-30 20:13:22.008 CUL_HM ELW_Kinderzimmer_links battery: ok
2021-11-30 20:13:22.008 CUL_HM ELW_Kinderzimmer_links batteryLevel: 2.6
2021-11-30 20:13:22.008 CUL_HM ELW_Kinderzimmer_links desired-temp: 21.5
2021-11-30 20:13:22.008 CUL_HM ELW_Kinderzimmer_links measured-temp: 23.0
2021-11-30 20:13:22.008 CUL_HM ELW_Kinderzimmer_links motorErr: ok
2021-11-30 20:13:22.023 CUL_HM ELW_Therm_Kinderzimmer_links ValvePosition: 5
2021-11-30 20:13:22.023 CUL_HM ELW_Therm_Kinderzimmer_links boostTime: -
2021-11-30 20:13:22.023 CUL_HM ELW_Therm_Kinderzimmer_links controlMode: manual
2021-11-30 20:13:22.023 CUL_HM ELW_Therm_Kinderzimmer_links desired-temp: 21.5
2021-11-30 20:13:22.023 CUL_HM ELW_Therm_Kinderzimmer_links measured-temp: 23.0
2021-11-30 20:13:22.023 CUL_HM ELW_Therm_Kinderzimmer_links partyEnd: -
2021-11-30 20:13:22.023 CUL_HM ELW_Therm_Kinderzimmer_links partyStart: -
2021-11-30 20:13:22.023 CUL_HM ELW_Therm_Kinderzimmer_links partyTemp: -
2021-11-30 20:13:22.023 CUL_HM ELW_Therm_Kinderzimmer_links T: 23.0 desired: 21.5 valve: 5
2021-11-30 20:13:22.036 CUL_HM HM_5C455D_Weather measured-temp: 23.0
2021-11-30 20:13:22.036 CUL_HM HM_5C455D_Weather 23.0
2021-11-30 20:13:30.204 CUL_HM ELW_Kinderzimmer_rechts actuator: 10
2021-11-30 20:13:30.204 CUL_HM ELW_Kinderzimmer_rechts battery: ok
2021-11-30 20:13:30.204 CUL_HM ELW_Kinderzimmer_rechts batteryLevel: 2.6
2021-11-30 20:13:30.204 CUL_HM ELW_Kinderzimmer_rechts desired-temp: 24.0
2021-11-30 20:13:30.204 CUL_HM ELW_Kinderzimmer_rechts measured-temp: 27.0
2021-11-30 20:13:30.204 CUL_HM ELW_Kinderzimmer_rechts motorErr: ok
2021-11-30 20:13:30.219 CUL_HM ELW_Therm_Kinderzimmer_rechts ValvePosition: 10
2021-11-30 20:13:30.219 CUL_HM ELW_Therm_Kinderzimmer_rechts boostTime: -
2021-11-30 20:13:30.219 CUL_HM ELW_Therm_Kinderzimmer_rechts controlMode: manual
2021-11-30 20:13:30.219 CUL_HM ELW_Therm_Kinderzimmer_rechts desired-temp: 24.0
2021-11-30 20:13:30.219 CUL_HM ELW_Therm_Kinderzimmer_rechts measured-temp: 27.0
2021-11-30 20:13:30.219 CUL_HM ELW_Therm_Kinderzimmer_rechts partyEnd: -
2021-11-30 20:13:30.219 CUL_HM ELW_Therm_Kinderzimmer_rechts partyStart: -
2021-11-30 20:13:30.219 CUL_HM ELW_Therm_Kinderzimmer_rechts partyTemp: -
2021-11-30 20:13:30.219 CUL_HM ELW_Therm_Kinderzimmer_rechts T: 27.0 desired: 24.0 valve: 10
2021-11-30 20:13:30.232 CUL_HM HM_5AC5BF_Weather measured-temp: 27.0
2021-11-30 20:13:30.232 CUL_HM HM_5AC5BF_Weather 27.0
2021-11-30 20:13:33.475 CUL_HM ELW_Eingang actuator: 6
2021-11-30 20:13:33.475 CUL_HM ELW_Eingang battery: ok
2021-11-30 20:13:33.475 CUL_HM ELW_Eingang batteryLevel: 2.7
2021-11-30 20:13:33.475 CUL_HM ELW_Eingang desired-temp: 21.0
2021-11-30 20:13:33.475 CUL_HM ELW_Eingang measured-temp: 22.8
2021-11-30 20:13:33.475 CUL_HM ELW_Eingang motorErr: ok
2021-11-30 20:13:33.493 CUL_HM ELW_Eingang_Weather measured-temp: 22.8
2021-11-30 20:13:33.493 CUL_HM ELW_Eingang_Weather 22.8
2021-11-30 20:13:33.500 CUL_HM ELW_Therm_Eingang_links ValvePosition: 6
2021-11-30 20:13:33.500 CUL_HM ELW_Therm_Eingang_links boostTime: -
2021-11-30 20:13:33.500 CUL_HM ELW_Therm_Eingang_links controlMode: auto
2021-11-30 20:13:33.500 CUL_HM ELW_Therm_Eingang_links desired-temp: 21.0
2021-11-30 20:13:33.500 CUL_HM ELW_Therm_Eingang_links measured-temp: 22.8
2021-11-30 20:13:33.500 CUL_HM ELW_Therm_Eingang_links partyEnd: -
2021-11-30 20:13:33.500 CUL_HM ELW_Therm_Eingang_links partyStart: -
2021-11-30 20:13:33.500 CUL_HM ELW_Therm_Eingang_links partyTemp: -
2021-11-30 20:13:33.500 CUL_HM ELW_Therm_Eingang_links T: 22.8 desired: 21.0 valve: 6
2021-11-30 20:13:35.345 HMLAN HMLAN1 loadLvl: batchLevel
2021-11-30 20:13:37.663 ESPEasy ESPEasy_newdevice_Sensor_2 absent
2021-11-30 20:13:42.194 ESPEasy ESPEasy_newdevice_Sensor_1 presence: absent
2021-11-30 20:13:42.199 ESPEasy ESPEasy_newdevice_Sensor_1 absent
2021-11-30 20:13:45.133 alexa alexa alexaFHEM: stopping
2021-11-30 20:13:45.142 Global global DELAYEDSHUTDOWN
2021-11-30 20:13:45.236 alexa alexa alexaFHEM.ProxyConnection: stopping; alexa-fhem terminating
2021-11-30 20:13:45.243 alexa alexa alexaFHEM.ProxyConnection: stopping; alexa-fhem terminating
2021-11-30 20:13:45.260 alexa alexa alexaFHEM.ProxyConnection: stopped
2021-11-30 20:13:47.203 alexa alexa alexaFHEM: stopped; shutdown




Wernieman

- 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

KölnSolar

Hi Namensvetter,
das
Zitat2021-11-30 20:13:42.199 ESPEasy ESPEasy_newdevice_Sensor_1 absent
2021-11-30 20:13:45.133 alexa alexa alexaFHEM: stopping
2021-11-30 20:13:45.142 Global global DELAYEDSHUTDOWN
kommt doch scheinbar immer. Da würde ich mal bei ESPEasy_newdevice_Sensor_1 bzw. dem WLAN ansetzen. Denn Du hast wohl in Deinem System etwas eingebaut, das BEWUSST Dein FHEM herunterfährt.
ZitatWo finde ich denn das Logfile ?
Das sollte man schon wissen und auch reingeguckt haben, BEVOR man hier ein Problem postet. Unsere Glaskugeln sind nämlich noch schlechter als Deine.
In Deinem global-device findest Du attr global logfile ....Da steht dann Name u. Pfad DEINES logfiles.
ZitatDafür unter FHEM diverse Dateien 0, 0.1, 0.101 (mit 100 Größe).
Ja was ist das denn ?  ??? :o :-\ Ich hab sowas zumindest noch nie gesehen. Daher spekulier ich mal auf ein Problem mit Deinem Datenträger. Raspberry mit SD ?

Grüße Markus
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

MadMax-FHEM

Eventuell könnten die 0,0.1 usw. (ungewollte) "Ausgaben" eines durch fhem gestarteten Scripts sein.
Hatte ich auch mal ;) bis ich ALLES nach /dev/null "umgeleitet" habe...
Auch wget-Aufrufe können "sowas" verursachen...

Eventmonitor bringt nicht so viel...
Log wäre schon besser...

Auch dein systed-Script...

Und ja: freezemon ist ein fhem Modul: help freezemon (in fhemWeb eingeben) oder commandref oder evtl. auch fhem Wiki...

Gruß, Joachim
FHEM PI3B+ Bullseye: HM-CFG-USB, 40x HM, ZWave-USB, 13x ZWave, EnOcean-PI, 15x EnOcean, HUE/deCONZ, CO2, ESP-Multisensor, Shelly, alexa-fhem, ...
FHEM PI2 Buster: HM-CFG-USB, 25x HM, ZWave-USB, 4x ZWave, EnOcean-PI, 3x EnOcean, Shelly, ha-bridge, ...
FHEM PI3 Buster (Test)

eddi79

Danke für eure Geduld.

Das steht bei mir bezüglich des Logfiles in der Config.
Heißt das es wird keines angelegt ?

attr global autosave 0
attr global backup_before_update 0
attr global logfile -
attr global modpath .



Ja es läuft auf einem Raspberry mit SD.

Schau mir das mit Sytemd heut abend mal in Ruhe an. Welche Datei ist hier genau relevant  ?

Sorry wenn ich oft blöde Fragen stelle, aber Linux ist leider noch ziemliches Neuland für mich. :-(

Markus


MadMax-FHEM

Hm, weiß nicht was ein solcher Eintrag in global "bedeutet" aber "normal" ist das nicht.

Bzgl. systemd wurde doch ein ausführlicher Link gepostet!
Da steht eigentl. alles drin...

Gruß, Joachim
FHEM PI3B+ Bullseye: HM-CFG-USB, 40x HM, ZWave-USB, 13x ZWave, EnOcean-PI, 15x EnOcean, HUE/deCONZ, CO2, ESP-Multisensor, Shelly, alexa-fhem, ...
FHEM PI2 Buster: HM-CFG-USB, 25x HM, ZWave-USB, 4x ZWave, EnOcean-PI, 3x EnOcean, Shelly, ha-bridge, ...
FHEM PI3 Buster (Test)

KölnSolar

ZitatSorry wenn ich oft blöde Fragen stelle, aber Linux ist leider noch ziemliches Neuland für mich.
Das ist nicht sooo schlimm. Gestellte Fragen beantworten ist viel wichtiger.  ;)
(was Du ja machst)
Grüße Markus
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt