Test js-controller v2.0.x (GitHub)
-
Den ersten Härtetest hat das System heute schon hinter sich.
Stromausfall und die USV war abgeschaltet
weil ich den Batteriepack mit zur Arbeit hatte zum Austausch.
Nach 30 Minuten fährt das System wieder hoch als wäre nichts geschehen.
-
@smartboart mehr log bitte
-
Hallo nochmals ich,
ich habe mit das Reconnection Handling nochmal angesehen, weil es anders war wie gewollt.
Die 2.0.30 auf GitHub hat das umgebaut.Ich wäre hier nochmal froh wenn Ihr checken könntet. Vor allem interessant ist das Verhalten von Slaves wenn der Master weg ist bzw. das verhalten des Gesamtsystems bei Redis-Nutzung wenn Redis weg ist.
Mit der 2.0.30 wird bis zu ca. 40s versucht die Verbindung wieder herzustellen. Wenn das gelingt kommt das ganze ohne Restarts aus und auch aufgelaufene Datenänderungen (zB setState oder Infos zu State-Änderungen) werden nachgespielt.
Wenn es länger braucht beenden sich die Adapter wie bisher und alles wartet das die DB wieder da ist. -
Habe beide auf die 2.0.30 gezogen.
Beim installieren gab es ungewöhnlich viele Warnungen, die hatte ich vorher nicht - siehe Log. Beide laufen aber ohne Auffälligkeiten.
-
Zu deinen gewünschten Tests:
Testfall 1:
master + slave = alive
master beenden
nicht wieder startenTestfall 2:
master + slave = alive
master beenden
20s warten
wieder startenTestfall 3:
master + slave = alive
redis beenden
nicht wieder startenTestfall 4:
master + slave = alive
redis beenden
paar Sekunden warten
redis starten
prüfen ob States die innerhalb der 40s erzeugt werden nach dem starten von redis verfügbar sind -
Testfall 1:
iobroker-master um 10:26:02 beendet und nicht wieder gestartet, log auf slave. Nach ner gewissen Zeit beenden sich die Adapter und der controller lauert:
-
Testfall 2:
iobroker-master um 10:30:05 beendet und nach 20s wieder gestartet, log auf slave:
--> Kein Log das die Verbindung wieder besteht. 10:32:37 wurde der iobroker-master wieder gestartet und war ein paar Sekunden später auch wieder Verbunden (--> ersichtlich im Admin).
-
Testfall 3
iobroker-redis um 10:45:13 Uhr beendet. Log Master.
Einige Minuten später iobroker-redis wieder gestartet - der iobroker-master schafft es nicht wieder auf die Beine. Nach einem restart des Containers für den Master ist wieder alles verbunden. -
Testfall 4
┬─[darkiop@odin:~]─[11:00:32]
╰─>$ docker stop iobroker-redis┬─[darkiop@odin:~]─[11:02:15]
╰─>$ docker start iobroker-redissmartmeter.1 hat einen Wert von 2019-10-15 11:01:34.726 über sql.0 in die DB geschrieben!
-
@darkiop Alles unix dgram complier warnungen
Aber ja ist egal, tut alles -
Danke für die Tests!!
- Testfall1 : So soll es sein
- Testfall 2: Auch hier soll es so sein. Wenn Du auf "debug" oder "silly" log hättest würdest du die reconnects als Log sehen.
- Testfall 3 Bitte mit der neuen 2.0.31 auf Github nochmal testen
- Testfall 4; Cooool
-
@apollon77 sagte in [Aufruf] js-controller 2.0 Beta Test:
Danke für die Tests!!
Gern!
- Testfall 3 Bitte mit der neuen 2.0.31 auf Github nochmal testen
Da stimmt was nicht:
┬─[darkiop@odin:~]─[13:35:02] ╰─>$ docker stop iobroker-redis iobroker-redis ┬─[darkiop@odin:~]─[13:41:53] ╰─>$ docker start iobroker-redis iobroker-redisLog Master:
2019-10-15 13:41:48.364 - error: scenes.0 (1492) connect ETIMEDOUT 2019-10-15 13:41:48.366 - error: pi-hole.0 (1228) connect ETIMEDOUT 2019-10-15 13:41:48.368 - error: harmony.0 (388) connect ETIMEDOUT 2019-10-15 13:41:48.368 - error: simple-api.0 (1695) connect ETIMEDOUT 2019-10-15 13:41:48.372 - error: javascript.1 (1068) connect ETIMEDOUT 2019-10-15 13:41:48.372 - error: sql.0 (1819) connect ETIMEDOUT 2019-10-15 13:41:48.372 - error: telegram.0 (2364) connect ETIMEDOUT 2019-10-15 13:41:48.375 - error: host.iobroker-master connect ETIMEDOUT 2019-10-15 13:41:48.379 - error: hm-rega.0 (403) connect ETIMEDOUT 2019-10-15 13:41:48.379 - error: hs100.0 (480) connect ETIMEDOUT 2019-10-15 13:41:48.379 - error: tr-064-community.0 (2710) connect ETIMEDOUT 2019-10-15 13:41:48.385 - error: stiebel-isg.0 (2029) connect ETIMEDOUT 2019-10-15 13:41:48.385 - error: moma.0 (1162) connect ETIMEDOUT 2019-10-15 13:41:48.390 - error: ping.0 (1378) connect ETIMEDOUT 2019-10-15 13:41:48.391 - error: hm-rpc.1 (426) connect ETIMEDOUT 2019-10-15 13:41:48.392 - error: backitup.0 (457) connect ETIMEDOUT 2019-10-15 13:41:48.393 - error: hm-rpc.2 (450) connect ETIMEDOUT 2019-10-15 13:41:48.393 - error: javascript.0 (808) connect ETIMEDOUT 2019-10-15 13:41:48.399 - error: mercedesme.0 (1108) connect ETIMEDOUT 2019-10-15 13:41:48.407 - error: info.0 (789) connect ETIMEDOUT 2019-10-15 13:42:00.004 - warn: host.iobroker-master instance system.adapter.daswetter.0 already running with pid 11372 2019-10-15 13:42:03.215 - info: host.iobroker-master instance system.adapter.daswetter.0 terminated with code 0 (NO_ERROR) 2019-10-15 13:42:04.501 - debug: harmony.0 (388) system.adapter.admin.0: logging true 2019-10-15 13:42:04.532 - debug: harmony.0 (388) States connected to redis: 192.168.1.86:6379 2019-10-15 13:42:20.048 - error: admin.0 (419) uncaught exception: Maximum call stack size exceeded 2019-10-15 13:42:20.052 - error: admin.0 (419) RangeError: Maximum call stack size exceeded at Sender.frameAndSend (/opt/iobroker/node_modules/ws/lib/Sender.js:166:14) at /opt/iobroker/node_modules/ws/lib/Sender.js:126:12 at Sender.applyExtensions (/opt/iobroker/node_modules/ws/lib/Sender.js:287:5) at /opt/iobroker/node_modules/ws/lib/Sender.js:120:10 at Sender.flush (/opt/iobroker/node_modules/ws/lib/Sender.js:268:3) at /opt/iobroker/node_modules/ws/lib/Sender.js:270:10 at /opt/iobroker/node_modules/ws/lib/Sender.js:127:7 at Sender.applyExtensions (/opt/iobroker/node_modules/ws/lib/Sender.js:287:5) at /opt/iobroker/node_modules/ws/lib/Sender.js:120:10 at Sender.flush (/opt/iobroker/node_modules/ws/lib/Sender.js:268:3) 2019-10-15 13:42:20.318 - error: host.iobroker-master Caught by controller[0]: RangeError: Maximum call stack size exceeded 2019-10-15 13:42:20.320 - error: host.iobroker-master Caught by controller[0]: at Sender.frameAndSend (/opt/iobroker/node_modules/ws/lib/Sender.js:166:14) 2019-10-15 13:42:20.321 - error: host.iobroker-master Caught by controller[0]: at /opt/iobroker/node_modules/ws/lib/Sender.js:126:12 2019-10-15 13:42:20.323 - error: host.iobroker-master Caught by controller[0]: at Sender.applyExtensions (/opt/iobroker/node_modules/ws/lib/Sender.js:287:5) 2019-10-15 13:42:20.324 - error: host.iobroker-master Caught by controller[0]: at /opt/iobroker/node_modules/ws/lib/Sender.js:120:10 2019-10-15 13:42:20.325 - error: host.iobroker-master Caught by controller[0]: at Sender.flush (/opt/iobroker/node_modules/ws/lib/Sender.js:268:3) 2019-10-15 13:42:20.326 - error: host.iobroker-master Caught by controller[0]: at /opt/iobroker/node_modules/ws/lib/Sender.js:270:10 2019-10-15 13:42:20.327 - error: host.iobroker-master Caught by controller[0]: at /opt/iobroker/node_modules/ws/lib/Sender.js:127:7 2019-10-15 13:42:20.328 - error: host.iobroker-master Caught by controller[0]: at Sender.applyExtensions (/opt/iobroker/node_modules/ws/lib/Sender.js:287:5) 2019-10-15 13:42:20.328 - error: host.iobroker-master Caught by controller[0]: at /opt/iobroker/node_modules/ws/lib/Sender.js:120:10 2019-10-15 13:42:20.329 - error: host.iobroker-master Caught by controller[0]: at Sender.flush (/opt/iobroker/node_modules/ws/lib/Sender.js:268:3) 2019-10-15 13:42:20.331 - info: host.iobroker-master instance system.adapter.admin.0 terminated with code 0 (NO_ERROR) 2019-10-15 13:42:20.333 - info: host.iobroker-master Restart adapter system.adapter.admin.0 because enabled 2019-10-15 13:42:20.340 - debug: harmony.0 (388) system.adapter.admin.0: logging false 2019-10-15 13:42:49.810 - info: tankerkoenig.0 (2155) Reading data from tankerkoenig ... 2019-10-15 13:42:50.388 - info: host.iobroker-master instance system.adapter.admin.0 started with pid 27743 2019-10-15 13:43:00.021 - info: host.iobroker-master instance system.adapter.daswetter.0 started with pid 28216 2019-10-15 13:43:09.649 - info: host.iobroker-master instance system.adapter.daswetter.0 terminated with code 0 (NO_ERROR)Keine neue States da:
-
@darkiop in dem Fall ist Admin gestorben weil sich irgendwie Dinge aufgestaut haben und Admin restartet. Ggf fehlen dadurch Daten? Lad im browser mal neu. Jetzt fehlende States da?
Ansonsten hat sich der iobroker aber wieder gefangen mit der Version oder?!
-
@darkiop Aahhh noch eine Frage: du hast nur States im redis, korrekt?
-
Ok, zweiter Test - jetzt ohne den admin.0 crash ....
der Master ist wieder auf den Beinen, der Slave hat es aber nicht geschaft. stop/start aufm Slave und er ist wieder verbunden.
Puffert der controller auch Logs? Die 3 hier sind eigentlich entstanden während redis weg war, geschrieben wurden sie nach dem redis wieder da war:
2019-10-15 14:00:00.786 - info: javascript.0 (618) script.js.PRODUKTIV.Beleuchtung.01_Bewegungsmelder_Flur: +++ Bewegung im Flur. Helligkeitsschwelle bei < 10 | Aktuelle Helligkeit: 31.8 | Licht wird nicht geschaltet. +++ 2019-10-15 14:00:01.180 - info: javascript.0 (618) script.js.PRODUKTIV.Beleuchtung.01_Bewegungsmelder_Flur: +++ Bewegung im Flur. Helligkeitsschwelle bei < 10 | Aktuelle Helligkeit: 32.6 | Licht wird nicht geschaltet. +++ 2019-10-15 14:00:01.523 - info: javascript.0 (618) script.js.PRODUKTIV.Beleuchtung.01_Bewegungsmelder_Flur: +++ Bewegung im Flur. Helligkeitsschwelle bei < 10 | Aktuelle Helligkeit: 32.6 | Licht wird nicht geschaltet. +++Edit:
So lange war redis weg:
-
@apollon77 sagte in [Aufruf] js-controller 2.0 Beta Test:
Aahhh noch eine Frage: du hast nur States im redis, korrekt?
Ja.
-
-
Mir ist noch was aufgefallen. Hab für den fullybrowser nen Chron Job im Admin angelegt.
Alle 4 Stunden neustarten.... Der Log sieht dann so aus. Ist der einzige adapter mit nem Chron job bei mir..
-
@smartboart denke alles nur Warnungen.
-
@smartboart sicher das es 4 stündlich ist? Das siehe eher nach minütlich aus.
