Test js-controller v2.0.x (GitHub)

SBorg

Node-RED Start: log.txt

Restart Node-RED Adapter:

2019-09-20 17:41:40.013 - debug: node-red.0 (16880) system.adapter.admin.0: logging false
2019-09-20 17:41:40.038 - debug: node-red.0 (16971) system.adapter.admin.0: logging false
2019-09-20 17:41:40.722 - info: host.Ubuntu object change system.adapter.node-red.0 (from: system.adapter.admin.0)
2019-09-20 17:41:40.723 - info: node-red.0 (16971) Adapter is disabled => stop
2019-09-20 17:41:40.729 - info: node-red.0 (16880) kill node-red task
2019-09-20 17:41:40.723 - info: host.Ubuntu "system.adapter.node-red.0" disabled
2019-09-20 17:41:40.726 - info: host.Ubuntu stopInstance system.adapter.node-red.0
2019-09-20 17:41:41.307 - info: node-red.0 (16971) terminating
2019-09-20 17:41:41.344 - info: node-red.0 (16880) node-red exited with 0
2019-09-20 17:41:41.348 - info: mqtt.0 (16456) Client [ioBroker] connection closed: closed
2019-09-20 17:41:41.731 - info: host.Ubuntu stopInstance timeout "1000 system.adapter.node-red.0 killing pid 16880
2019-09-20 17:41:41.808 - info: node-red.0 (16880) Terminated (NO_ERROR): Without reason
2019-09-20 17:41:41.840 - info: host.Ubuntu instance system.adapter.node-red.0 terminated with code 0 (NO_ERROR)
2019-09-20 17:41:49.504 - info: host.Ubuntu object change system.adapter.node-red.0 (from: system.adapter.admin.0)
2019-09-20 17:41:49.505 - info: host.Ubuntu "system.adapter.node-red.0" enabled
2019-09-20 17:41:49.588 - info: host.Ubuntu instance system.adapter.node-red.0 started with pid 6366
2019-09-20 17:41:52.629 - debug: node-red (6366) Redis Objects: Use Redis connection: 127.0.0.1:9001
2019-09-20 17:41:55.060 - debug: node-red (6366) Objects connected to redis: 127.0.0.1:9001
2019-09-20 17:41:55.078 - debug: node-red (6366) objectDB connected
2019-09-20 17:41:55.084 - debug: node-red (6366) Redis States: Use Redis connection: 127.0.0.1:9000
2019-09-20 17:41:55.098 - debug: node-red (6366) statesDB connected
2019-09-20 17:41:55.124 - error: node-red (6366) node-red.0 invalid instance object: {"_id":"","type":"channel","common":{"name":"States created by node-red.%INSTANCE%","role":"info"},"native":{}}
2019-09-20 17:41:55.136 - debug: node-red (6366) States connected to redis: 127.0.0.1:9000
2019-09-20 17:41:56.034 - info: node-red.0 (6366) starting. Version 1.9.0 in /opt/iobroker/node_modules/iobroker.node-red, node: v8.16.1
2019-09-20 17:41:58.051 - info: node-red.0 (6366) Starting node-red: --max-old-space-size=156 /opt/iobroker/node_modules/iobroker.node-red/node_modules/node-red/red.js -v --settings /opt/iobroker/iobroker-data/node-red/settings.js
2019-09-20 17:42:03.598 - debug: node-red.0 (6366) 20 Sep 17:42:03 - [info]
Willkommen bei Node-RED!
===================
2019-09-20 17:42:03.601 - debug: node-red.0 (6366) 20 Sep 17:42:03 - [info] Node-RED Version: v0.20.7
2019-09-20 17:42:03.611 - debug: node-red.0 (6366) 20 Sep 17:42:03 - [info] Node.js Version: v8.16.1
20 Sep 17:42:03 - [info] Linux 5.0.0-29-generic ia32 LE
2019-09-20 17:42:06.394 - debug: node-red.0 (6366) 20 Sep 17:42:06 - [info] Paletten-Nodes werden geladen
2019-09-20 17:42:36.179 - debug: node-red.0 (6366) 20 Sep 17:42:36 - [info] Polymer started at /node-red/polymer
2019-09-20 17:42:39.590 - debug: node-red.0 (6366) 20 Sep 17:42:39 - [info] Dashboard version 2.15.5 started at /node-red/ui
2019-09-20 17:42:43.523 - debug: node-red.0 (6366) 20 Sep 17:42:43 - [info] Einstellungsdatei: /opt/iobroker/iobroker-data/node-red/settings.js
2019-09-20 17:42:43.529 - debug: node-red.0 (6366) 20 Sep 17:42:43 - [info] Kontextspeicher: 'default' [ module=memory]
2019-09-20 17:42:43.533 - debug: node-red.0 (6366) 20 Sep 17:42:43 - [info] Benutzerverzeichnis: /opt/iobroker/iobroker-data/node-red/
2019-09-20 17:42:43.535 - warn: node-red.0 (6366) 20 Sep 17:42:43 - [warn] Projekte inaktiviert: setze editorTheme.projects.enabled=true zum Aktivieren
2019-09-20 17:42:43.541 - debug: node-red.0 (6366) 20 Sep 17:42:43 - [info] Flow-Datei: /opt/iobroker/iobroker-data/node-red/flows.json
2019-09-20 17:42:33.761 - debug: node-red (6646) Redis Objects: Use Redis connection: 127.0.0.1:9001
2019-09-20 17:42:43.617 - debug: node-red (6646) objectDB connected
2019-09-20 17:42:43.622 - debug: node-red (6646) Redis States: Use Redis connection: 127.0.0.1:9000
2019-09-20 17:42:43.628 - debug: node-red (6646) Objects connected to redis: 127.0.0.1:9001
2019-09-20 17:42:43.661 - debug: node-red (6646) statesDB connected
2019-09-20 17:42:43.692 - debug: node-red.0 (6366) 20 Sep 17:42:43 - [info] Server wird jetzt auf http://192.168.1.30:1880/node-red/ ausgeführt.
2019-09-20 17:42:43.823 - debug: node-red.0 (6366) 20 Sep 17:42:43 - [info] Flows starten
2019-09-20 17:42:44.258 - debug: node-red.0 (6366) 20 Sep 17:42:44 - [info] Flows gestartet
2019-09-20 17:42:44.787 - debug: node-red (6646) States connected to redis: 127.0.0.1:9000
2019-09-20 17:42:44.821 - info: mqtt.0 (16456) Client [ioBroker] connected with secret xxx
2019-09-20 17:42:44.867 - debug: node-red.0 (6366) 20 Sep 17:42:44 - [info] [mqtt-broker:MQTT@1883] Verbindung zum Broker ioBroker@mqtt://192.168.1.30:1883 hergestellt.
2019-09-20 17:42:44.879 - info: mqtt.0 (16456) Client [ioBroker] subscribes on "mqtt.0.cmnd.Wohnzimmer-Nebler.POWER"
2019-09-20 17:42:44.880 - info: mqtt.0 (16456) Client [ioBroker] publishOnSubscribe
2019-09-20 17:42:44.883 - info: mqtt.0 (16456) Client [ioBroker] subscribes on "mqtt.0.stat.HeizCon.PowerUP"
2019-09-20 17:42:44.883 - info: mqtt.0 (16456) Client [ioBroker] publishOnSubscribe
2019-09-20 17:43:44.212 - debug: node-red.0 (6366) 20 Sep 17:43:44 - [info] [inject:Tablettenbestand] Wiederholen = 3600000
2019-09-20 17:44:38.649 - info: tankerkoenig.0 (16423) Reading data from tankerkoenig ...
...

