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

[oFbEQnpoLKKl6mbY5e13]

@apollon77

Nachdem ich ja bereits auf JS-Controller 4.0.15 war. Musste ich heute leider wegen eines Problems ein Restore vom Vortag machen. Also habe ich JS-Controller 4.0.12 installiert und ein Restore gestartet. Leider kann VIS nun nicht mehr installiert werden:

2022-02-21 10:17:49.796  - info: host.iobroker iobroker.js-controller version 4.0.12 js-controller starting
2022-02-21 10:17:49.804  - info: host.iobroker Copyright (c) 2014-2022 bluefox, 2014 hobbyquaker
2022-02-21 10:17:49.805  - info: host.iobroker hostname: iobroker, node: v14.19.0
2022-02-21 10:17:49.807  - info: host.iobroker ip addresses: 192.168.0.202
2022-02-21 10:17:50.127  - info: host.iobroker connected to Objects and States
2022-02-21 10:17:50.157  - info: host.iobroker added notifications configuration of host
2022-02-21 10:17:50.271  - info: host.iobroker Plugin sentry Sentry Plugin disabled for this process because sending of statistic data is disabled for the system
2022-02-21 10:17:50.334  - info: host.iobroker 54 instances found
2022-02-21 10:17:50.441  - info: host.iobroker starting 2 instances
2022-02-21 10:17:50.470  - warn: host.iobroker startInstance cannot find adapter "vis@1.4.12". Try to install it... 1 attempt
2022-02-21 10:17:50.472  - info: host.iobroker iobroker install vis@1.4.12 using installedFrom
2022-02-21 10:17:50.680  - info: host.iobroker instance system.adapter.admin.0 started with pid 1259
2022-02-21 10:17:52.878  - info: admin.0 (1259) Plugin sentry Sentry Plugin disabled for this process because sending of statistic data is disabled for the system
2022-02-21 10:17:52.988  - info: admin.0 (1259) starting. Version 5.3.0 in /opt/iobroker/node_modules/iobroker.admin, node: v14.19.0, js-controller: 4.0.12
2022-02-21 10:17:53.049  - info: admin.0 (1259) requesting all states
2022-02-21 10:17:53.050  - info: admin.0 (1259) requesting all objects
2022-02-21 10:17:53.494  - info: admin.0 (1259) Request actual repository...
2022-02-21 10:17:54.165  - info: host.iobroker Updating repository "beta" under "http://download.iobroker.net/sources-dist-latest.json"
2022-02-21 10:17:54.350  - info: host.iobroker iobroker npm-install: NPM version: 6.14.16Installing iobroker.vis@1.4.12... (System call)
2022-02-21 10:17:54.704  - info: host.iobroker instance system.adapter.backitup.0 started with pid 1305
2022-02-21 10:17:56.967  - info: admin.0 (1259) received all objects
2022-02-21 10:17:58.206  - info: admin.0 (1259) Repository received successfully.
2022-02-21 10:17:58.305  - info: admin.0 (1259) http server listening on port 8081
2022-02-21 10:17:58.306  - info: admin.0 (1259) Use link "http://localhost:8081" to configure.
2022-02-21 10:17:58.927  - info: backitup.0 (1305) starting. Version 2.3.3 in /opt/iobroker/node_modules/iobroker.backitup, node: v14.19.0, js-controller: 4.0.12
2022-02-21 10:17:59.067  - info: backitup.0 (1305) [iobroker] backup was activated at 02:00 every 1 day(s)
2022-02-21 10:17:59.159  - info: backitup.0 (1305) [ccu] backup was activated at 01:30 every 3 day(s)
2022-02-21 10:18:49.379  - info: host.iobroker iobroker npm-install: 
2022-02-21 10:18:49.382  - info: host.iobroker iobroker npm-install: > iobroker.vis@1.4.12 install /opt/iobroker/node_modules/iobroker.vis> node main.js --install
2022-02-21 10:18:51.086  - info: vis.0 (1361) starting. Version 1.4.12 in /opt/iobroker/node_modules/iobroker.vis, node: v14.19.0, js-controller: 4.0.12
2022-02-21 10:18:51.281  - info: vis.0 (1361) vis license is OK.
2022-02-21 10:18:51.851  - error: vis.0 (1361) 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-21 10:18:51.853  - error: vis.0 (1361) unhandled promise rejection: Not exists
2022-02-21 10:18:51.857  - error: vis.0 (1361) Error: Not exists
    at Object.maybeCallbackWithError (/opt/iobroker/node_modules/@iobroker/js-controller-common/lib/common/tools.js:2961:17)
    at ObjectsInRedisClient._readFile (/opt/iobroker/node_modules/@iobroker/db-objects-redis/lib/objects/objectsInRedisClient.js:1042:26)
    at Immediate.<anonymous> (/opt/iobroker/node_modules/@iobroker/db-objects-redis/lib/objects/objectsInRedisClient.js:1091:29)
    at processImmediate (internal/timers.js:466:21)
