Alpha Test js-controller Kiera (v6.0)

[Feuersturm]

@mcm1957 Ich versuche das Problem etwas weiter einzugrenzen. Ich habe 6 ICAL Instanzen für verschiedene Kalender laufen die ich verschieden behandel. Ein Debug Log von verschiedenen Kalendern ist nicht so einfach möglich, da dort ja die Produktivdaten in den Kalendern stecken. Ich habe auf meinem Produktivsystem und auf meinem Testsystem verschiedene Versionen im Einsatz, ich schau mal ob das auch noch einen Einfluss hat.

[mcm1957]

@Feuersturm
Kannst du mal in den system objects checken of eine Config dort sichtbar ist und via admin UI schaun ob die Config dort sichtbar ist?

[mcm1957]

@feuersturm

Ja klar.
Aber schau mal (für dich) mit DEBUG log ob da überhaupt Kalenderdaten kommen. Dein Logging von oben schaut so aus als würde der ical gar nicht starten :-). Und es wäre ein großer Unterschied ob er nicjht startet oder "nur" nicht normal arbeitet.

warn: ical.0 (7436) Terminated (NO_ADAPTER_CONFIG_FOUND): Without reason
kommt ja vom js-controller. Steht da vorher was vom ical drinnen?

[mcm1957]

@foxriver76

Noch ne Idee:
Der ical beendet sich mit adapter.stop();
Ab js-controller 6 kann die Funktion ja nun Parameter (Exist Status). Kann es sein, dass der Status 3 nur ein reiner Zufallswert ist weil der js-controller / adapter-code "kein Paramater" nicht richtig erkennen und zu einem definierten Wert mappen? Sprich - 'undefinierte Variable Problem'

[Feuersturm]

@mcm1957 @foxriver76
zum ical Thema.

Ich habe auf meiner anderen Produktivinstanz mit ical 1.15.0 auch einmal meinen ical.5 ioBroker Testkalender eingerichtet. Dort läuft js-controller 5.0.19. Auf dem System kann ich das Debug Loglevel von ical.5 einschalten und der Kalender wird mit zwei Testterminen korrekt ausgelesen. Alle anderen ical Instanzen 0 bis 4 sind nicht aktiv.

Auf meinem Testsystem mit js-controller 6 ist ical5 der gleiche ioBroker Testkalender.
Wenn ich dort versuche das Debug Loglevel von ical5 einzuschalten sehe ich folgendes im Log:

2024-05-22 21:50:36.753 - info: host.ioBrokerTestsystem instance scheduled system.adapter.ical.5 * * * * *
2024-05-22 21:50:36.797 - info: host.ioBrokerTestsystem instance system.adapter.ical.5 started with pid 10678
2024-05-22 21:50:42.079 - error: ical.0 (10678) adapter disabled
2024-05-22 21:50:42.143 - warn: ical.0 (10678) Terminated (NO_ADAPTER_CONFIG_FOUND): Without reason
2024-05-22 21:50:42.872 - error: host.ioBrokerTestsystem instance system.adapter.ical.5 terminated with code 3 (NO_ADAPTER_CONFIG_FOUND)
2024-05-22 21:51:00.146 - info: host.ioBrokerTestsystem instance system.adapter.ical.5 started with pid 10689
2024-05-22 21:51:04.861 - error: ical.0 (10689) ical.0 already running
2024-05-22 21:51:04.873 - warn: ical.0 (10689) Terminated (ADAPTER_ALREADY_RUNNING): Without reason
2024-05-22 21:51:05.465 - error: host.ioBrokerTestsystem instance system.adapter.ical.5 having pid 10689 terminated with code 7 (ADAPTER_ALREADY_RUNNING)
2024-05-22 21:51:54.169 - info: host.ioBrokerTestsystem stopInstance system.adapter.ical.5 (force=false, process=false)
2024-05-22 21:51:54.182 - info: host.ioBrokerTestsystem stopInstance canceled schedule system.adapter.ical.5
2024-05-22 21:51:58.028 - info: host.ioBrokerTestsystem instance scheduled system.adapter.ical.5 * * * * *
2024-05-22 21:51:58.083 - info: host.ioBrokerTestsystem instance system.adapter.ical.5 started with pid 10700
2024-05-22 21:52:00.039 - warn: host.ioBrokerTestsystem instance system.adapter.ical.5 already running with pid 10700
2024-05-22 21:52:03.062 - error: ical.0 (10700) adapter disabled
2024-05-22 21:52:03.132 - warn: ical.0 (10700) Terminated (NO_ADAPTER_CONFIG_FOUND): Without reason
2024-05-22 21:52:03.918 - error: host.ioBrokerTestsystem instance system.adapter.ical.5 terminated with code 3 (NO_ADAPTER_CONFIG_FOUND)