apollon77

@SBorg das sieht aber an sich korrekt aus oder?! ... ja das start log. Checke.

apollon77

@SBorg Hm ... das problem ist das node-red "zu schnell" startet. Wie Du im log schön siehst will er schon states abrufen 17:31:41.477 ... aber die ioBroker-Datenbank-Verbindungen sind erst 17:31:42.659 verfügbar. Das sind einige "Get"-Nodes die in dem Zuge wohl recht am Anfang stehen. Und weil bei einem kompletten Start natürlich der Host recht zu viel tun hat kann das mal etwas dauern ...

Bitte GitHub Issue bei node-red anlegen. Das muss man irgendwie "delayen" bis der Adapter ready ist. Bitte so einen 2getObject" Fehler mit Stack Trace reinpacken ins Issue.

apollon77

@SBorg Bitte mal node-red vom GitHub versuchen. Ist blind gemacht kann also auch schieff gehen Das sollte das prüfen der States verzögern falls die DBs noch nicht da sind.

e-i-k-e

Guten Abend,

habe soeben mein Multihost System mit redis nach Anleitung aktualisiert.
Master: Rock64 4GB + SSD, 6x Slave Raspberry).
Iobroker: Node.js 10.16.3, js controller: 2.0.4, npm: 6.11.3

Ich bekomme nun folgende Fehlermeldung unzählige mal im Log Angezeigt (mit geänderterer IP-Adresse)

Bis auf die Adapter der Slave Geräte, sind alle Adapter Grün.
Unter Host werden die Slave als verfügbar angezeigt.

apollon77

@e-i-k-e Die Slaves sind also auch alle auf die 2.0 aktualisiert? Es scheint als ob die Slaves nicht zum Master kommen. WIe ist die Ausgabe von "iobroker setup custom" von allen Hosts bitte?

apollon77

Hey All,

