FHEMSync (Alternative zu FHEM2FHEM/RFHEM)

Maista · 18 April 2020, 21:12:42

Hallo Dominik

ist das "normal" das folgendes immer im Log zu finden ist:


...
[18.4.2020, 20:53:22] [MAIN   ] Monitoring remote device: BMP180
[18.4.2020, 20:53:34] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:53:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:53:57] [MAIN   ] Monitoring remote device: FA_26_A2D984000007
[18.4.2020, 20:53:57] [MAIN   ] Monitoring remote device: FIRMATA
[18.4.2020, 20:53:57] [MAIN   ] Monitoring remote device: FI_26_A3D984001605
[18.4.2020, 20:53:57] [MAIN   ] Monitoring remote device: HI_28_A2D984001677
[18.4.2020, 20:53:57] [MAIN   ] Monitoring remote device: KH_28_FF5A50811605
[18.4.2020, 20:53:57] [MAIN   ] Monitoring remote device: KH_28_FF715C811603
[18.4.2020, 20:53:57] [MAIN   ] Monitoring remote device: KH_28_FF8E8C811603
[18.4.2020, 20:53:57] [MAIN   ] Monitoring remote device: KH_28_FF976C811605
[18.4.2020, 20:53:58] [MAIN   ] Monitoring remote device: KH_28_FFA45D811604
[18.4.2020, 20:53:58] [MAIN   ] Monitoring remote device: LI_28_A3D984001605
[18.4.2020, 20:53:58] [MAIN   ] Monitoring remote device: Luefter
[18.4.2020, 20:53:58] [MAIN   ] Monitoring remote device: NAFT.002
[18.4.2020, 20:53:58] [MAIN   ] Monitoring remote device: NAVOC.002
[18.4.2020, 20:53:58] [MAIN   ] Monitoring remote device: TA_28_736020050000
[18.4.2020, 20:53:58] [MAIN   ] Monitoring remote device: TA_28_FF313C4E0400
[18.4.2020, 20:53:58] [MAIN   ] Monitoring remote device: UKW_12V
[18.4.2020, 20:53:58] [MAIN   ] Monitoring remote device: UKW_ON
[18.4.2020, 20:54:04] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:54:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:54:34] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:54:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:55:04] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:55:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:55:34] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:55:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:56:04] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:56:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:56:34] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:57:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:57:04] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:57:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:57:34] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:58:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:58:04] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:58:23] [MAIN   ] Monitoring remote device: BMP180
[18.4.2020, 20:58:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:58:34] [SLAVE2 ] longpoll end: retry in: 30000msec
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: FA_26_A2D984000007
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: FIRMATA
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: FI_26_A3D984001605
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: HI_28_A2D984001677
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: KH_28_FF5A50811605
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: KH_28_FF715C811603
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: KH_28_FF8E8C811603
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: KH_28_FF976C811605
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: KH_28_FFA45D811604
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: LI_28_A3D984001605
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: Luefter
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: NAFT.002
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: NAVOC.002
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: TA_28_736020050000
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: TA_28_FF313C4E0400
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: UKW_12V
[18.4.2020, 20:58:59] [MAIN   ] Monitoring remote device: UKW_ON
[18.4.2020, 20:59:04] [SLAVE1 ] longpoll end: retry in: 30000msec
....
jump to the top

Wird das Device jedes mal gelöscht und neu angelegt? Ich meine das war bisher nicht so.
Hab allerdings nur noch die letzten drei Tage als Log aufgehoben. Daher weis ich nicht ob das vorher auch schon war

Zwischen ~5:xx - ~15:xx gab es heute wieder irgend ein Problem.
In der Zeit wurde nichts syncronisiert.
Der Slave lief aber ohne Probleme.
Das Log von heute im Anhang als ZIP. Verbose ist allerdings 0.....

Gruss Gerd

Edit: ZIP gelöscht

dominik · 18 April 2020, 21:35:16

Hi Gerd,

danke dir fuer die Rueckmeldung!

Den "uninitialized" Fehler sollte ich nun in Version 0.9.9 behoben haben (1. Post). Die vielen userAttr muss ich leider so machen, da es in FHEM keine andere Moeglichkeit gibt die Attribute nachtraeglich einem Device hinzuzufuegen. Die Liste enthaelt alle Attribute die nicht im Standard vorhanden sind.