Es kommen keine weiteren Debugdaten vom ical5. Unter ical.5.data werden auch nicht die Daten aus dem Kalender eingetragen. ical5 startet aus meiner Sicht also gar nicht erst.

[mcm1957]

@feuersturm
Danke
Ich glaub da muss wirklich Foxriver mal ein Auge drauf werfen (ohne es zu beschädigen )
Already running kommt oft wenn ein scheduled adapter nicht sauber beendet wird. Aber warum !?!? k.A.

[mcm1957]

@feuersturm said in Alpha Test js-controller Kiera (v6.0):

Grad gesehen:

info: host.ioBrokerTestsystem instance scheduled system.adapter.ical.5 * * * * *

Hast du wirklich einen cron * * * * * eingestellt ?

Mit * * * * * schaff ich auch already running. Aber die NO_CONFIG Meldung schaff ich nicht.

[Feuersturm]

@mcm1957 sagte in Alpha Test js-controller Kiera (v6.0):

Hast du wirklich einen cron * * * * * eingestellt ?

Ja, das war jetzt zum testen.

[mcm1957]

@Feuersturm
@foxriver76

Soda - ich hab das Ganze jetzt mal reproduzierbar.

Mit EINER Instanz funktioniert alles.

Installiert man ZWEI ical Instanzen und startet beide ist auch alles in Ordnung.
Startet man nur Instanz 0 und läßt Instanz 1 gestoppt ist alles in Ordnung.

Startet man NUR instanz 1 und läßt Instanz 0 disabled, dann kommt der NO_CONFIG Fehler.

Sieht so aus aös würde irgendwo nicht auf die richtige Instanz sondern auf Instanz 0 zugegriffen.
Die Suche überlass ich aber dann mal dir Moritz Und ja, der Status NO_CONFIG bedeutet auch Instanz gestoppt lt. EXIT Code Source

Am ical selbst dürfte es nicht liegen. Entweder ist das bei allen mehrfachen Instanzen so oder es betrifft nur scheduled Adapter.

EDIT:

Im Log sioeht man, dass die Instanz nicht richtig weitergegeben wird. Ich starte hier Instanz 1 - die NO_CONFOIG MEldung schreibt aber eine Instanz 0 !!! Siehe Zeile 2 und 3.

host.Envy 2024-05-22 22:48:54.918	error	instance system.adapter.ical.1 terminated with code 3 (NO_ADAPTER_CONFIG_FOUND)
ical.0    2024-05-22 22:48:54.345	warn	Terminated (NO_ADAPTER_CONFIG_FOUND): Without reason
ical.0    2024-05-22 22:48:54.340	error	adapter disabled
host.Envy 2024-05-22 22:48:52.164	info	instance system.adapter.ical.1 started with pid 10676
host.Envy 2024-05-22 22:48:52.154	info	instance scheduled system.adapter.ical.1 0,4 0,30 * * * *

Der Fehler ist mit 2 daemon Instanzen ebenso gegeben (2x Zigbee). Er hat also nix mit scheduled zu tun.

[foxriver76]

