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

[crunchip]

nachdem ich hierher verwiesen wurde, siehe https://forum.iobroker.net/post/761767
heutiges backitup Update von v2.3.0.auf v2.3.1 ergab folgenden Fehler
habe kein multihost und verwende jsonl

2022-02-13 17:06:27.572 - info: backitup.0 (15941) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason
2022-02-13 17:06:28.086 - error: backitup.0 (15941) Connection is closed.
2022-02-13 17:06:28.089 - error: backitup.0 (15941) Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch().
2022-02-13 17:06:28.090 - error: backitup.0 (15941) unhandled promise rejection: States DB is not allowed to start in the current Multihost environment
2022-02-13 17:06:28.092 - error: backitup.0 (15941) Error: States DB is not allowed to start in the current Multihost environment
at Redis. (/opt/iobroker/node_modules/@iobroker/db-states-redis/lib/states/statesInRedisClient.js:579:23)
at processTicksAndRejections (internal/process/task_queues.js:95:5)
2022-02-13 17:06:28.093 - error: backitup.0 (15941) States DB is not allowed to start in the current Multihost environment
2022-02-13 17:06:33.341 - info: backitup.0 (16133) starting. Version 2.3.1 in /opt/iobroker/node_modules/iobroker.backitup, node: v14.19.0, js-controller: 4.0.8
2022-02-13 17:06:33.540 - info: backitup.0 (16133) [iobroker] backup was activated at 05:30 every 1 day(s)

[apollon77]

@crunchip mit welchem Controller? 4.0.8? Ok gesehen das es 4.0.8 ist.

Lese ich den log Auszug korrekt das der Fehler bei einem Start nach dem Update kam aber alles tut? Kommt der Fehler wieder wenn du restartest?

Edit: und bitte mehr log von davor und danach. Am besten auch log vom Update selbst. Ist zu arg gekürzt

[crunchip]

@apollon77 sagte in js-controller 4.0 jetzt im BETA/LATEST!:

Am besten auch log vom Update selbst

ein restart funktioniert ohne Fehlermeldung
das war das log vom Adapter update, hatte nur die info meldungen weggelassen

ganze Fassung

2022-02-13 17:00:00.595 - info: javascript.0 (17194) script.js.Visualisierung.Lebensmittel-RRS: RSS-Feed https://www.lebensmittelwarnung.de/bvl-lmw-de/opensaga/feed/alle/bayern.rss eingelesen
2022-02-13 17:05:07.110 - info: backitup.0 (17701) Got terminate signal TERMINATE_YOURSELF
2022-02-13 17:05:07.135 - info: backitup.0 (17701) cleaned everything up...
2022-02-13 17:05:07.137 - info: backitup.0 (17701) terminating
2022-02-13 17:05:07.139 - info: backitup.0 (17701) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2022-02-13 17:06:00.320 - info: javascript.0 (17194) script.js.Benachrichtigung.zigbee_überwachung: Run Zigbee-Watchdog
2022-02-13 17:06:04.699 - info: javascript.0 (17194) script.js.common.System.Statusmeldungen.Status_Lichtzählen1: Auslösender Schalter: sonoff.0.Kueche.POWER: false
2022-02-13 17:06:04.707 - info: javascript.0 (17194) script.js.common.System.Statusmeldungen.Status_Lichtzählen1: Text:
2022-02-13 17:06:04.708 - info: javascript.0 (17194) script.js.common.System.Statusmeldungen.Status_Lichtzählen1: Anzahl Lichter: 20 # davon Lichter an: 0
2022-02-13 17:06:27.572 - info: backitup.0 (15941) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason
2022-02-13 17:06:28.086 - error: backitup.0 (15941) Connection is closed.
2022-02-13 17:06:28.089 - error: backitup.0 (15941) Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch().
2022-02-13 17:06:28.090 - error: backitup.0 (15941) unhandled promise rejection: States DB is not allowed to start in the current Multihost environment
2022-02-13 17:06:28.092 - error: backitup.0 (15941) Error: States DB is not allowed to start in the current Multihost environment
at Redis. (/opt/iobroker/node_modules/@iobroker/db-states-redis/lib/states/statesInRedisClient.js:579:23)
at processTicksAndRejections (internal/process/task_queues.js:95:5)
2022-02-13 17:06:28.093 - error: backitup.0 (15941) States DB is not allowed to start in the current Multihost environment
2022-02-13 17:06:33.341 - info: backitup.0 (16133) starting. Version 2.3.1 in /opt/iobroker/node_modules/iobroker.backitup, node: v14.19.0, js-controller: 4.0.8
2022-02-13 17:06:33.540 - info: backitup.0 (16133) [iobroker] backup was activated at 05:30 every 1 day(s)
2022-02-13 17:08:00.508 - info: javascript.0 (17194) script.js.Visualisierung.Tageshow: URL: media.tagesschau.de/video/2022/0213/TV-20220213-1630-1001.webxl.h264.mp4
2022-02-13 17:08:00.517 - info: javascript.0 (17194) script.js.Visualisierung.Tageshow: HTML: https://www.tagesschau.de/multimedia/livestreams/livestream-3-105~player.html