2022-02-21 10:18:51.858  - error: vis.0 (1361) Not exists
2022-02-21 10:18:52.364  - info: vis.0 (1361) terminating
2022-02-21 10:18:52.366  - warn: vis.0 (1361) Terminated (UNCAUGHT_EXCEPTION): Without reason

Was kann ich tun?

[apollon77]

@ofbeqnpolkkl6mby5e13 Versuch mal vis downgrade auf 4.0.5 und danach wieder hoch auf 4.0.12 Und bitte mit dem Log ein Vis issue anlegen

[oFbEQnpoLKKl6mbY5e13]

@apollon77
JS-Controller oder vis?

[apollon77]

@ofbeqnpolkkl6mby5e13 Issue bei vis bitte

[oFbEQnpoLKKl6mbY5e13]

@apollon77

Das behebt das Problem nicht:

2022-02-21 10:40:38.147  - info: host.iobroker "system.adapter.vis.0" enabled
2022-02-21 10:40:39.910  - info: vis.0 (2892) starting. Version 1.4.5 in /opt/iobroker/node_modules/iobroker.vis, node: v14.19.0, js-controller: 4.0.12
2022-02-21 10:40:40.184  - info: vis.0 (2892) vis license is OK.
2022-02-21 10:40:40.242  - error: vis.0 (2892) uncaught exception: EACCES: permission denied, open '/opt/iobroker/node_modules/iobroker.vis/www/widgets/fritzdect/css/style.css'
2022-02-21 10:40:40.245  - error: vis.0 (2892) Error: EACCES: permission denied, open '/opt/iobroker/node_modules/iobroker.vis/www/widgets/fritzdect/css/style.css'
    at Object.openSync (fs.js:497:3)
    at Object.writeFileSync (fs.js:1528:35)
    at copyFileSync (/opt/iobroker/node_modules/iobroker.vis/lib/install.js:16:8)
    at /opt/iobroker/node_modules/iobroker.vis/lib/install.js:36:17
    at Array.forEach (<anonymous>)
    at copyFolderRecursiveSync (/opt/iobroker/node_modules/iobroker.vis/lib/install.js:31:15)
    at /opt/iobroker/node_modules/iobroker.vis/lib/install.js:34:17
    at Array.forEach (<anonymous>)
    at copyFolderRecursiveSync (/opt/iobroker/node_modules/iobroker.vis/lib/install.js:31:15)
    at /opt/iobroker/node_modules/iobroker.vis/lib/install.js:34:17
2022-02-21 10:40:40.246  - error: vis.0 (2892) Exception-Code: EACCES: EACCES: permission denied, open '/opt/iobroker/node_modules/iobroker.vis/www/widgets/fritzdect/css/style.css'
2022-02-21 10:40:40.762  - info: vis.0 (2892) terminating
2022-02-21 10:40:40.764  - warn: vis.0 (2892) Terminated (UNCAUGHT_EXCEPTION): Without reason
2022-02-21 10:40:41.371  - info: host.iobroker instance system.adapter.vis.0 terminated while should be started once

Ich führe mal ein iobroker fix durch.

Nach iobroker fix:

2022-02-21 10:51:21.640  - info: vis.0 (1348) starting. Version 1.4.5 in /opt/iobroker/node_modules/iobroker.vis, node: v14.19.0, js-controller: 4.0.12
2022-02-21 10:51:21.904  - info: vis.0 (1348) vis license is OK.
2022-02-21 10:51:23.094  - info: vis.0 (1348) Terminated (NO_ERROR): Without reason
2022-02-21 10:51:23.691  - info: host.iobroker instance system.adapter.vis.0 terminated while should be started once

Danach wieder hoch zu 1.4.12. Jetzt läuft vis wieder. Nur hätte ich mir das alles sparen können, weil mein ursprüngliches Problem weiterhin existiert...

[Thomas Braun]

@ofbeqnpolkkl6mby5e13

Wie sehen die Rechte denn an

/opt/iobroker/node_modules/iobroker.vis/www/widgets/fritzdect/css/style.css

aus?

[oFbEQnpoLKKl6mbY5e13]

@thomas-braun
Wie gesagt, das Problem konnte durch iobroker fix behoben werden.

# ls -l /opt/iobroker/node_modules/iobroker.vis/www/widgets/fritzdect/css/style.css
-rw-rw-rw- 1 iobroker iobroker 70 Feb 21 11:05 /opt/iobroker/node_modules/iobroker.vis/www/widgets/fritzdect/css/style.css

[saeft_2003]

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

@saeft_2003 naja mit 630MB frei ist dann die Frage wie gross denn so das Backup ist wenn es tut? bzw wieviele Daten sind es denn die da gesichert werden

Das backup hat 90MB.

edit: ich habe ohne den ram zu erhöhen ein Backup manuell gestartet das lief ohne Probleme durch.

[apollon77]

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

Nur hätte ich mir das alles sparen können, weil mein ursprüngliches Problem weiterhin existiert...

??

[oFbEQnpoLKKl6mbY5e13]

@apollon77

Restore mit VIS v1.4.12 geht mindestens bei mir nicht und hat mit JS-Controller nichts zu tun. Das Problem, weshalb ich auf einen älteren Stand wollte, ist aber Off-Topic.

Danke für die Hilfe!

[apollon77]

@saeft_2003 Kannst du mir mal einen gefallen tun?

Mach mal eine Shell auf mit "top", dann drücke "Shift-M" - das sortiert dir die EInträge nach RAM nutzung. Dnan am besten davon nen Screenshot

Jetzt bitte mal zwei Dinge tun:
1.) in einer zweiten Shell ein "iob backup" ausführen
2.) Backup über BackItUp manuell starten

Und bei beiden Dingen bitte mal das "top" beobachten ... Wo steigt der RAM Verbrauch an und wenn ja in welchem prozess?

Vllt kommen wir so ein bissl dahinter

[Marty56]

@apollon77
Ich habe meine zweite iobroker Instanz (Raspberry 3) ohne Probleme umgestellt.
Dort hatte ich früher die States auf redis umgestellt und würde jetzt gern auf JSONL migrieren.

iobroker status
iobroker is running on this host.


Objects type: jsonl
States  type: redis

Ist das unproblematisch?

[apollon77]

@marty56 Na klar, einfach per iob setup custom machen

[Marty56]

@apollon77 Danke. Hat unproblematisch geklappt.

[e-i-k-e]

Aktualisiere gerade ein Slave von 4.0.10 auf 4.0.15.
Habe es nun bereits mit sudo -H -u iobroker npm install iobroker.js-controller versucht.

Bei den anderen Geräten hatte ich dieses Problem nicht.
Was kann ich tun?

pi@raspberrypi-display2:/opt/iobroker $ sudo -H -u iobroker npm install iobroker.js-controller

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

NPM version: 6.14.16

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

No connection to objects 192.168.3.100:6379[redis]
/opt/iobroker/node_modules/standard-as-callback/built/index.js:6
        throw e;
        ^

Error: Connection is closed.
    at close (/opt/iobroker/node_modules/ioredis/built/redis/event_handler.js:184:25)
    at Socket.<anonymous> (/opt/iobroker/node_modules/ioredis/built/redis/event_handler.js:151:20)
    at Object.onceWrapper (events.js:520:26)
    at Socket.emit (events.js:400:28)
    at TCP.<anonymous> (net.js:686:12)