Die longpoll Errors und die "Failed to fetch devices" Fehler im Log bei dir sind sehr eigenartig. Ich hatte heute 6h den 2. Pi ausgeschalten und da hatte ich die "Failed to fetch devices" Fehlermeldung. Die longpoll Errors deuten manchmal darauf hin, dass eventuell FHEM fuer ein paar Sekunden blockiert ist und daher der longpoll endet. Kann das der Fall sein? Das "Failed to fetch devices" im Log kann nur vorkommen wenn FHEM nicht erreichbar ist. Ich habe das Log gerade erweitert, damit der Fehler im Log erkenntlich ist. Bitte auf 2.7.3 fhemsync aktualisieren. Wenn das wieder passiert, wissen wir beim naechsten Mal mehr

Maista · 18 April 2020, 22:34:22

Hay Dominik,

wie schon mal erwähnt ist der Slave1 der RPi welcher mir 1Wire Sensoren abfragt.
Dieser läuft noch nicht Asyncron in der Bus-Abfrage. In der Zeit bleibt das System immer stehen bis alles fertig ist.
Ich hatte noch keine Lust das erneut zu probieren.

Also bei mir ist das schon "normal". Nach dem abfragen der 1Wire läuft das System ja weiter.
Auf dem Slave werden die Daten ins Log geschrieben.
Ich sehe im Log von FHEM gerade das ich um die 15Uhr ein Update mit Shutdown gemacht habe.
Deswegen ist das FHEMSync vermutlich dann wieder angelaufen.

Okay , ich mach dann ein Update von FHEMSync. Ich musste heute mein zigbee2mqtt.io updaten da scheinbar durch die Installation von FHEMSync etwas zu neu war für das ZigBee2mqtt

Läuft aber wieder.

Ich melde mich wieder

Maista · 20 April 2020, 20:19:36

Moin Dominik,

früher als erwartet melde ich mich schon wieder

Code Auswählen