[apollon77]

@crunchip sorry. Im log sehe ich immer noch keinen install oder Update drin. Bitte meeeeeeeeeehr log

[crunchip]

@apollon77 es gibt nicht meeeeeeeeeeehr, das ist genau das was ausgegeben wurde nachdem ich das update gemacht habe. Nicht mehr und nicht weniger.
Kann nichts her zaubern was es nicht gibt.

Im übrigen hab ich auch noch metro widget und ìQontrol ein update gemacht, find ich aber ebenfalls nichts dazu im log!!

[apollon77]

@crunchip ich würde gern das update im
Log sehen (an sich ist das immer mit drin) und auch die zeitlichen Zusammenhänge des Updates mit dem Adapter start der da gezickt hat.

[crunchip]

@apollon77 ich kann dir das ganze log vom Tag schicken

habe gerade vis update gemacht um 21:33 habe ich den aktualisieren Button gedrückt

log dazu

2022-02-13 21:25:12.293 - info: vis.0 (10029) starting. Version 1.4.12 in /opt/iobroker/node_modules/iobroker.vis, node: v14.19.0, js-controller: 4.0.8
2022-02-13 21:25:12.451 - info: vis.0 (10029) vis license is OK.
2022-02-13 21:25:19.794 - info: vis.0 (10029) Changes in index.html detected => update cache.manifest
2022-02-13 21:25:20.195 - info: vis.0 (10029) Upload vis anew, while changes detected...
2022-02-13 21:25:26.571 - info: javascript.0 (17194) script.js.common.System.Statusmeldungen.Status_Lichtzählen1: Auslösender Schalter: zigbee.0.00158d0005062d72.state: true
2022-02-13 21:25:26.574 - info: javascript.0 (17194) script.js.common.System.Statusmeldungen.Status_Lichtzählen1: Text: Buerolicht ,Esstisch ,Flur,Garten,Kueche ,Noel-Wandlicht ,Sofa ,Terrasse
2022-02-13 21:25:26.575 - info: javascript.0 (17194) script.js.common.System.Statusmeldungen.Status_Lichtzählen1: Anzahl Lichter: 20 # davon Lichter an: 8
2022-02-13 21:25:31.456 - info: javascript.0 (17194) script.js.common.System.Statusmeldungen.Status_Lichtzählen1: Auslösender Schalter: sonoff.0.WC-Licht.POWER: true
2022-02-13 21:25:31.459 - info: javascript.0 (17194) script.js.common.System.Statusmeldungen.Status_Lichtzählen1: Text: Buerolicht ,Esstisch ,Flur,Garten,Kueche ,Noel-Wandlicht ,Sofa ,Terrasse,WC-Licht
2022-02-13 21:25:31.460 - info: javascript.0 (17194) script.js.common.System.Statusmeldungen.Status_Lichtzählen1: Anzahl Lichter: 20 # davon Lichter an: 9
2022-02-13 21:25:53.194 - info: vis.0 (10029) Uploaded. 0
2022-02-13 21:25:53.706 - info: vis.0 (10029) Terminated (NO_ERROR): Without reason
2022-02-13 21:26:39.217 - info: vis.0 (10545) starting. Version 1.4.12 in /opt/iobroker/node_modules/iobroker.vis, node: v14.19.0, js-controller: 4.0.8
2022-02-13 21:26:39.507 - info: vis.0 (10545) vis license is OK.
2022-02-13 21:26:41.307 - info: vis.0 (10545) Terminated (NO_ERROR): Without reason

[apollon77]

@crunchip Ist dad das Log aus dem Admin? Kannst Du bitte mal schauen ob im Logfile auf der Platte mehr steht unter /opt/iobroker/logs/...

[crunchip]

@apollon77 sagte in js-controller 4.0 jetzt im BETA/LATEST!:

Ist dad das Log aus dem Admin?

log herunterladen Button und dann herauskopiert

auf der Platte steht auch nicht mehr

[apollon77]

@crunchip wtf ... das macht es irgendwie noch komischer ... starte controller und so mal neu

[crunchip]

@apollon77 mach ich morgen vormittag, sehe gerade, es kamen ein paar Adapter updates, dann teste ich das in aller Ruhe