@mcm1957 mach mal einen ical anders als Instanz 0 vielleicht liegt es daran

Edit ok bist ja schon dran

[mcm1957]

@foxriver76
Beim Start wird da was falsch übergeben. Schau dir das Log genau an. Da loggt eine INstanz 0 was obwohl eine INstanz 1 gestartet wurde. Und ja, 0 ist ja disabled

[Rene55]

@foxriver76 Upgrade durchgeführt. Läuft auf den ersten Blick bis auf Rest-API und Simple-API. Dier haben beide core_2.6.11.

[foxriver76]

@rene55 Was kommt da im Log mit 2.6.11? Sollte eigentlich ok sein

[Rene55]

@foxriver76

rest-api.0	2024-05-23 10:19:01.445	warn	Terminated (JS_CONTROLLER_STOPPED): Without reason
rest-api.0	2024-05-23 10:19:01.444	error	Cannot create webserver: TypeError: Cannot read properties of undefined (reading 'createServerAsync')
rest-api.0	2024-05-23 10:19:00.150	info	starting. Version 1.1.0 in /opt/iobroker/node_modules/iobroker.rest-api, node: v20.13.1, js-controller: 6.0.1-alpha.0-20240522-72e34be5c

[foxriver76]

@rene55 ok muss beides in den Adaptern gefixt werden

[foxriver76]

@feuersturm Kannst du nochmal updaten bitte, bzw. alle Linux Nutzer sollten nochmal updaten

[sigi234]

@foxriver76 sagte in Alpha Test js-controller Kiera (v6.0):

@feuersturm Kannst du nochmal updaten bitte, bzw. alle sollten nochmal updaten

Auch Win User?

[foxriver76]

@sigi234 Not yet, nein

[foxriver76]

@rene55 Rest API 2.0.1 ist auf dem Weg ins latest, damit sollte es gehen.

[Feuersturm]

@foxriver76 Update auf 6.0.1-alpha.0-20240523-1d3cb759c

ical Problem ist behoben. ical5 wird jetzt ausgelesen auch wenn die anderen icals (0-4) inaktiv sind

sind das hier Themen für die Adapter oder für den controller (hatte ich weiter oben auch schon in den logs, ist also nichts neues)

2024-05-23 12:02:27.141 - error: host.ioBrokerTestsystem Caught by controller[0]: /opt/iobroker/node_modules/iobroker.vis-google-fonts/node_modules/@iobroker/adapter-core/build/controllerTools.js:83
2024-05-23 12:02:27.145 - error: host.ioBrokerTestsystem Caught by controller[0]: throw new Error(`Cannot resolve JS-Controller module ${name}.js`);
2024-05-23 12:02:27.148 - error: host.ioBrokerTestsystem Caught by controller[0]: ^
2024-05-23 12:02:27.154 - error: host.ioBrokerTestsystem Caught by controller[0]: Error: Cannot resolve JS-Controller module zipFiles.js
2024-05-23 12:02:27.157 - error: host.ioBrokerTestsystem Caught by controller[0]: at resolveNamedModule (/opt/iobroker/node_modules/iobroker.vis-google-fonts/node_modules/@iobroker/adapter-core/build/controllerTools.js:83:11)
2024-05-23 12:02:27.160 - error: host.ioBrokerTestsystem Caught by controller[0]: at Object. (/opt/iobroker/node_modules/iobroker.vis-google-fonts/node_modules/@iobroker/adapter-core/build/controllerTools.js:128:15)
2024-05-23 12:02:27.163 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._compile (node:internal/modules/cjs/loader:1364:14)
2024-05-23 12:02:27.166 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._extensions..js (node:internal/modules/cjs/loader:1422:10)
2024-05-23 12:02:27.169 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module.load (node:internal/modules/cjs/loader:1203:32)
2024-05-23 12:02:27.172 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._load (node:internal/modules/cjs/loader:1019:12)
2024-05-23 12:02:27.175 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module.require (node:internal/modules/cjs/loader:1231:19)
2024-05-23 12:02:27.178 - error: host.ioBrokerTestsystem Caught by controller[0]: at require (node:internal/modules/helpers:177:18)
2024-05-23 12:02:27.181 - error: host.ioBrokerTestsystem Caught by controller[0]: at Object. (/opt/iobroker/node_modules/iobroker.vis-google-fonts/node_modules/@iobroker/adapter-core/build/index.js:19:27)
2024-05-23 12:02:27.187 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._compile (node:internal/modules/cjs/loader:1364:14)
2024-05-23 12:02:27.190 - error: host.ioBrokerTestsystem Caught by controller[0]: Node.js v18.20.3

