NEWS
Beta Test js-controller 3.0.x auf GitHub
-
@apollon77 said in Beta Test js-controller 3.0.x auf GitHub:
@e-i-k-e Welche js-controller Version ist im Einsatz? Laut Log kam das vor als es noch die 2.2.9 war. Dann ab 17:47:49.463 war es der 3.0.8er controller und da gabs das nimmer. oder sehe ich was falsch?
@apollon77
Gut möglich, jetzt läuft die 3.0.8er.
Anbei ein Auszug aus dem log, nachdem ich versucht habe diesen neu zu starten..Slave:
2020-04-15 11:26:18.431 - [32minfo[39m: host.raspberrypi-display1 stopInstance system.adapter.javascript.2 (force=false, process=true) 2020-04-15 11:26:18.436 - [32minfo[39m: host.raspberrypi-display1 stopInstance system.adapter.javascript.2 send kill signal 2020-04-15 11:26:19.440 - [32minfo[39m: host.raspberrypi-display1 stopInstance system.adapter.javascript.2 killing pid 27154 2020-04-15 11:26:21.611 - [32minfo[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 started with pid 7635 2020-04-15 11:26:27.195 - [31merror[39m: javascript.2 (7635) javascript.2 already running 2020-04-15 11:26:27.201 - [33mwarn[39m: javascript.2 (7635) Terminated (ADAPTER_ALREADY_RUNNING): Without reason 2020-04-15 11:26:28.455 - [31merror[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 terminated with code 7 (ADAPTER_ALREADY_RUNNING) 2020-04-15 11:26:28.456 - [32minfo[39m: host.raspberrypi-display1 Restart adapter system.adapter.javascript.2 because enabled 2020-04-15 11:26:58.472 - [32minfo[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 started with pid 7696 2020-04-15 11:27:02.372 - [32minfo[39m: javascript.2 (27154) Stop script script.js.Raspberry.Bildschirm_über_BWM_Flur_unten 2020-04-15 11:27:03.439 - [31merror[39m: javascript.2 (7696) javascript.2 already running 2020-04-15 11:27:03.446 - [33mwarn[39m: javascript.2 (7696) Terminated (ADAPTER_ALREADY_RUNNING): Without reason 2020-04-15 11:27:04.718 - [31merror[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 terminated with code 7 (ADAPTER_ALREADY_RUNNING) 2020-04-15 11:27:04.719 - [32minfo[39m: host.raspberrypi-display1 Restart adapter system.adapter.javascript.2 because enabled 2020-04-15 11:27:34.735 - [32minfo[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 started with pid 7769 2020-04-15 11:27:39.805 - [32minfo[39m: javascript.2 (7769) starting. Version 4.4.3 in /opt/iobroker/node_modules/iobroker.javascript, node: v10.20.0, js-controller: 3.0.8 2020-04-15 11:27:40.039 - [32minfo[39m: javascript.2 (7769) requesting all states 2020-04-15 11:27:40.044 - [32minfo[39m: javascript.2 (7769) requesting all objects 2020-04-15 11:27:40.045 - [33mwarn[39m: javascript.2 (7769) adapter.objects.getObjectList is deprecated, and will be removed in the future. Please use adapter.getObjectList/Async. Report this to Developer! 2020-04-15 11:27:43.854 - [32minfo[39m: javascript.2 (7769) received all objects 2020-04-15 11:27:46.488 - [32minfo[39m: javascript.2 (7769) received all states 2020-04-15 11:27:47.519 - [32minfo[39m: javascript.2 (7769) Start javascript script.js.Raspberry.Bildschirm_über_BWM_Flur_unten 2020-04-15 11:27:47.690 - [32minfo[39m: javascript.2 (7769) script.js.Raspberry.Bildschirm_über_BWM_Flur_unten: registered 3 subscriptions and 0 schedules 2020-04-15 11:28:01.522 - [32minfo[39m: javascript.2 (27154) Got terminate signal TERMINATE_YOURSELF 2020-04-15 11:28:01.523 - [33mwarn[39m: javascript.2 (27154) Got terminate signal. Checking desired PID: 0 vs own PID 27154 2020-04-15 11:28:01.524 - [33mwarn[39m: javascript.2 (27154) Got terminate signal. Checking desired PID: 7635 vs own PID 27154 2020-04-15 11:28:01.525 - [33mwarn[39m: javascript.2 (27154) Got terminate signal. Checking desired PID: 0 vs own PID 27154 2020-04-15 11:28:01.526 - [33mwarn[39m: javascript.2 (27154) Got terminate signal. Checking desired PID: 7696 vs own PID 27154 2020-04-15 11:29:05.133 - [33mwarn[39m: javascript.2 (27154) Got terminate signal. Checking desired PID: 0 vs own PID 27154 2020-04-15 11:29:05.135 - [33mwarn[39m: javascript.2 (27154) Got terminate signal. Checking desired PID: 7769 vs own PID 27154 2020-04-15 11:29:05.153 - [32minfo[39m: javascript.2 (27154) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason 2020-04-15 11:30:07.086 - [32minfo[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 terminated with code 156 (START_IMMEDIATELY_AFTER_STOP) 2020-04-15 11:30:07.088 - [32minfo[39m: host.raspberrypi-display1 Restart adapter system.adapter.javascript.2 because enabled 2020-04-15 11:30:08.121 - [32minfo[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 started with pid 8043 2020-04-15 11:30:10.498 - [33mwarn[39m: javascript.2 (7769) Got terminate signal. Checking desired PID: 0 vs own PID 7769 2020-04-15 11:30:10.503 - [32minfo[39m: javascript.2 (7769) Stop script script.js.Raspberry.Bildschirm_über_BWM_Flur_unten 2020-04-15 11:30:10.506 - [33mwarn[39m: javascript.2 (7769) Got terminate signal. Checking desired PID: 8043 vs own PID 7769 2020-04-15 11:30:10.521 - [32minfo[39m: javascript.2 (7769) terminating 2020-04-15 11:30:10.522 - [32minfo[39m: javascript.2 (7769) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason 2020-04-15 11:30:13.072 - [31merror[39m: javascript.2 (8043) javascript.2 already running 2020-04-15 11:30:13.078 - [33mwarn[39m: javascript.2 (8043) Terminated (ADAPTER_ALREADY_RUNNING): Without reason 2020-04-15 11:30:14.213 - [31merror[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 terminated with code 7 (ADAPTER_ALREADY_RUNNING) 2020-04-15 11:30:14.214 - [32minfo[39m: host.raspberrypi-display1 Restart adapter system.adapter.javascript.2 because enabled 2020-04-15 11:30:44.227 - [32minfo[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 started with pid 8116 2020-04-15 11:30:49.289 - [32minfo[39m: javascript.2 (8116) starting. Version 4.4.3 in /opt/iobroker/node_modules/iobroker.javascript, node: v10.20.0, js-controller: 3.0.8 2020-04-15 11:30:49.447 - [32minfo[39m: javascript.2 (8116) requesting all states 2020-04-15 11:30:49.451 - [32minfo[39m: javascript.2 (8116) requesting all objects 2020-04-15 11:30:49.453 - [33mwarn[39m: javascript.2 (8116) adapter.objects.getObjectList is deprecated, and will be removed in the future. Please use adapter.getObjectList/Async. Report this to Developer! 2020-04-15 11:30:53.281 - [32minfo[39m: javascript.2 (8116) received all objects 2020-04-15 11:30:55.801 - [32minfo[39m: javascript.2 (8116) received all states 2020-04-15 11:30:56.851 - [32minfo[39m: javascript.2 (8116) Start javascript script.js.Raspberry.Bildschirm_über_BWM_Flur_unten 2020-04-15 11:30:57.000 - [32minfo[39m: javascript.2 (8116) script.js.Raspberry.Bildschirm_über_BWM_Flur_unten: registered 3 subscriptions and 0 schedules 2020-04-15 11:31:04.118 - [31merror[39m: host.raspberrypi-display1 instance system.adapter.javascript.2 terminated by request of the instance itself and will not be restarted, before user restarts it. 2020-04-15 11:31:04.119 - [32minfo[39m: host.raspberrypi-display1 Do not restart adapter system.adapter.javascript.2 because desired by instance
Master:
javascript.2 2020-04-15 11:30:57.000 info (8116) script.js.Raspberry.Bildschirm_über_BWM_Flur_unten: registered 3 subscriptions and 0 schedules javascript.2 2020-04-15 11:30:56.851 info (8116) Start javascript script.js.Raspberry.Bildschirm_über_BWM_Flur_unten javascript.2 2020-04-15 11:30:55.801 info (8116) received all states javascript.2 2020-04-15 11:30:53.281 info (8116) received all objects javascript.2 2020-04-15 11:30:49.453 warn (8116) adapter.objects.getObjectList is deprecated, and will be removed in the future. Please use adapter.getObjectList/Async. Report this to Developer! javascript.2 2020-04-15 11:30:49.451 info (8116) requesting all objects javascript.2 2020-04-15 11:30:49.447 info (8116) requesting all states javascript.2 2020-04-15 11:30:49.289 info (8116) starting. Version 4.4.3 in /opt/iobroker/node_modules/iobroker.javascript, node: v10.20.0, js-controller: 3.0.8 javascript.2 2020-04-15 11:30:13.078 warn (8043) Terminated (ADAPTER_ALREADY_RUNNING): Without reason javascript.2 2020-04-15 11:30:13.072 error (8043) javascript.2 already running javascript.2 2020-04-15 11:30:10.522 info (7769) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason javascript.2 2020-04-15 11:30:10.521 info (7769) terminating javascript.2 2020-04-15 11:30:10.506 warn (7769) Got terminate signal. Checking desired PID: 8043 vs own PID 7769 javascript.2 2020-04-15 11:30:10.503 info (7769) Stop script script.js.Raspberry.Bildschirm_über_BWM_Flur_unten javascript.2 2020-04-15 11:30:10.498 warn (7769) Got terminate signal. Checking desired PID: 0 vs own PID 7769 javascript.2 2020-04-15 11:29:05.153 info (27154) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason javascript.2 2020-04-15 11:29:05.135 warn (27154) Got terminate signal. Checking desired PID: 7769 vs own PID 27154 javascript.2 2020-04-15 11:29:05.133 warn (27154) Got terminate signal. Checking desired PID: 0 vs own PID 27154 javascript.2 2020-04-15 11:28:01.526 warn (27154) Got terminate signal. Checking desired PID: 7696 vs own PID 27154 javascript.2 2020-04-15 11:28:01.525 warn (27154) Got terminate signal. Checking desired PID: 0 vs own PID 27154 javascript.2 2020-04-15 11:28:01.524 warn (27154) Got terminate signal. Checking desired PID: 7635 vs own PID 27154 javascript.2 2020-04-15 11:28:01.523 warn (27154) Got terminate signal. Checking desired PID: 0 vs own PID 27154 javascript.2 2020-04-15 11:28:01.522 info (27154) Got terminate signal TERMINATE_YOURSELF javascript.2 2020-04-15 11:27:47.690 info (7769) script.js.Raspberry.Bildschirm_über_BWM_Flur_unten: registered 3 subscriptions and 0 schedules javascript.2 2020-04-15 11:27:47.519 info (7769) Start javascript script.js.Raspberry.Bildschirm_über_BWM_Flur_unten javascript.2 2020-04-15 11:27:46.488 info (7769) received all states javascript.2 2020-04-15 11:27:43.854 info (7769) received all objects javascript.2 2020-04-15 11:27:40.045 warn (7769) adapter.objects.getObjectList is deprecated, and will be removed in the future. Please use adapter.getObjectList/Async. Report this to Developer! javascript.2 2020-04-15 11:27:40.044 info (7769) requesting all objects javascript.2 2020-04-15 11:27:40.039 info (7769) requesting all states javascript.2 2020-04-15 11:27:39.805 info (7769) starting. Version 4.4.3 in /opt/iobroker/node_modules/iobroker.javascript, node: v10.20.0, js-controller: 3.0.8 javascript.2 2020-04-15 11:27:03.446 warn (7696) Terminated (ADAPTER_ALREADY_RUNNING): Without reason javascript.2 2020-04-15 11:27:03.439 error (7696) javascript.2 already running javascript.2 2020-04-15 11:27:02.372 info (27154) Stop script script.js.Raspberry.Bildschirm_über_BWM_Flur_unten javascript.2 2020-04-15 11:26:27.201 warn (7635) Terminated (ADAPTER_ALREADY_RUNNING): Without reason javascript.2 2020-04-15 11:26:27.195 error (7635) javascript.2 already running
-
@ilovegym Aah dann bist Du das bisher ist da immer der controller gecrasht. ENOMEM heisst das der Prozess nicht gestartet werden konnte weil kein RAM mehr frei ist ...
Schau Dir mal dein system genau an bzw reboote mal.
-
@e-i-k-e sagte in Beta Test js-controller 3.0.x auf GitHub:
Also bei mir sieht es so aus als ob die Instanz hier ein Problem beim stoppen hat. Hängt vllt irgendeins der Skripte oder so? In jedem Fall verhält sich das System laut Log korrekt.
Beim Slave sehe ich in dem Log Auszug:- 11:26:19.440 controller sagt prozess 27154 er solle sich beenden
- 11:26:19.440 adapter hat nicht reagiert, controller sendet dem prozess ein kill signal 27154
- 11:26:21.611 controller startet neuen prozess (7635)
- 11:27:02.372 prozess 27154 meldet das er das erste Skript gestoppt hat
- 11:27:03.439 prozess 7635 beendet sich weil noch ein anderer läuft
- 11:27:34.735 controller startet neuen prozess (7769) ... der startet weil der andere Prozess ewig sein alive nicht mehr aktualisiert hat
*11:28:01.522 prozess 27154 läuft immer noch und bekommt JETZT erst die Info über state changes vom 7635 start (1:40 Minuten später!!) und dnan alle - 11:29:05.153 ENDLICH beendet sich 27154 (2:46 Minuten nachdem er den Kill befehl erhalten hat)
Alles in Allem schau ich mal was ich optimieren kann, aber fakt ist das irgendein Skript deine Instanz mega blockiert und damit alles durcheinander bringt ... Finde das Skript und fixe es
Um das Thema zu ohne Skript Fix zu beheben musst Du schauen das du stop drückst, wartest bis der alte prozess wirklich weg ist und dann neu startest.
-
@ilovegym Versuch mal rauszufinden wann genau das kommt. Also welchen Visu adapter du aufrufen musst damit das im Log kommt. und dann bei dem ein "iobroker upload NAME" ausführen .
-
@Homer-J sagte in Beta Test js-controller 3.0.x auf GitHub:
läuft zwar ich kann a
Bitte Issue beim Adapter öffnen
-
@Homer-J sagte in Beta Test js-controller 3.0.x auf GitHub:
läuft zwar ich kann aber nichts schalten es reagiert nichts.
Log?
-
Hi @apollon77 Hab heut morgen die 3.0.9 Version installiert im Anschluss die Node.js auf die 12.. Version angehoben nochmal zur Sicherheit den Fixer drüber laufen lassen und seitdem läuft er.
-
Bei mir gibts zwar keine Fehler im Log und alle Adapter sind grün, aber einige Scripte haben gesponnen und der TR-064 findet keine Geräte mehr.
Bin gerade dabei ein Backup zurück zuspielen um zu testen, ob es tatsächlich am JS liegt, oder sich einfach das System was eingefangen hat. -
@Homer-J Und Yahka tut?
-
@Jan1 Hm ... ohne Details schwierig
-
@apollon77 läuft
-
@apollon77
Wenn keine Fehler im Log stehen ist es eben etwas blöd mehr zu sagen. Augenscheinlich lief ja alles, mich hatte heute gewundert, warum der Saugroboter nicht lief. Der fährt nur bei Abwesenheit und der DP wurde das letzte mal am WE geändert. Dann geschaut ob der TR-064 mein Handy im WLAN erkennt und das bliebt auf true, ebenfalls das Handy meiner Holden, die immer noch samt Handy auf Arbeit ist.
Das Backup ist drauf und der TR-064 läuft wieder. Nun kommt der JS 3.0.9 dazu, mal sehen ob s dann immer noch läuft -
@Jan1 tr-064 gabs aber ne gefixte version für den neuen controller ...
-
@apollon77
Ich geh mal davon aus, dass es genau an der lag, da ich die über Github geladen hatte. Ich nutze zwar keine Anrufliste, hatte den aber trotzdem drauf. Der kommt auch gleich wieder drauf -
@e-i-k-e Achja um hier zusehen ob du ein problem mit den Skripten hast gibts noch einen Weg. Bei den Objekten gibt es system.adapter.javascript.2.eventLoopLag ... schau die den mal an. Der sollte im Idealfall nur seeeehr klein sein (sind Milisekunden). Normal ist alles ich sag mal kleiner 30, was ist der Wert denn bei Dir so? Gff logge den mal per history oder so und dann schau.
Wenn der Wert große Werte hat dann blockiert irgendetwas die Abarbeitung und dann muss Du schauen
-
Hi,
ich habe mit die Probleme von e-i-k-e nochmal genauer angesehen. Aus dem bereits analysierten gibt es einerseits die 3.0.10 auf GitHub, die noch etwas verbessert generell
3.0.10 (2020-04-15) Release Elena
- (Apollon77) consider the Adapter Stop Timeout also for adapter restarts to give adapters enough time to stop before restarting
In dem obigen Fall wo der Adapter über 2 Minuten blockiert bevor er sich mal neu startet bringt der Fix nichts, dafür hab ich ein Issue angelegt. Das zu optimieren ist etwas aufwändiger und für den Fortschritt den wir mit der 3.0 schon haben "zu gross". Sehe ich aber für die 3.1 vor.
-
Ich habe gerade den js-controller 3.0.9 bei mir getestet. Alle Adapter starten problemlos bis auf den Robonect-Adapter. Dieser verweigert den Start mit folgender Fehlermeldung:
robonect.0 2020-04-15 15:48:22.309 error at processTicksAndRejections (internal/process/task_queues.js:97:5) robonect.0 2020-04-15 15:48:22.309 error at /opt/iobroker/node_modules/iobroker.robonect/lib/library.js:112:32 robonect.0 2020-04-15 15:48:22.309 error (31968) TypeError: self.pollGPs is not a function robonect.0 2020-04-15 15:48:22.308 error (31968) uncaught exception: self.pollGPs is not a function robonect.0 2020-04-15 15:47:49.046 error at processTicksAndRejections (internal/process/task_queues.js:97:5) robonect.0 2020-04-15 15:47:49.046 error at /opt/iobroker/node_modules/iobroker.robonect/lib/library.js:112:32 robonect.0 2020-04-15 15:47:49.046 error (31220) TypeError: self.pollGPs is not a function robonect.0 2020-04-15 15:47:49.045 error (31220) uncaught exception: self.pollGPs is not a function
Gruß Marco
-
@lonsimbt Der Fehler kommt irgendwo "aus dem Adapter". Wüsste nicht wie der controller da was mit zu tun haben könnte
-
@lonsimbt PS: EInzige as sein kann ist das der Fehler schon immer da war aber bisher "nur" eine unhandled Promise rejection war die nicht zum adapter-crash geführt hat ... mit 3.0er controller ist es das aber. Da muss der Entwickler ran
-
@apollon77 gerade installiert vom git, ist aber noch der 3.0.9