Edit:
hängt aber nicht damit zusammen, das die Logstufe vom Host auf warn steht?

[apollon77]

@crunchip sagte in js-controller 4.0 jetzt im BETA/LATEST!:

hängt aber nicht damit zusammen, das die Logstufe vom Host auf warn steht?

hehe ... ja klar, damit fehlen alle info logs ... damit fehlen einige Infos zum Debuggen

[crunchip]

@apollon77 sagte in js-controller 4.0 jetzt im BETA/LATEST!:

ja klar, damit fehlen alle info logs

da bin ich mir aber nicht sicher, host hab ich schon ewig auf warn stehen, aber wenn mich nicht alles täuscht, sah man trotzdem die Meldungen... update version x auf version y

[apollon77]

So, wir nähern uns dem Ende ... die 4.0.9, damit Stable RC1, mit letzten Optimierungen und Fixes ist auf dem Weg ins Latest Repo:

4.0.9 (2022-02-13)

• (foxriver76) dependency check on instance deletion: hostname has to be relative to instance if single instance is deleted
• (foxriver76) fix setInterval method for adapters
• (AlCalzone) Prevent db-file-locking issues for jsonl database; the connectTimeout ro databases is now minimum 5s (overrides lower values from configuration)

[Feuersturm]

@apollon77 Update auf Master und Slave mit der 4.0.9 verlief ohne Probleme.

[SBorg]

Ich habe mir beim letzten Upgrade nix dabei gedacht, kann ja immer mal passieren, aber jetzt wieder:

iob upgrade self
Update js-controller from @4.0.8 to @4.0.9
Stopped Objects DB
Stopped States DB
NPM version: 6.14.16
Installing iobroker.js-controller@4.0.9... (System call)

> iobroker.js-controller@4.0.9 preinstall /opt/iobroker/node_modules/iobroker.js-controller
> node lib/preinstallCheck.js

NPM version: 6.14.16

> iobroker.js-controller@4.0.9 install /opt/iobroker/node_modules/iobroker.js-controller
> node iobroker.js setup first

Successfully migrated 15599 objects to Redis Sets
object _design/system updated


 States database error: connect ECONNREFUSED 127.0.0.1:9000
 States database error: connect ECONNREFUSED 127.0.0.1:9000
 States database error: connect ECONNREFUSED 127.0.0.1:9000
 States database error: connect ECONNREFUSED 127.0.0.1:9000
 States database error: connect ECONNREFUSED 127.0.0.1:9000
 States database error: connect ECONNREFUSED 127.0.0.1:9000
 States database error: connect ECONNREFUSED 127.0.0.1:9000
 States database error: connect ECONNREFUSED 127.0.0.1:9000
 States database error: connect ECONNREFUSED 127.0.0.1:9000
^C

Nach npm i iobroker.js-controller@4.0.9 --production läuft es dann (iob upgrade self meldet dann nur wäre schon aktuell ;) )

[apollon77]

@sborg sagte in js-controller 4.0 jetzt im BETA/LATEST!:

Stopped States DB

Ist das ein SingleHost System oder Multihost?

[SBorg]

@apollon77 sagte in js-controller 4.0 jetzt im BETA/LATEST!:

@sborg sagte in js-controller 4.0 jetzt im BETA/LATEST!:

Stopped States DB

Ist das ein SingleHost System oder Multihost?

Normaler SingleHost, kein Redis, NPM 6.14.16, Nodejs 14.19.0, also alles Standard, nix Extravagantes.
Bis (glaube 4.0.6) lief es problemlos.
Wenn ich mal wieder ( ;) ) der Einzige bin, kann ich mir ja mittels "npm i ..." helfen.
Ich setze den "npm i..." übrigens dann direkt nach "iob upgrade self" ab, keine weiteren Aktionen nötig.

[coyote]

Sooo, habe jetzt auch mal alles auf die beta hochgezogen (produktives System)
DB ist auf Redis/Redis.
socket.IO und web auch auf die beta geupdatet.

Upgrade Prozess:

coyote@ioBroker:~$ iobroker stop
coyote@ioBroker:~$ iobroker upgrade self
Update js-controller from @3.3.22 to @4.0.9
NPM version: 6.14.16
npm install iobroker.js-controller@4.0.9 --loglevel error --unsafe-perm --prefix "/opt/iobroker" (System call)
In file included from ../../nan/nan.h:58,
                 from ../src/unix_dgram.cc:5:
/home/iobroker/.cache/node-gyp/14.19.0/include/node/node.h:793:7: warning: cast between incompatible function types from ‘void (*)(v8::Local<v8::Object>)’ to ‘node::addon_register_func’ {aka ‘void (*)(v8::Local<v8::Object>, v8::Local<v8::Value>, void*)’} [-Wcast-function-type]
  793 |       (node::addon_register_func) (regfunc),                          \
      |       ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