2024-05-23 12:03:55.275 - error: host.ioBrokerTestsystem Caught by controller[0]: /opt/iobroker/node_modules/iobroker.device-manager/node_modules/@iobroker/adapter-core/build/controllerTools.js:83
2024-05-23 12:03:55.278 - error: host.ioBrokerTestsystem Caught by controller[0]: throw new Error(`Cannot resolve JS-Controller module ${name}.js`);
2024-05-23 12:03:55.281 - error: host.ioBrokerTestsystem Caught by controller[0]: ^
2024-05-23 12:03:55.284 - error: host.ioBrokerTestsystem Caught by controller[0]: Error: Cannot resolve JS-Controller module zipFiles.js
2024-05-23 12:03:55.287 - error: host.ioBrokerTestsystem Caught by controller[0]: at resolveNamedModule (/opt/iobroker/node_modules/iobroker.device-manager/node_modules/@iobroker/adapter-core/build/controllerTools.js:83:11)
2024-05-23 12:03:55.289 - error: host.ioBrokerTestsystem Caught by controller[0]: at Object. (/opt/iobroker/node_modules/iobroker.device-manager/node_modules/@iobroker/adapter-core/build/controllerTools.js:128:15)
2024-05-23 12:03:55.292 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._compile (node:internal/modules/cjs/loader:1364:14)
2024-05-23 12:03:55.294 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._extensions..js (node:internal/modules/cjs/loader:1422:10)
2024-05-23 12:03:55.297 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module.load (node:internal/modules/cjs/loader:1203:32)
2024-05-23 12:03:55.299 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._load (node:internal/modules/cjs/loader:1019:12)
2024-05-23 12:03:55.301 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module.require (node:internal/modules/cjs/loader:1231:19)
2024-05-23 12:03:55.304 - error: host.ioBrokerTestsystem Caught by controller[0]: at require (node:internal/modules/helpers:177:18)
2024-05-23 12:03:55.307 - error: host.ioBrokerTestsystem Caught by controller[0]: at Object. (/opt/iobroker/node_modules/iobroker.device-manager/node_modules/@iobroker/adapter-core/build/index.js:19:27)
2024-05-23 12:03:55.309 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._compile (node:internal/modules/cjs/loader:1364:14)
2024-05-23 12:03:55.311 - error: host.ioBrokerTestsystem Caught by controller[0]: Node.js v18.20.3
2024-05-23 12:03:55.314 - error: host.ioBrokerTestsystem instance system.adapter.device-manager.0 terminated with code 1 (JS_CONTROLLER_STOPPED)