die 2.0.5 ist soeben auf GitHub gelandet und sollte alle hier verifizierten Themen behandeln. Daher bitte folgendes Re-Testen:

Zugriff auf Files/Images in Vis oder web (oder iqontrol?) sollte tun, auch eigene File-Locations die eigentlich nicht supported sind
Für node-red gibts eine neue node-red Adapterversion ...
Bitte auch mit ble nochmal testen. Es sollte jetzt mal mindestens beim versuch den in compact-Modus zu starten den Fehler geben und das zu einem Fallback-Start als normaler Prozess führen. Beim Restart kann es immer noch zu craseds kommen, das kann nur der Adapter fixen. Aber wäre cool zu wissen wie sich das jetzt verhält.

Weiterhin ist die Migration jetzt noch ausgeklügelter was das Nachfragen angehtund die Entscheidung ob eine Migration erlaubt wird.

e-i-k-e

@apollon77

Danke für deine schnelle Antwort.

Ein Neustart der Slave Geräte hat das Problem behoben. Evtl. sollte man diese Info in die Anleitung mit aufnehmen?

e-i-k-e

@apollon77

Mit 2.0.5 bekomme ich meinen ioBroker Master nicht mehr gestartet.

root@ioBroker-Rock:~# iobroker status
 Cannot parse /opt/iobroker/iobroker-data/objects.json: SyntaxError: Unexpected                                                                                                              end of JSON input
 Cannot parse /opt/iobroker/iobroker-data/objects.json.bak: SyntaxError: Unexpec                                                                                                             ted end of JSON input
iobroker is not running.

Objects type: file
States  type: redis

Wie komme ich auf die 2.0.4 zurück?

apollon77

@e-i-k-e da hat es irgendwie deine objects.json zerstört. liegt eher nicht am Controller denke ich. Sichere die mal weg und schau ob du im Verzeichnis iobroker-data/Backup-objects ein früheres file findest was tut. Einfach auspacken und anstelle objects.json hinkopieren.

apollon77

@e-i-k-e naja dazu müsste ich genauer verstehen was das Szenario war. Hast du alles gestoppt, geupdatet und dann Master und dann slaves gestartet oder wie?

e-i-k-e

@apollon77
Kannst du mir bitte kurz unter die Arme greifen?

apollon77

@e-i-k-e is: heb mal die kaputte objects.json auf und schick sie mir per E-Mail (iobroker@fischer-ka.de). Dann kann ich mal schauen was kaputt war.

apollon77

@e-i-k-e was genau brauchst du? Ich hab nur keinen Rechner mehr weil ich grad im Bett gelandet bin.
Habe oben an sich die groben Infos geschrieben.

Die objects.json ist kaputt. Die musst du mit einer älteren Version austauschen. Diese Backups liegen im Angegebenen Verzeichnis. Eine aktuelle raussuchen., entpacken und Objekts.json ersetzen. Falls Backup gleicher Fehler dann eine ältere nehmen.

e-i-k-e

Ich weiß nicht, wie ich diese Datei entpacken muss.

sigi234

@e-i-k-e sagte in [Aufruf] js-controller 2.0 Beta Test:

Ich weiß nicht, wie ich diese Datei entpacken muss.

https://www.7-zip.org/

apollon77

@e-i-k-e glaube die Endung ist .gz oder? Dann

gunzip  <Dateiname>

e-i-k-e

Hat geklappt, Danke!

Schicke Dir die alte Datei rüber.

Einstein67

Nach einem (erfolgreichen) Standard Backup mit dem Adapter BackitUp, kommen diese Zeilen im Log.

Error	2019-09-21 06:54:47.255	warn	from InMemDB: Error: ERR keys id=web.0 - Not exists
Error	2019-09-21 06:54:47.254	warn	from InMemDB: Error: ERR keys id=admin.0 - Not exists

Die Backups lassen sich aber problemlos Restoren. "admin.0" und "web.0" sind leere Ordner im Backup-Archiv. Der "vis.0" Ordner in dem etwas drinnen ist erzeugt keine Fehlermeldung. Also möglicherweise ein reiner Schönheitsfehler

Alles andere läuft auf meinem System absolut perfekt. Getestet von JS 2.0.2 bis JS 2.0.5

EDIT: Die Meldungen kommen auch bei "iobroker backup" wenn der Iobroker vorher nicht gestoppt wird!

apollon77

@Einstein67 ja, Danke. Schaue ich an und fixe es bzw denke mache aus warn ne andere Meldung.

NEWS

Test js-controller v2.0.x (GitHub)

Support us

733
Online

31.7k
Users

79.8k
Topics

1.3m
Posts

NEWS

Test js-controller v2.0.x (GitHub)

Support us

733Online

31.7kUsers

79.8kTopics

1.3mPosts

733
Online

31.7k
Users

79.8k
Topics

1.3m
Posts