UPDATE 31.10.: Amazon Alexa - ioBroker Skill läuft aus ?

[David16]

@Kuddel leider habe ich seit einigen tage wieder das Problem, dass meine Rollos morgens überhaupt nicht hochfahren und abends immer zur Sonnenuntergangszeit herunterfahren. Die Verzögerung scheint gar nicht zu greifen. - Ich verstehe es nicht ich habe schon stundenlang gesucht.

Vielleicht kannst du mir helfen?

Hier die Zeiten-Datenpunkte aus dem Javascript.0:

[David16]

@Kuddel

Hier noch die Scripte von mir. Im Endeffekt deine Sripte nur mit der Cron-Veränderung:
Rolladen_Tag&Nacht.txt
Zeiten.txt

[Kuddel]

@david16 wenn ich mir deine Datenobjekte so anschaue, sind aktuell bei dir folgende Zeiten eingestellt:

öffnen: 06:21 Uhr
schließen: 20:57 Uhr

Passiert zu den Zeiten gar nicht?

Hast du mal in deinem Blockly direkt nach dem Cron einen "Debug Output" Baustein eingebaut, um zu sehen, ob der Cron auslöst?

Gibt es im log Fehler, wenn die Uhrzeit verändert wird?

So sehen meine Objekte aus

Spoiler

[David16]

@kuddel Ich habe gestern den "Cron" einmal rausgenommen. Da sind die Rollos dann um 21:01 wie Sie sollten heruntergefahren. Zumindest einen Teilerfolg. Heute Morgens hat sich allerdings wieder nichts getan.

Laut Log ist heute Morgen garnichts passiert.

2021-04-19 06:55:07.626  - info: host.iobroker instance system.adapter.dwd.0 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION)
2021-04-19 06:55:22.030  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:55:42.016  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:56:02.017  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:56:22.015  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:56:42.014  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:57:02.017  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:57:22.015  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:57:42.018  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:58:02.018  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:58:22.017  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:58:42.017  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:59:02.027  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:59:22.188  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:59:42.021  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 07:00:00.117  - info: host.iobroker instance system.adapter.dwd.0 started with pid 30797
2021-04-19 07:00:02.021  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 07:00:02.465  - info: dwd.0 (30797) starting. Version 2.7.5 in /opt/iobroker/node_modules/iobroker.dwd, node: v12.16.2, js-controller: 3.2.16
2021-04-19 07:00:04.151  - info: host.iobroker instance system.adapter.daswetter.0 started with pid 30988
2021-04-19 07:00:05.069  - debug: daswetter.0 (30988) Redis Objects: Use Redis connection: 127.0.0.1:9001
2021-04-19 07:00:05.152  - debug: daswetter.0 (30988) Objects client ready ... initialize now
2021-04-19 07:00:05.221  - debug: daswetter.0 (30988) Objects create System PubSub Client
2021-04-19 07:00:05.223  - debug: daswetter.0 (30988) Objects create User PubSub Client
2021-04-19 07:00:05.224  - debug: daswetter.0 (30988) Objects client initialize lua scripts
2021-04-19 07:00:05.248  - debug: daswetter.0 (30988) Objects connected to redis: 127.0.0.1:9001
2021-04-19 07:00:05.257  - debug: daswetter.0 (30988) objectDB connected
2021-04-19 07:00:05.259  - debug: daswetter.0 (30988) Redis States: Use Redis connection: 127.0.0.1:9000
2021-04-19 07:00:05.292  - debug: daswetter.0 (30988) States create System PubSub Client
2021-04-19 07:00:05.293  - debug: daswetter.0 (30988) States create User PubSub Client
2021-04-19 07:00:05.359  - debug: daswetter.0 (30988) States connected to redis: 127.0.0.1:9000
2021-04-19 07:00:05.360  - debug: daswetter.0 (30988) statesDB connected

Ich habe das die Zeiten aus den Javascript.0 nochmal von heute Morgen angehängt:

Zu deinen Fragen: In einem Blockly habe ich keinen "Debug Output" eingebaut. Ich habe leider keine großen Programmierkenntnisse. Bekomme ich das ohne Probleme reingebaut?

Das Verändern der Zeiten führt zu keinen Log Fehlern.

[David16]

@Kuddel reicht das mit dem Debug output dann so aus?!

Edit:

Logs:

2021-04-19 09:28:42.006  - info: javascript.1 (1906) Stop script script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3
2021-04-19 09:28:42.026  - info: javascript.2 (2066) Stop script script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3
2021-04-19 09:28:42.031  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 09:28:42.038  - info: javascript.3 (2085) Stop script script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3
2021-04-19 09:28:42.156  - info: javascript.1 (1906) Start javascript script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3
2021-04-19 09:28:42.201  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: test
2021-04-19 09:28:42.210  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: registered 2 subscriptions and 1 schedule

[Kuddel]

@david16 der Debug-Output Block muss zwingend in der CRON Trigger. Wir wollen ja prüfen, ob der CRON auslöst.

[David16]

@kuddel Habe es jetzt für morgens und abends einmal reingebaut.
Werde Morgen früh berichten.

Ich habe dazu aber noch ein paar Fragen:

Nutzt denn noch den CRON Restart?

Ich habe in deinen Scripten zur "dritten VIS" gesehen, dass du den "CRON-Restart" hier nicht programmierst hast?

Und du bei dir Screenshot hast du die Werte Rolladen öffnen im CRON-Regel morgens bei Stunde und Minuten anders programmiert. - Kann das Auswirkungen haben?

---

Ich habe gerade nochmal eine weitere Frage an dich: Ich bekomme leider immer die folgende Error-Meldung, obwohl der Datenpunkt vorhanden ist - Was mache ich denn da falsch?

2021-04-19 06:55:22.030  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:55:42.016  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:56:02.017  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:56:22.015  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:56:42.014  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:57:02.017  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:57:22.015  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:57:42.018  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:58:02.018  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:58:22.017  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:58:42.017  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:59:02.027  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:59:22.188  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.
2021-04-19 06:59:42.021  - error: javascript.3 (2085) script.js.java.sonstige.logs_vis: Log-Script-Fehler: Gewählter Filter Info, aber Datenpunkt [0_userdata.0.Log-Script.logInfo.logJSON] ist nicht vorhanden.

Der Datenpunkt ist doch vorhanden? Googlen und Schauen hat mir nicht weitergeholfen...

[Kuddel]

@david16

Der CRON Restart ist weiterhin drin, damit bei jedem Speichern, Start der Javascript-Instanz oder Start vom ioBroker der CRON neu eingestellt wird (sicher ist sicher).

ich hab in der aktuellen Version vom Skript die Schaltzeiten nur noch in einem DP und nicht mehr unterschieden in Stunde und Minute.

Ist nur eine kleine Schönheitskorrektur, ändert aber nichts an der Funktionalität.

Bzgl. der Log Fehler: Ist das auch ein Skript von mir ? Falls ja, ich nutze dafür mittlerweile den Adaptert Log-Parser

[David16]

@kuddel okay danke für die schnellen Antworten.
Den Logparser werde ich gleich mal installieren. :)

Ich schreibe dann Morgen wie es wegen dem Script ausgesehen hat.

[David16]

@Kuddel der LogParser ist echt toll, den kannte ich noch gar nicht, kannst du mir dir deine Einstellungen hierzu senden? - Die Datenpunkte etc. aus der Visualisierung versuche ich mir dann selbst reinzukopieren :)

[David16]

@Kuddel Wie bereits angekündigt die Rückmeldung.

Gestern Abend hat das Skript ausgelöst. Alles Top, Uhrzeit passt. Logs sind auch geschrieben worden:

2021-04-19 20:53:00.019  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: CRON für Rolladen abends ausgelöst
2021-04-19 20:53:00.026  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Küche|links auf 0% +++
2021-04-19 20:53:00.028  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Küche|rechts auf 0% +++
2021-04-19 20:53:00.029  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Arbeitszimmer auf 0% +++
2021-04-19 20:53:00.030  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Schlafzimmer|links auf 0% +++
2021-04-19 20:53:00.031  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Schlafzimmer|rechts auf 0% +++
2021-04-19 20:53:00.032  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Wohnzimmer Couch auf 0% +++
2021-04-19 20:53:00.033  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Wohnzimmer Terrasse auf 0% +++
2021-04-19 20:53:00.034  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Wohnzimmer Esstisch links auf 0% +++
2021-04-19 20:53:00.034  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Wohnzimmer Esstisch Mitte auf 0% +++
2021-04-19 20:53:00.035  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Wohnzimmer Esstisch rechts auf 0% +++
2021-04-19 20:53:00.036  - info: javascript.1 (1906) script.js.smarthome.rolladen.rolladen_tag_nacht_v0_3: +++ Sonnenuntergang 30: Turmzimmer|links auf 0% +++

Heute Morgen ist wieder nichts passiert. Kein Log, kein Hochfahren der Rollos. Ich verstehe es einfach nicht, wieso morgens nicht passiert...

Hier nochmal ein aktueller Screenshot der Zeiten:
Hier ist mir aufgefallen, dass der Rolladen_cron_restart nicht aktualisiert wurde?!

´
und das Skript:
rolladen_tag_nacht_v0_3.json

Vielleicht findest du ja einen Ansatz.

[Kuddel]

@david16 dann verlgeiche einfach mal die blöcke für morgens und abends, ob da unterschiede zu erkennen sind.

es ist auf jeden fall schon einmal gut, dass abends alles so funktioniert wie es soll.

[David16]

@kuddel Das habe ich schon ein paar mal probiert. Leider ohne Erfolg. :disappointed:

Abends funktioniert es ja prima:

Ich habe das Gefühl, dass es wegen dem folgenden Codeschnipsel am Morgen nicht geht:

Hier war es früher wie folgt, das siehe ich aus dem alten Exportdateien die ich von dir einmal importiert hatte:

Kannst du mir sagen wie der Datenpunkt mit dem "Stop ... "richtig heißen müsste.

[Kuddel]

@david16 du hast bereits den Fehler gefuden und die richtige stelle eingekreist.

Der Block stoppt den CRON Job. Danach wir er neu gestartet, damit Änderungen übernommen werden.

In dem Block muss also "stop Name_CRON" stehen.

In deinem Fall "stop morgens"

[David16]

@kuddel Es hat auch noch an etwas anderem gelegen. Mit deinen Tipp Debug Output habe ich es finden können. Ich habe es nach jeden Schritt habe einbauen können. Danke hierfür :)

Hier war der Arbeitsstatus ausschlaggebend. Ich habe Ihn rausgenommen und dann sind die Rollos auch schon hochgefahren:

[ChrisXY]

gibs ein NoNo Update ? Adapter wurde ja neu geschrieben und einiges geändert ?

[Kuddel]

@chrisxy

sieht bei mir aktuell so aus:

Dusty ist der Sauger im OG

[ChrisXY]

@kuddel said in [Vorstellung] Meine zweite Visualisierung:

@chrisxy

sieht bei mir aktuell so aus:

Dusty ist der Sauger im OG

Okay und der läuft mit der neusten Adapter Version? Kannst du die VIS Dateien dafür mal hochladen ? Bekomm da z.b bei Pause aktuell "10" angezeigt bei Status. Denke da wird noch einiges anderes nicht funktionieren ?

[Kuddel]

@chrisxy ich nutze Version 2.2.4 und habe auch immer noch Warnings im Log

[ChrisXY]

@kuddel komisch hatte anfangs auch Probleme hab alles gelöscht erstmal mit Adapter Einstellungen gestartet danach die Map aktiviert auch die neue mit Farben usw. Klappte dann. War aber bisschen hin und her. Seit dem keine fehler im Log.