2024-05-23 12:04:00.530 - error: host.ioBrokerTestsystem Caught by controller[0]: /opt/iobroker/node_modules/iobroker.vis-inventwo/node_modules/@iobroker/adapter-core/build/controllerTools.js:83
2024-05-23 12:04:00.533 - error: host.ioBrokerTestsystem Caught by controller[0]: throw new Error(`Cannot resolve JS-Controller module ${name}.js`);
2024-05-23 12:04:00.536 - error: host.ioBrokerTestsystem Caught by controller[0]: ^
2024-05-23 12:04:00.539 - error: host.ioBrokerTestsystem Caught by controller[0]: Error: Cannot resolve JS-Controller module zipFiles.js
2024-05-23 12:04:00.542 - error: host.ioBrokerTestsystem Caught by controller[0]: at resolveNamedModule (/opt/iobroker/node_modules/iobroker.vis-inventwo/node_modules/@iobroker/adapter-core/build/controllerTools.js:83:11)
2024-05-23 12:04:00.545 - error: host.ioBrokerTestsystem Caught by controller[0]: at Object. (/opt/iobroker/node_modules/iobroker.vis-inventwo/node_modules/@iobroker/adapter-core/build/controllerTools.js:128:15)
2024-05-23 12:04:00.549 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._compile (node:internal/modules/cjs/loader:1364:14)
2024-05-23 12:04:00.552 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._extensions..js (node:internal/modules/cjs/loader:1422:10)
2024-05-23 12:04:00.555 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module.load (node:internal/modules/cjs/loader:1203:32)
2024-05-23 12:04:00.558 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._load (node:internal/modules/cjs/loader:1019:12)
2024-05-23 12:04:00.560 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module.require (node:internal/modules/cjs/loader:1231:19)
2024-05-23 12:04:00.563 - error: host.ioBrokerTestsystem Caught by controller[0]: at require (node:internal/modules/helpers:177:18)
2024-05-23 12:04:00.566 - error: host.ioBrokerTestsystem Caught by controller[0]: at Object. (/opt/iobroker/node_modules/iobroker.vis-inventwo/node_modules/@iobroker/adapter-core/build/index.js:19:27)
2024-05-23 12:04:00.571 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._compile (node:internal/modules/cjs/loader:1364:14)
2024-05-23 12:04:00.574 - error: host.ioBrokerTestsystem Caught by controller[0]: Node.js v18.20.3
2024-05-23 12:04:00.579 - error: host.ioBrokerTestsystem instance system.adapter.vis-inventwo.0 terminated with code 1 (JS_CONTROLLER_STOPPED)

Folgendes ist neu, da ich noch ein paar Adapter aktiviert habe die bisher inaktiv waren:

2024-05-23 12:04:18.978 - error: host.ioBrokerTestsystem Caught by controller[0]: /opt/iobroker/node_modules/iobroker.samsung-community/main.js:112
2024-05-23 12:04:19.023 - error: host.ioBrokerTestsystem Caught by controller[0]: var adapter = utils.Adapter({
2024-05-23 12:04:19.039 - error: host.ioBrokerTestsystem Caught by controller[0]: ^
2024-05-23 12:04:19.054 - error: host.ioBrokerTestsystem Caught by controller[0]: TypeError: utils.Adapter is not a function
2024-05-23 12:04:19.065 - error: host.ioBrokerTestsystem Caught by controller[0]: at Object. (/opt/iobroker/node_modules/iobroker.samsung-community/main.js:112:21)
2024-05-23 12:04:19.077 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._compile (node:internal/modules/cjs/loader:1364:14)
2024-05-23 12:04:19.087 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._extensions..js (node:internal/modules/cjs/loader:1422:10)
2024-05-23 12:04:19.098 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module.load (node:internal/modules/cjs/loader:1203:32)
2024-05-23 12:04:19.108 - error: host.ioBrokerTestsystem Caught by controller[0]: at Module._load (node:internal/modules/cjs/loader:1019:12)
2024-05-23 12:04:19.118 - error: host.ioBrokerTestsystem Caught by controller[0]: at Function.executeUserEntryPoint [as runMain] (node:internal/modules/run_main:128:12)
2024-05-23 12:04:19.127 - error: host.ioBrokerTestsystem Caught by controller[0]: at node:internal/main/run_main_module:28:49
2024-05-23 12:04:19.136 - error: host.ioBrokerTestsystem Caught by controller[0]: Node.js v18.20.3
2024-05-23 12:04:19.145 - error: host.ioBrokerTestsystem instance system.adapter.samsung-community.0 terminated with code 1 (JS_CONTROLLER_STOPPED)