/home/iobroker/.cache/node-gyp/14.19.0/include/node/node.h:827:3: note: in expansion of macro ‘NODE_MODULE_X’
  827 |   NODE_MODULE_X(modname, regfunc, NULL, 0)  // NOLINT (readability/null_usage)
      |   ^~~~~~~~~~~~~
../src/unix_dgram.cc:404:1: note: in expansion of macro ‘NODE_MODULE’
  404 | NODE_MODULE(unix_dgram, Initialize)
      | ^~~~~~~~~~~
coyote@ioBroker:~$ iobroker update

Alle Adapter sind soweit durchgestartet bis auf den ble Adapter. Der wollte "npm install --production" in Adapter Directory.
Habe ich dann auch gemacht, dann ist er gestartet.

Einzig das hier hab ich noch beim Start:

host.ioBroker	2022-02-14 16:19:49.573	error	instance system.adapter.smartmeter.0 terminated with code 1 (JS_CONTROLLER_STOPPED)
host.ioBroker	2022-02-14 16:19:49.573	error	Caught by controller[0]: }
host.ioBroker	2022-02-14 16:19:49.572	error	Caught by controller[0]: code: 'ERR_DLOPEN_FAILED'
host.ioBroker	2022-02-14 16:19:49.572	error	Caught by controller[0]: at Module.load (internal/modules/cjs/loader.js:950:32) {
host.ioBroker	2022-02-14 16:19:49.572	error	Caught by controller[0]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
host.ioBroker	2022-02-14 16:19:49.571	error	Caught by controller[0]: at Module._compile (internal/modules/cjs/loader.js:1085:14)
host.ioBroker	2022-02-14 16:19:49.571	error	Caught by controller[0]: at Object.<anonymous> (/opt/iobroker/node_modules/@serialport/bindings/lib/linux.js:2:36)
host.ioBroker	2022-02-14 16:19:49.571	error	Caught by controller[0]: at bindings (/opt/iobroker/node_modules/bindings/bindings.js:112:48)
host.ioBroker	2022-02-14 16:19:49.570	error	Caught by controller[0]: at require (internal/modules/cjs/helpers.js:101:18)
host.ioBroker	2022-02-14 16:19:49.570	error	Caught by controller[0]: at Module.require (internal/modules/cjs/loader.js:974:19)
host.ioBroker	2022-02-14 16:19:49.570	error	Caught by controller[0]: at Function.Module._load (internal/modules/cjs/loader.js:790:12)
host.ioBroker	2022-02-14 16:19:49.569	error	Caught by controller[0]: at Module.load (internal/modules/cjs/loader.js:950:32)
host.ioBroker	2022-02-14 16:19:49.569	error	Caught by controller[0]: at Object.Module._extensions..node (internal/modules/cjs/loader.js:1144:18)
host.ioBroker	2022-02-14 16:19:49.569	error	Caught by controller[0]: the module (for instance, using `npm rebuild` or `npm install`).
host.ioBroker	2022-02-14 16:19:49.569	error	Caught by controller[0]: NODE_MODULE_VERSION 83. Please try re-compiling or re-installing
host.ioBroker	2022-02-14 16:19:49.568	error	Caught by controller[0]: NODE_MODULE_VERSION 72. This version of Node.js requires
host.ioBroker	2022-02-14 16:19:49.568	error	Caught by controller[0]: was compiled against a different Node.js version using
host.ioBroker	2022-02-14 16:19:49.567	error	Caught by controller[0]: Error: The module '/opt/iobroker/node_modules/@serialport/bindings/build/Release/bindings.node'
host.ioBroker	2022-02-14 16:19:49.560	error	Caught by controller[0]: ^
host.ioBroker	2022-02-14 16:19:49.559	error	Caught by controller[0]: throw e;
host.ioBroker	2022-02-14 16:19:49.559	error	Caught by controller[0]: /opt/iobroker/node_modules/bindings/bindings.js:121

Gibts da noch ein Problem?

[apollon77]

@coyote Jetzt zeit mal mehr Log ... also an sich sollte er nach der Meldung sagen "Adapter needs rebuild" und dann rebuild machen und dann den adapter neu starten der da gecrasht ist ... Wenn es tat ist rum. Wenn nicht versucht er nochmal gezielter zu rebuilden. Also was genau sagt denn das log so?

Und ja bei Ble ist es bekannt ... da haben wir bei einem noble-Modul einen PR offen ... dann geht das in Zukunft vllt besser