[20.4.2020, 06:26:43] [MAIN   ] Monitoring remote device: BMP180
[20.4.2020, 06:27:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:27:08] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:27:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:27:38] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:28:05] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:28:08] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:28:35] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:28:38] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:28:57] [MAIN   ] Monitoring remote device: FA_26_A2D984000007
[20.4.2020, 06:28:57] [MAIN   ] Monitoring remote device: FIRMATA
[20.4.2020, 06:28:58] [MAIN   ] Monitoring remote device: FI_26_A3D984001605
[20.4.2020, 06:28:58] [MAIN   ] Monitoring remote device: HI_28_A2D984001677
[20.4.2020, 06:28:58] [MAIN   ] Monitoring remote device: KH_28_FF5A50811605
[20.4.2020, 06:28:59] [MAIN   ] Monitoring remote device: KH_28_FF715C811603
[20.4.2020, 06:28:59] [MAIN   ] Monitoring remote device: KH_28_FF8E8C811603
[20.4.2020, 06:28:59] [MAIN   ] Monitoring remote device: KH_28_FF976C811605
[20.4.2020, 06:28:59] [MAIN   ] Monitoring remote device: KH_28_FFA45D811604
[20.4.2020, 06:29:00] [MAIN   ] Monitoring remote device: LI_28_A3D984001605
[20.4.2020, 06:29:00] [MAIN   ] Monitoring remote device: Luefter
[20.4.2020, 06:29:00] [MAIN   ] Monitoring remote device: NAFT.002
[20.4.2020, 06:29:01] [MAIN   ] Monitoring remote device: NAVOC.002
[20.4.2020, 06:29:01] [MAIN   ] Monitoring remote device: TA_28_736020050000
[20.4.2020, 06:29:01] [MAIN   ] Monitoring remote device: TA_28_FF313C4E0400
[20.4.2020, 06:29:02] [MAIN   ] Monitoring remote device: UKW_12V
[20.4.2020, 06:29:02] [MAIN   ] Monitoring remote device: UKW_ON
[20.4.2020, 06:29:10] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:29:10] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:29:40] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:29:40] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:30:10] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:30:10] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:30:40] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:30:40] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:31:10] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:31:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:31:40] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:31:44] [MAIN   ] Monitoring remote device: BMP180
[20.4.2020, 06:32:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:32:11] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:32:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:32:41] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:33:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:33:11] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:33:35] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:33:41] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:34:04] [MAIN   ] Monitoring remote device: FA_26_A2D984000007
[20.4.2020, 06:34:04] [MAIN   ] Monitoring remote device: FIRMATA
[20.4.2020, 06:34:04] [MAIN   ] Monitoring remote device: FI_26_A3D984001605
[20.4.2020, 06:34:05] [MAIN   ] Monitoring remote device: HI_28_A2D984001677
[20.4.2020, 06:34:05] [MAIN   ] Monitoring remote device: KH_28_FF5A50811605
[20.4.2020, 06:34:05] [MAIN   ] Monitoring remote device: KH_28_FF715C811603
[20.4.2020, 06:34:06] [MAIN   ] Monitoring remote device: KH_28_FF8E8C811603
[20.4.2020, 06:34:06] [MAIN   ] Monitoring remote device: KH_28_FF976C811605
[20.4.2020, 06:34:06] [MAIN   ] Monitoring remote device: KH_28_FFA45D811604
[20.4.2020, 06:34:06] [MAIN   ] Monitoring remote device: LI_28_A3D984001605
[20.4.2020, 06:34:07] [MAIN   ] Monitoring remote device: Luefter
[20.4.2020, 06:34:07] [MAIN   ] Monitoring remote device: NAFT.002
[20.4.2020, 06:34:07] [MAIN   ] Monitoring remote device: NAVOC.002
[20.4.2020, 06:34:08] [MAIN   ] Monitoring remote device: TA_28_736020050000
[20.4.2020, 06:34:08] [MAIN   ] Monitoring remote device: TA_28_FF313C4E0400
[20.4.2020, 06:34:08] [MAIN   ] Monitoring remote device: UKW_12V
[20.4.2020, 06:34:09] [MAIN   ] Monitoring remote device: UKW_ON
[20.4.2020, 06:34:09] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:34:16] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:34:39] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:34:46] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:35:09] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:35:16] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:35:39] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:35:46] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:36:16] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:36:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:36:45] [MASTER ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:36:45] [SLAVE2 ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:36:46] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:37:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:37:16] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:37:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:37:46] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:38:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:38:16] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:38:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:38:47] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:39:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:39:09] [MASTER ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:39:10] [SLAVE1 ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:39:17] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:39:35] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:39:47] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:40:05] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:40:17] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:40:35] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:40:47] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:41:17] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:41:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:41:46] [MASTER ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:41:46] [SLAVE2 ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:41:47] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:42:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:42:17] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:42:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:42:47] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:43:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:43:17] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:43:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:43:47] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:44:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:44:10] [MASTER ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:44:11] [SLAVE1 ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:44:17] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:44:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:44:47] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:45:05] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:45:17] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:45:35] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:45:47] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:46:17] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:46:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:46:47] [MASTER ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:46:47] [SLAVE2 ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:46:47] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:47:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:47:18] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:47:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:47:48] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:48:04] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:48:18] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:48:34] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:48:48] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:49:05] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:49:11] [MASTER ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:49:12] [SLAVE1 ] Failed to fetch devices: RangeError: Maximum call stack size exceeded
[20.4.2020, 06:49:18] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:49:35] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:49:48] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 06:50:05] [SLAVE1 ] longpoll end: retry in: 30000msec
....

Laeuft mein Speicher voll?
Laut Speicher-Nutzung "RAM-Nutzung Total: 923.23, Min: 289.98, Max: 579.86, Aktuell: 579.86"

nach einem set fhemsync restart

Code Auswählen

....
[20.4.2020, 20:14:36] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 20:14:36] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 20:15:06] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 20:15:06] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 20:15:37] [SLAVE1 ] longpoll end: retry in: 30000msec
[20.4.2020, 20:15:37] [SLAVE2 ] longpoll end: retry in: 30000msec
[20.4.2020, 20:16:12] [MAIN   ] Starting FHEMSync version 2.7.3...
[20.4.2020, 20:16:12] [MAIN   ] Options: {"version":"2.7.3","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[20.4.2020, 20:16:35] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:35] [MAIN   ] Monitoring remote device: BMP180
[20.4.2020, 20:16:35] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:35] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:35] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:35] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:36] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:36] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:36] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:36] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:37] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:37] [SLAVE1 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:37] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:37] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:37] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:37] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:38] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:38] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:38] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:38] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:38] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:39] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:39] [SLAVE1 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:39] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: FA_26_A2D984000007
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: FIRMATA
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: FI_26_A3D984001605
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: HI_28_A2D984001677
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: KH_28_FF5A50811605
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: KH_28_FF715C811603
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: KH_28_FF8E8C811603
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: KH_28_FF976C811605
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: KH_28_FFA45D811604
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: LI_28_A3D984001605
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: Luefter
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: NAFT.002
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: NAVOC.002
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: TA_28_736020050000
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: TA_28_FF313C4E0400
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: UKW_12V
[20.4.2020, 20:16:39] [MAIN   ] Monitoring remote device: UKW_ON
[20.4.2020, 20:16:39] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:39] [SLAVE2 ] longpoll end: retry in: 200msec
[20.4.2020, 20:16:40] [SLAVE2 ] longpoll end: retry in: 200msec

