Alpha Test js-controller Kiera (v6.0)

[mcm1957]

@feuersturm
Kannst du mal versuchen den ical mit DEBUG Loglevel zu starten?

Der ical hat in der Version noch das nicht mehr unterstütze subscribe: <stateid> drinnen. Das sollte aber eigentlich keinen Start verhindern sondern nur den manuellen Trigger de facto außer Betrieb setzen.

@foxriver76
Achtung: Github ist etwas neuer als die Release. Auf Github hat Klein0r das subscribe schon aus io-package entfernt

Sobald windows js-controlelr geht, könnt ich da was checken. Hab aber kein Linux Testsystem - und für production ist js-controller 6 noch etwas zu alpha

[mcm1957]

@foxriver76
Der adapter-core von ical sollte aktuell sein

[foxriver76]

@mcm1957 Jop am Handy das log falsch zugeordnet Problem sind nur die <2.6.9 noch aber fixe ich morgen im Controller.

Ja iCal villt was mit subscribe zu tun kann ich morgen evtl auch testen.

[mcm1957]

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

@mcm1957 Jop am Handy das log falsch zugeordnet Problem sind nur die <2.6.9 noch aber fixe ich morgen im Controller.

Ja iCal villt was mit subscribe zu tun kann ich morgen evtl auch testen.

Der ical ist auch ein subscribe Adapter. Kann sein, dass das der einzige ist.

[mcm1957]

Ich hab jetzt mal mein Windowssytem auf js-controller 6 gebracht (mit dem manuellen Fix)

Der ical startet bei mir einwandfrei:

host.Envy
2024-05-22 21:25:08.287	info	instance system.adapter.ical.0 terminated with code 0 (NO_ERROR)

ical.0
2024-05-22 21:25:07.726	info	Terminated (NO_ERROR): Without reason

ical.0
2024-05-22 21:25:02.716	info	All calenders could not be processed, Do not clean up events

ical.0
2024-05-22 21:25:02.715	warn	Error reading "http://11111.ics": Cannot read URL: "http://11111.ics"

ical.0
2024-05-22 21:25:02.714	warn	Error reading from URL "http://11111.ics"

ical.0
2024-05-22 21:25:02.474	info	starting. Version 1.15.0 in C:/ioBroker/node_modules/iobroker.ical, node: v18.20.2, js-controller: 6.0.1-alpha.0-20240522-72e34be5c
host.Envy
2024-05-22 21:25:01.952	info	Restart compact group controller 3

Hinweis: Das System läuft im compact mode - das sollte aber wohl kaum ein Problem BEHEBEN-

Hinweis2: Ich hab keine echte Config sondern nur das was als Default drinnen ist abgespeichert.

[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