Emitted 'error' event on ScanStream instance at:
    at /opt/iobroker/node_modules/ioredis/built/ScanStream.js:38:22
    at tryCatcher (/opt/iobroker/node_modules/standard-as-callback/built/utils.js:12:23)
    at /opt/iobroker/node_modules/standard-as-callback/built/index.js:33:51
    at processTicksAndRejections (internal/process/task_queues.js:95:5)
npm WARN optional SKIPPING OPTIONAL DEPENDENCY: fsevents@~2.3.2 (node_modules/chokidar/node_modules/fsevents):
npm WARN notsup SKIPPING OPTIONAL DEPENDENCY: Unsupported platform for fsevents@2.3.2: wanted {"os":"darwin","arch":"any"} (current: {"os":"linux","arch":"arm"})

npm ERR! code ELIFECYCLE
npm ERR! errno 1
npm ERR! iobroker.js-controller@4.0.10 install: `node iobroker.js setup first`
npm ERR! Exit status 1
npm ERR!
npm ERR! Failed at the iobroker.js-controller@4.0.10 install script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.

npm ERR! A complete log of this run can be found in:
npm ERR!     /home/iobroker/.npm/_logs/2022-02-21T20_58_28_730Z-debug.l

Keine Ahnung was jetzt los ist.. :astonished:

pi@raspberrypi-display2:~ $ iobroker stop
pi@raspberrypi-display2:~ $ iobroker fix
library: loaded
Library version=2022-02-10
ioBroker is not installed in /opt/iobroker! Cannot fix anything...

[apollon77]

@e-i-k-e Wie geht es dem Redis? Steht ggf etwas im Redis log? Ich hab das gefühl das sich der beendet mittendrin.

[e-i-k-e]

@apollon77

Hast du zufällig den Pfad parat?

Ich habe den kompletten Container inkl. Reids mal neu gestartet. Keine Änderung.

[Thomas Braun]

@e-i-k-e

less -R /var/log/redis/redis-server.log

und

systemctl status redis

anschauen.

[Diginix]

@AlCalzone @apollon77
Hab eben per CLI admin, web und ws aktualisiert und dann ist der js-controller mal wieder RAM technisch eskaliert mit über 2 GB. Hat sich dann aber ohne mein Zutun wieder stabilisiert. Wahrs. reichten die 6 GB RAM der VM gerade so aus.

              total        used        free      shared  buff/cache   available
Mem:           5941        4407         131           1        1402        1242
Swap:             0           0           0

[e-i-k-e]

@thomas-braun

Danke.

Im Log steht immer das gleiche, nichts auffälliges.

11670:C 20 Feb 2022 22:13:49.210 * DB saved on disk
11670:C 20 Feb 2022 22:13:49.228 * RDB: 7 MB of memory used by copy-on-write
139:M 20 Feb 2022 22:13:49.345 * Background saving terminated with success
139:M 20 Feb 2022 22:15:49.680 * 10000 changes in 60 seconds. Saving...
139:M 20 Feb 2022 22:15:49.698 * Background saving started by pid 11703
11703:C 20 Feb 2022 22:16:04.583 * DB saved on disk
11703:C 20 Feb 2022 22:16:04.601 * RDB: 14 MB of memory used by copy-on-write
139:M 20 Feb 2022 22:16:04.688 * Background saving terminated with success
139:M 20 Feb 2022 22:18:21.814 * 10000 changes in 60 seconds. Saving...

pi@ioBroker-Rock:~$ systemctl status redis
* redis-server.service - Advanced key-value store
     Loaded: loaded (/lib/systemd/system/redis-server.service; enabled; vendor preset: enabled)
     Active: active (running) since Mon 2022-02-21 22:27:17 CET; 46min ago
       Docs: http://redis.io/documentation,
             man:redis-server(1)
    Process: 114 ExecStart=/usr/bin/redis-server /etc/redis/redis.conf (code=exited, status=0/SUCCESS)
   Main PID: 135 (redis-server)
      Tasks: 4 (limit: 18952)
     Memory: 1.7G
        CPU: 6min 39.212s
     CGroup: /system.slice/redis-server.service
             `-135 /usr/bin/redis-server 0.0.0.0:6379

Fünf weitere Slave laufen aber auch problemlos mit dem Master und der Redis DB.