Nun kommen wieder Daten. Im Event-Monitor hatte ich zuvor nichts mehr gesehen.

Gruss Gerd

dominik · 20 April 2020, 20:29:44

Danke dir fuer das Log, das hilft mir schon viel weiter! Da muss ich wohl irgendwo im Code einen Loop drin haben der den Speicher voll laufen laesst. Werde mir das genauer anschauen.

Maista · 20 April 2020, 22:23:01

Hay Dominik,

ich hab erst seit kurzem das Loggen des RAM aktiviert.
Weil mein PI vermutlich das blöde Problem mit altem Perl und Speicher voll laufen hat.

Im Anhang sieht man das auch das nach dem set fhemsync restart der Speicher wieder mehr Platz hat.

Viel Spass beim suchen. So wie ich das gelesen und verstanden habe gibt Perl den Speicher ja nicht mehr unbedingt her den es sich ein mal genehmigt hat.
Zumindest nicht bei altem Perl.

Danke und bis dann.

Gerd

Maista · 28 April 2020, 17:35:02

Hallo Dominik.
Was macht die Kunst? Speicher Problem gefunden?
Sobald der Speicher Verbrauch ein gewissen Wert erreicht hat hört FHEMSync auf zu arbeiten.
Wenn ich ein "Set FHEMSync restart " durchführe läuft's bis zum nächsten Anstieg.

Gruß Gerd

dominik · 28 April 2020, 20:29:29

Hi,
noch nicht, ich hatte bei mir gestern auch gesehen, dass fhemsync neu gestartet wurde, weil es zu viel Speicher konsumiert hat. Ich werde mich noch die Woche dran setzen.

dominik · 08 Mai 2020, 21:07:15

Hi,
möchte nur bescheid geben, dass ich noch dran bin an der Fehlersuche. Ich kann den Crash täglich bei mir reproduzieren und bin nun auf Detailsuche wo der Memory Leak liegt.

Maista · 08 Mai 2020, 21:50:34

Moin Dominik,

das ist schön ;

Wie kann das sein das FHEM weiter läuft nur FHEMSync nicht weiter arbeitet?
Wenn ich restart von FHEMSync mache gehts dann ja weiter.

Ist das auch eins dieser leidigen Speicher-Probleme von alten Perl-Versionen?

Gruss Gerd

dominik · 09 Mai 2020, 00:13:11

So...endlich gefunden und behoben. Update fhemsync 2.8.0, damit sollte es nun keinen Crash mehr geben.

Maista · 09 Mai 2020, 00:29:58

Merci,
gerade installiert.
Schau mehr mal was der Tag bringt ;

Gn8 Gerd

Maista · 09 Mai 2020, 00:33:27

Das war leider nix ?!

Startet und Stoppt wieder!

Code Auswählen

[9.5.2020, 00:29:39] [MAIN   ] Starting FHEMSync version 2.8.0...
[9.5.2020, 00:29:39] [MAIN   ] Options: {"version":"2.8.0","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[9.5.2020, 00:29:41] [SLAVE1 ] longpoll end: retry in: 200msec
/usr/lib/node_modules/fhemsync/fhemsync.js:257
    setTimeout(this.startLongpoll().bind(this), timeout);
                    ^

TypeError: this.startLongpoll is not a function
    at Request.<anonymous> (/usr/lib/node_modules/fhemsync/fhemsync.js:257:21)
    at Request.emit (events.js:327:22)
    at IncomingMessage.<anonymous> (/usr/lib/node_modules/fhemsync/node_modules/request/request.js:1076:12)
    at Object.onceWrapper (events.js:421:28)
    at IncomingMessage.emit (events.js:327:22)
    at endReadableNT (_stream_readable.js:1201:12)
    at processTicksAndRejections (internal/process/task_queues.js:84:21)
[9.5.2020, 00:30:04] [MAIN   ] Starting FHEMSync version 2.8.0...
[9.5.2020, 00:30:04] [MAIN   ] Options: {"version":"2.8.0","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[9.5.2020, 00:30:12] [SLAVE1 ] longpoll end: retry in: 200msec
/usr/lib/node_modules/fhemsync/fhemsync.js:257
    setTimeout(this.startLongpoll().bind(this), timeout);
                    ^

TypeError: this.startLongpoll is not a function
    at Request.<anonymous> (/usr/lib/node_modules/fhemsync/fhemsync.js:257:21)
    at Request.emit (events.js:327:22)
    at IncomingMessage.<anonymous> (/usr/lib/node_modules/fhemsync/node_modules/request/request.js:1076:12)
    at Object.onceWrapper (events.js:421:28)
    at IncomingMessage.emit (events.js:327:22)
    at endReadableNT (_stream_readable.js:1201:12)
    at processTicksAndRejections (internal/process/task_queues.js:84:21)
[9.5.2020, 00:30:34] [MAIN   ] Starting FHEMSync version 2.8.0...
[9.5.2020, 00:30:34] [MAIN   ] Options: {"version":"2.8.0","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[9.5.2020, 00:30:37] [SLAVE2 ] longpoll end: retry in: 200msec
/usr/lib/node_modules/fhemsync/fhemsync.js:257
    setTimeout(this.startLongpoll().bind(this), timeout);
                    ^

TypeError: this.startLongpoll is not a function
    at Request.<anonymous> (/usr/lib/node_modules/fhemsync/fhemsync.js:257:21)
    at Request.emit (events.js:327:22)
    at IncomingMessage.<anonymous> (/usr/lib/node_modules/fhemsync/node_modules/request/request.js:1076:12)
    at Object.onceWrapper (events.js:421:28)
    at IncomingMessage.emit (events.js:327:22)
    at endReadableNT (_stream_readable.js:1201:12)
    at processTicksAndRejections (internal/process/task_queues.js:84:21)
[9.5.2020, 00:30:58] [MAIN   ] Starting FHEMSync version 2.8.0...
[9.5.2020, 00:30:58] [MAIN   ] Options: {"version":"2.8.0","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[9.5.2020, 00:30:59] [SLAVE1 ] longpoll end: retry in: 200msec
/usr/lib/node_modules/fhemsync/fhemsync.js:257
    setTimeout(this.startLongpoll().bind(this), timeout);
                    ^

TypeError: this.startLongpoll is not a function
    at Request.<anonymous> (/usr/lib/node_modules/fhemsync/fhemsync.js:257:21)
    at Request.emit (events.js:327:22)
    at IncomingMessage.<anonymous> (/usr/lib/node_modules/fhemsync/node_modules/request/request.js:1076:12)
    at Object.onceWrapper (events.js:421:28)
    at IncomingMessage.emit (events.js:327:22)
    at endReadableNT (_stream_readable.js:1201:12)
    at processTicksAndRejections (internal/process/task_queues.js:84:21)
[9.5.2020, 00:31:20] [MAIN   ] Starting FHEMSync version 2.8.0...
[9.5.2020, 00:31:20] [MAIN   ] Options: {"version":"2.8.0","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[9.5.2020, 00:31:21] [SLAVE1 ] longpoll end: retry in: 200msec
/usr/lib/node_modules/fhemsync/fhemsync.js:257
    setTimeout(this.startLongpoll().bind(this), timeout);
                    ^

TypeError: this.startLongpoll is not a function
    at Request.<anonymous> (/usr/lib/node_modules/fhemsync/fhemsync.js:257:21)
    at Request.emit (events.js:327:22)
    at IncomingMessage.<anonymous> (/usr/lib/node_modules/fhemsync/node_modules/request/request.js:1076:12)
    at Object.onceWrapper (events.js:421:28)
    at IncomingMessage.emit (events.js:327:22)
    at endReadableNT (_stream_readable.js:1201:12)
    at processTicksAndRejections (internal/process/task_queues.js:84:21)
[9.5.2020, 00:31:42] [MAIN   ] Starting FHEMSync version 2.8.0...
[9.5.2020, 00:31:42] [MAIN   ] Options: {"version":"2.8.0","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[9.5.2020, 00:31:43] [SLAVE1 ] longpoll end: retry in: 200msec
/usr/lib/node_modules/fhemsync/fhemsync.js:257
    setTimeout(this.startLongpoll().bind(this), timeout);
                    ^

TypeError: this.startLongpoll is not a function
    at Request.<anonymous> (/usr/lib/node_modules/fhemsync/fhemsync.js:257:21)
    at Request.emit (events.js:327:22)
    at IncomingMessage.<anonymous> (/usr/lib/node_modules/fhemsync/node_modules/request/request.js:1076:12)
    at Object.onceWrapper (events.js:421:28)
    at IncomingMessage.emit (events.js:327:22)
    at endReadableNT (_stream_readable.js:1201:12)
    at processTicksAndRejections (internal/process/task_queues.js:84:21)
[9.5.2020, 00:31:53] [MAIN   ] Starting FHEMSync version 2.8.0...
[9.5.2020, 00:31:53] [MAIN   ] Options: {"version":"2.8.0","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[9.5.2020, 00:31:54] [SLAVE1 ] longpoll end: retry in: 200msec
/usr/lib/node_modules/fhemsync/fhemsync.js:257
    setTimeout(this.startLongpoll().bind(this), timeout);
                    ^

TypeError: this.startLongpoll is not a function
    at Request.<anonymous> (/usr/lib/node_modules/fhemsync/fhemsync.js:257:21)
    at Request.emit (events.js:327:22)
    at IncomingMessage.<anonymous> (/usr/lib/node_modules/fhemsync/node_modules/request/request.js:1076:12)
    at Object.onceWrapper (events.js:421:28)
    at IncomingMessage.emit (events.js:327:22)
    at endReadableNT (_stream_readable.js:1201:12)
    at processTicksAndRejections (internal/process/task_queues.js:84:21)
[9.5.2020, 00:32:06] [MAIN   ] Starting FHEMSync version 2.8.0...
[9.5.2020, 00:32:06] [MAIN   ] Options: {"version":"2.8.0","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[9.5.2020, 00:32:06] [SLAVE1 ] longpoll end: retry in: 200msec
/usr/lib/node_modules/fhemsync/fhemsync.js:257
    setTimeout(this.startLongpoll().bind(this), timeout);
                    ^

TypeError: this.startLongpoll is not a function
    at Request.<anonymous> (/usr/lib/node_modules/fhemsync/fhemsync.js:257:21)
    at Request.emit (events.js:327:22)
    at IncomingMessage.<anonymous> (/usr/lib/node_modules/fhemsync/node_modules/request/request.js:1076:12)
    at Object.onceWrapper (events.js:421:28)
    at IncomingMessage.emit (events.js:327:22)
    at endReadableNT (_stream_readable.js:1201:12)
    at processTicksAndRejections (internal/process/task_queues.js:84:21)
[9.5.2020, 00:32:15] [MAIN   ] Starting FHEMSync version 2.8.0...
[9.5.2020, 00:32:15] [MAIN   ] Options: {"version":"2.8.0","fhem":true,"port":true,"webname":true,"auth":true,"device":true,"selfSignedCert":true}
[9.5.2020, 00:32:15] [SLAVE1 ] longpoll end: retry in: 200msec
/usr/lib/node_modules/fhemsync/fhemsync.js:257
    setTimeout(this.startLongpoll().bind(this), timeout);
                    ^

TypeError: this.startLongpoll is not a function
    at Request.<anonymous> (/usr/lib/node_modules/fhemsync/fhemsync.js:257:21)
    at Request.emit (events.js:327:22)
    at IncomingMessage.<anonymous> (/usr/lib/node_modules/fhemsync/node_modules/request/request.js:1076:12)
    at Object.onceWrapper (events.js:421:28)
    at IncomingMessage.emit (events.js:327:22)
    at endReadableNT (_stream_readable.js:1201:12)
    at processTicksAndRejections (internal/process/task_queues.js:84:21)

jump to the top

Zitat2020-05-09 00:32:58 FHEMSYNC fhemsync fhemsync: running /usr/bin/fhemsync
2020-05-09 00:33:00 FHEMSYNC fhemsync fhemsync: stopped
2020-05-09 00:33:20 FHEMSYNC fhemsync fhemsync: running /usr/bin/fhemsync
2020-05-09 00:33:22 FHEMSYNC fhemsync fhemsync: stopped
2

Maista · 09 Mai 2020, 00:37:43

Die Module haben sich ja nicht geändert ?!

dominik · 09 Mai 2020, 08:19:28

Update 2.8.1. Module sind gleich geblieben.

Ich glaub du hattest schon mal gesagt, dass bei dir Remote FHEM manchmal 1 Minute hängt, oder? Weil das longpoll ended ist eher der Fehlerfall wenn das kommt.