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

Stuebi

@Stuebi sagte in [Aufruf] js-controller 2.0 Beta Test:

Ich habe eben das update auf den js-controller 2.0 Beta auf meinem Entwicklerrechner (macbook, nodejs 10) durchgeführt. Seit dem wird im Visual Studio Code (VSC) kein Logging im Debug Modus angezeigt. Jemand eine Idee was ich dort ändern muss.
....

VG
Stübi

Ich habe jetzt eine Lösung gefunden damit das Logfile im Debugging Modus im Visual Studio Code (VSC) bei dem js-controller >= 2 wieder angezeigt wird. In der launch.json im .vscode Verzeichnis ist der Eintrag "outputCapture": "std" hinzuzufügen. Damit wird über STDOUT im Terminal des VSC angezeigt. Die Args sind

Beispiel:

{
    "configurations": [
        {
            "type": "node",
            "request": "launch",
            "name": "Programm starten",
            "program": "${workspaceFolder}/shelly.js",
            "cwd": "${workspaceRoot}",
            "args": [
                "--debug", "--force", "--logs", "--trace-warnings"
            ],
            "outputCapture": "std"
        }
    ]
}

Debugging Output:

VG
Stübi

apollon77

Hey an alle Tester,

hier mal die Zusammenfassung der Themen die ich gerade so auf dem Radar habe. Bitte schaut mal ob das passt oder was fehlt!

Performance 2.0 bei file/file (Thema "daswetter") @SBorg hast du mal ne Laufzeit des adapters von der 1.5 ... würde gern mal was vergleichen?)
kein IPv4 verfügbar bei start/boot und controller bleibt trotz restarts dabei das IPv4 noch nicht geht (EHOSTUNREACH). Mit nem Delay nach dem Start tuts. 1 Bericht mit RPi 4
Mit "redis/redis" und vielen States (ca >25.000) laufen ggf LUA Skripte zu lange und blockieren den Redis
VSCode und Adapter-Debugging - Console Ausgaben werden nicht sauber durchgeschliffen, Workaround verfügbar
vis-justgage Beschriftung im Browser "null" anstelle Text, Liegt daran das jetzt ggf null anstelle "undefined" zurückkommt. Müssen klären ob Widgets oder vis das fixen sollte. Könnte andere Widgets auch betreffen
auto restart des controller-prozesses tut nicht (1 Bericht)
(kein Problem) shelly Adapter funktioniert nicht mehr, 1 Bericht -- Reboot hats behoben

Ist das vollständig?

Ansonsten habe ich eine Bitte an Euch, die Ihr ja jetzt viel getestet habt. Lest mit Eurem heutigen Wissen bitte nochmal die Anleitung/FAQ oben durch und überlegt was fehlt bzw was euch geholfen hätte bzw was falsch/unvollständig ist. Ich würde auch gern parallel anfangen das oben für den latest Release vorzubereiten.

darkiop

Hi Ingo,
sorry hatte gestern leider keine Zeit. Bei mir noch folgende Dinge:

IPv4 beim Start des Slaves, keine Verbindung zum Master
Wenn ioBroker Master beendet wird schafft es der Slave nicht mehr sich zu verbinden
Skript Download package.zip - javascript-Adapter
Jede Nacht um 3:58 verliert der Slave die Verbindung zum Master und schafft es nicht mehr sich zu verbinden. Siehe Log am Ende. Im Log des Masters gibt es um 03:58 leider nichts.

Vermutlich haben 1,2 und 3 die selbe Ursache (außer das was um 3:58 passiert, das habe ich nocht nicht gefunden - Skript-Trigger und Instanz Restarts habe ich berreits durch).

Aktuell laufen beide auf 2.0.22.

Bin nun allerdings bis heut Abend unterwegs.

Danke + Grüße

cat iobroker.2019-10-03.log
2019-10-03 03:57:31.992  - warn: host.iobroker-hwr Slave controller detected disconnection. Stop all instances.
2019-10-03 03:57:32.000  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-03 03:57:32.001  - info: host.iobroker-hwr stopInstance forced system.adapter.smartmeter.0 killing pid 1272
2019-10-03 03:57:32.002  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-03 03:57:32.003  - info: host.iobroker-hwr stopInstance forced system.adapter.smartmeter.1 killing pid 1288
2019-10-03 03:57:32.005  - warn: smartmeter.0 (1272) ERROR CLOSING SERIALPORT
2019-10-03 03:57:32.007  - warn: smartmeter.1 (1288) ERROR CLOSING SERIALPORT
2019-10-03 03:57:33.493  - warn: smartmeter.1 (1288) Cannot connect/reconnect to objects DB. Terminating
2019-10-03 03:57:33.494  - warn: smartmeter.0 (1272) Cannot connect/reconnect to objects DB. Terminating
2019-10-03 03:57:43.997  - warn: host.iobroker-hwr Slave controller detected disconnection. Stop all instances.
2019-10-03 03:57:44.002  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-03 03:57:44.002  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-03 03:57:54.494  - error: host.iobroker-hwr connect ETIMEDOUT
2019-10-03 03:57:54.496  - error: host.iobroker-hwr Error: connect ETIMEDOUT
    at Socket.<anonymous> (/opt/iobroker/node_modules/ioredis/built/redis/index.js:282:31)
    at Object.onceWrapper (events.js:286:20)
    at Socket.emit (events.js:198:13)
    at Socket._onTimeout (net.js:442:8)
    at ontimeout (timers.js:436:11)
    at tryOnTimeout (timers.js:300:5)
    at listOnTimeout (timers.js:263:5)
    at Timer.processTimers (timers.js:223:10)
2019-10-03 03:58:02.006  - info: host.iobroker-hwr iobroker _restart
2019-10-03 03:58:02.514  - info: host.iobroker-hwr iobroker Starting node restart.js



cat iobroker.2019-10-04.log
2019-10-04 03:58:16.868  - warn: host.iobroker-hwr Slave controller detected disconnection. Stop all instances.
2019-10-04 03:58:16.874  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-04 03:58:16.875  - info: host.iobroker-hwr stopInstance forced system.adapter.smartmeter.0 killing pid 985
2019-10-04 03:58:16.876  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-04 03:58:16.877  - info: host.iobroker-hwr stopInstance forced system.adapter.smartmeter.1 killing pid 1005
2019-10-04 03:58:16.880  - warn: smartmeter.0 (985) ERROR CLOSING SERIALPORT
2019-10-04 03:58:16.881  - warn: smartmeter.1 (1005) ERROR CLOSING SERIALPORT
2019-10-04 03:58:18.369  - warn: smartmeter.1 (1005) Cannot connect/reconnect to objects DB. Terminating
2019-10-04 03:58:18.376  - warn: smartmeter.0 (985) Cannot connect/reconnect to objects DB. Terminating
2019-10-04 03:58:24.983  - warn: host.iobroker-hwr Slave controller detected disconnection. Stop all instances.
2019-10-04 03:58:24.984  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-04 03:58:24.984  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-04 03:58:27.980  - warn: host.iobroker-hwr Slave controller detected disconnection. Stop all instances.
2019-10-04 03:58:27.981  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-04 03:58:27.981  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-04 03:58:30.481  - error: host.iobroker-hwr connect EHOSTUNREACH 192.168.1.82:9000
2019-10-04 03:58:30.482  - error: host.iobroker-hwr Error: connect EHOSTUNREACH 192.168.1.82:9000
    at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1106:14)
2019-10-04 03:58:32.980  - warn: host.iobroker-hwr Slave controller detected disconnection. Stop all instances.
2019-10-04 03:58:32.981  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-04 03:58:32.981  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-04 03:58:45.487  - error: host.iobroker-hwr connect ECONNREFUSED 192.168.1.82:9000
2019-10-04 03:58:45.487  - error: host.iobroker-hwr Error: connect ECONNREFUSED 192.168.1.82:9000
    at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1106:14)
2019-10-04 03:58:46.879  - info: host.iobroker-hwr iobroker _restart
2019-10-04 03:58:47.391  - info: host.iobroker-hwr iobroker Starting node restart.js
2019-10-04 05:43:08.886  - info: host.iobroker-hwr iobroker.js-controller version 2.0.19 js-controller starting
2019-10-04 05:43:08.893  - info: host.iobroker-hwr Copyright (c) 2014-2019 bluefox, 2014 hobbyquaker
2019-10-04 05:43:08.894  - info: host.iobroker-hwr hostname: iobroker-hwr, node: v10.16.3
2019-10-04 05:43:08.896  - info: host.iobroker-hwr ip addresses: 10.3.1.22 fe80::dea6:32ff:fe17:78f5
2019-10-04 05:43:09.113  - info: host.iobroker-hwr connected to Objects and States
2019-10-04 05:43:09.153  - warn: host.iobroker-hwr Multihost discovery server: service started on 0.0.0.0:50005
2019-10-04 05:43:10.178  - info: host.iobroker-hwr 85 instances found
2019-10-04 05:43:10.195  - info: host.iobroker-hwr starting 2 instances
2019-10-04 05:43:10.213  - info: host.iobroker-hwr instance system.adapter.smartmeter.0 started with pid 2855
2019-10-04 05:43:14.213  - info: host.iobroker-hwr instance system.adapter.smartmeter.1 started with pid 2870
2019-10-04 06:14:38.153  - info: host.iobroker-hwr received SIGTERM
2019-10-04 06:14:38.157  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-04 06:14:38.157  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-04 06:14:38.179  - warn: smartmeter.0 (2855) ERROR CLOSING SERIALPORT
2019-10-04 06:14:38.182  - warn: smartmeter.1 (2870) ERROR CLOSING SERIALPORT
2019-10-04 06:14:38.217  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0 send kill signal
2019-10-04 06:14:38.218  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1 send kill signal
2019-10-04 06:14:38.993  - info: host.iobroker-hwr instance system.adapter.smartmeter.1 terminated with code 156 (156)
2019-10-04 06:14:39.096  - info: host.iobroker-hwr instance system.adapter.smartmeter.0 terminated with code 156 (156)
2019-10-04 06:14:39.096  - info: host.iobroker-hwr All instances are stopped.
2019-10-04 06:14:39.357  - info: host.iobroker-hwr terminated
2019-10-04 06:26:49.496  - info: host.iobroker-hwr iobroker.js-controller version 2.0.22 js-controller starting
2019-10-04 06:26:49.508  - info: host.iobroker-hwr Copyright (c) 2014-2019 bluefox, 2014 hobbyquaker
2019-10-04 06:26:49.509  - info: host.iobroker-hwr hostname: iobroker-hwr, node: v10.16.3
2019-10-04 06:26:49.512  - info: host.iobroker-hwr ip addresses: 10.3.1.22 fe80::dea6:32ff:fe17:78f5
2019-10-04 06:26:50.124  - info: host.iobroker-hwr connected to Objects and States
2019-10-04 06:26:50.169  - warn: host.iobroker-hwr Multihost discovery server: service started on 0.0.0.0:50005
2019-10-04 06:26:51.006  - info: host.iobroker-hwr Delete state "system.host.iobroker-hwr.compactModeEnabled"
2019-10-04 06:26:51.206  - info: host.iobroker-hwr 85 instances found
2019-10-04 06:26:51.227  - info: host.iobroker-hwr starting 2 instances
2019-10-04 06:26:51.252  - info: host.iobroker-hwr instance system.adapter.smartmeter.0 started with pid 982
2019-10-04 06:26:51.276  - info: host.iobroker-hwr Some obsolete host states deleted.
2019-10-04 06:26:55.235  - info: host.iobroker-hwr instance system.adapter.smartmeter.1 started with pid 1001
2019-10-04 06:48:37.054  - info: host.iobroker-hwr Update repository "latest" under "http://download.iobroker.net/sources-dist-latest.json"
 
 

cat iobroker.2019-10-05.log
2019-10-05 03:58:12.569  - warn: host.iobroker-hwr Slave controller detected disconnection. Stop all instances.
2019-10-05 03:58:12.575  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-05 03:58:12.576  - info: host.iobroker-hwr stopInstance forced system.adapter.smartmeter.0 killing pid 982
2019-10-05 03:58:12.577  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-05 03:58:12.578  - info: host.iobroker-hwr stopInstance forced system.adapter.smartmeter.1 killing pid 1001
2019-10-05 03:58:12.583  - warn: smartmeter.1 (1001) ERROR CLOSING SERIALPORT
2019-10-05 03:58:14.074  - warn: smartmeter.1 (1001) Cannot connect/reconnect to objects DB. Terminating
2019-10-05 03:58:24.575  - warn: host.iobroker-hwr Slave controller detected disconnection. Stop all instances.
2019-10-05 03:58:24.576  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-05 03:58:24.577  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-05 03:58:25.394  - error: host.iobroker-hwr connect EHOSTUNREACH 192.168.1.82:9000
2019-10-05 03:58:25.395  - error: host.iobroker-hwr Error: connect EHOSTUNREACH 192.168.1.82:9000
    at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1106:14)
2019-10-05 03:58:29.897  - warn: host.iobroker-hwr Slave controller detected disconnection. Stop all instances.
2019-10-05 03:58:29.898  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.0
2019-10-05 03:58:29.899  - info: host.iobroker-hwr stopInstance system.adapter.smartmeter.1
2019-10-05 03:58:40.399  - error: host.iobroker-hwr connect ECONNREFUSED 192.168.1.82:9000
2019-10-05 03:58:40.399  - error: host.iobroker-hwr Error: connect ECONNREFUSED 192.168.1.82:9000
    at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1106:14)
2019-10-05 03:58:42.581  - info: host.iobroker-hwr iobroker _restart
2019-10-05 03:58:42.777  - error: host.iobroker-hwr iobroker path.js:1077        path = process.cwd();                       ^Error: ENOENT: no such file or directory, uv_cwd    at Object.resolve (path.js:1077:24)    at Function.Module._resolveLookupPaths (internal/modules/cjs/loader.js:534:17)    at Function.Module._resolveFilename (internal/modules/cjs/loader.js:629:20)    at Function.Module._load (internal/modules/cjs/loader.js:562:25)    at Module.require (internal/modules/cjs/loader.js:692:17)    at require (internal/modules/cjs/helpers.js:25:18)    at Object.<anonymous> (/opt/iobroker/node_modules/iobroker.js-controller/iobroker.js:1:1)    at Module._compile (internal/modules/cjs/loader.js:778:30)    at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10)    at Module.load (internal/modules/cjs/loader.js:653:32)
2019-10-05 03:58:42.780  - info: host.iobroker-hwr iobroker exit 1
2019-10-05 07:10:31.915  - info: host.iobroker-hwr iobroker.js-controller version 2.0.22 js-controller starting
2019-10-05 07:10:31.922  - info: host.iobroker-hwr Copyright (c) 2014-2019 bluefox, 2014 hobbyquaker
2019-10-05 07:10:31.923  - info: host.iobroker-hwr hostname: iobroker-hwr, node: v10.16.3
2019-10-05 07:10:31.925  - info: host.iobroker-hwr ip addresses: 10.3.1.22 fe80::dea6:32ff:fe17:78f5
2019-10-05 07:10:32.147  - info: host.iobroker-hwr connected to Objects and States
2019-10-05 07:10:32.187  - warn: host.iobroker-hwr Multihost discovery server: service started on 0.0.0.0:50005
2019-10-05 07:10:33.291  - info: host.iobroker-hwr 85 instances found
2019-10-05 07:10:33.315  - info: host.iobroker-hwr starting 2 instances
2019-10-05 07:10:33.336  - info: host.iobroker-hwr instance system.adapter.smartmeter.0 started with pid 12375
2019-10-05 07:10:37.326  - info: host.iobroker-hwr instance system.adapter.smartmeter.1 started with pid 12418

SBorg

Performance bei der 1.5.14 bei Aktivierung der ersten drei APIs liegt so zwischen 25-30 Sekunden.
Mit der 2.0.22 ~180 Sekunden, gelegentlich auch über meinem derzeitigen Timeout von 300 Sekunden. Dann stockt auch die Verarbeitung weiterer Javascripts die da ev. laufen möchten.

...und woran auch immer es liegt: Node-RED Dashboard will einfach nicht

apollon77

@SBorg Ok, Dashboard wie gesagt tut bei mir und anderen ... ich weiss nicht hilfreich für dich, aber ürde ich gerade nicht dem controller anlasten

apollon77 · Aktuell laufen beide auf 2.0.22.

@darkiop Ooohk,

dein 1. ist in meiner Liste
dein 2. jetzt auch (bei dir tut der restart nicht ... können wir gern tief rein "diggen"
1. sollte seit 2.0.22 gefixt sein
  Dann zu 4. ... liefert /var/log/syslog irgendwas? Einerseits spielt da der nicht funktio0nierende restart rein, aber auch hier ist jetzt die neue Fehlermeldung interessant:

2019-10-05 03:58:42.777 - error: host.iobroker-hwr iobroker path.js:1077 path = process.cwd(); ^Error: ENOENT: no such file or directory, uv_cwd at Object.resolve (path.js:1077:24) at Function.Module._resolveLookupPaths (internal/modules/cjs/loader.js:534:17) at Function.Module._resolveFilename (internal/modules/cjs/loader.js:629:20) at Function.Module._load (internal/modules/cjs/loader.js:562:25) at Module.require (internal/modules/cjs/loader.js:692:17) at require (internal/modules/cjs/helpers.js:25:18) at Object.<anonymous> (/opt/iobroker/node_modules/iobroker.js-controller/iobroker.js:1:1) at Module._compile (internal/modules/cjs/loader.js:778:30) at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) at Module.load (internal/modules/cjs/loader.js:653:32)

Wie geht es denn der SD Karte so? Dieser Fehler heisst an sich das das Verzeichnis weg ist ... und damit wird merkwürdig. Deshalb schau mal zu der Zeit bitte in /var/log/syslog
Ansonsten wäre ist bei: Filesystem check, Netzteil checken/tauschen? ...

apollon77 · Aktuell laufen beide auf 2.0.22.

@darkiop So, dann wollen wir mal.
An sich ist die Logik so (du hast ja jetzt sytemd und nicht mehr init.d) das systemd selbst den restart macht wenn der controller mit einem Exitcode != 0 sich beendet.

Also man nehme ein System wo iobroker per systemd gestartet ist. Wenn du jetzt den js-controller-Prozess killst (ps auxww|grep js-controller -... dann kill prozessid) merkt systemd das und startet Ihn neu.

Bei mir im /var/log/syslog sieht das dann so aus:

Oct  5 12:42:34 io-test systemd[1]: iobroker.service: Main process exited, code=exited, status=1/FAILURE
Oct  5 12:42:44 io-test systemd[1]: iobroker.service: Failed with result 'exit-code'.
Oct  5 12:42:45 io-test systemd[1]: iobroker.service: Service hold-off time over, scheduling restart.
Oct  5 12:42:45 io-test systemd[1]: iobroker.service: Scheduled restart job, restart counter is at 1.
Oct  5 12:42:45 io-test systemd[1]: Stopped ioBroker Server.
Oct  5 12:42:45 io-test systemd[1]: Started ioBroker Server.
... startet wieder ...

Fangen wir mal damit an ob das bei dir korrekt tut.

darkiop

@apollon77 ich mach heut Abend nochmal ne Test Session. Die Karte ist eigentlich neu, prüfe sie aber. Alternativ hab ich auch Ersatz da.

Das mit dem Skript Download habe ich auch bei der 2.0.22. geh heute Abend nochmals mit beiden zurück auf die 1.5 und teste da.

sven12

@apollon77
@Stuebi
Ich habe den shelly Adapter mal deinstalliert.
Eine erneute installation brachte folgenden Fehler zu Tage...

2019-10-05 13:20:51.118  - info: host.iobroker-host iobroker host.iobroker-host install adapter shelly
2019-10-05 13:20:51.133  - info: host.iobroker-host iobroker upload [2] shelly.admin /opt/iobroker/node_modules/iobroker.shelly/admin/words.js words.js application/javascript
2019-10-05 13:20:51.217  - info: host.iobroker-host iobroker upload [1] shelly.admin /opt/iobroker/node_modules/iobroker.shelly/admin/shelly.png shelly.png image/png
2019-10-05 13:20:51.272  - info: host.iobroker-host iobroker upload [0] shelly.admin /opt/iobroker/node_modules/iobroker.shelly/admin/index_m.html index_m.html text/html
2019-10-05 13:20:51.347  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly created/updated
2019-10-05 13:20:51.503  - info: host.iobroker-host iobroker host.iobroker-host create instance shelly
2019-10-05 13:20:51.508  - info: host.iobroker-host iobroker host.iobroker-host object shelly.0.info.connection created
2019-10-05 13:20:51.537  - info: host.iobroker-host iobroker host.iobroker-host object shelly.0.info created
2019-10-05 13:20:51.568  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.sigKill created
2019-10-05 13:20:51.602  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.eventLoopLag created
2019-10-05 13:20:51.633  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.outputCount created
2019-10-05 13:20:51.664  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.inputCount created
2019-10-05 13:20:51.695  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.uptime created
2019-10-05 13:20:51.725  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.memRss created
2019-10-05 13:20:51.756  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.memHeapTotal created
2019-10-05 13:20:51.805  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.memHeapUsed created
2019-10-05 13:20:51.864  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.cputime created
2019-10-05 13:20:51.894  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.cpu created
2019-10-05 13:20:51.938  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.compactMode created
2019-10-05 13:20:51.968  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.connected created
2019-10-05 13:20:51.997  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0.alive created
2019-10-05 13:20:52.039  - info: host.iobroker-host iobroker host.iobroker-host object system.adapter.shelly.0 created
2019-10-05 13:20:52.045  - error: host.iobroker-host iobroker (node:19932) UnhandledPromiseRejectionWarning: Error: Connection is closed.    at close (/opt/iobroker/node_modules/ioredis/built/redis/event_handler.js:115:25)    at Socket.<anonymous> (/opt/iobroker/node_modules/ioredis/built/redis/event_handler.js:82:20)    at Object.onceWrapper (events.js:315:30)    at emitOne (events.js:116:13)    at Socket.emit (events.js:211:7)    at TCP._handle.close [as _onclose] (net.js:561:12)(node:19932) UnhandledPromiseRejectionWarning: 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(). (rejection id: 1)(node:19932) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.
2019-10-05 13:20:53.043  - info: host.iobroker-host iobroker exit 0

Hilft das weiter?

Uninstall

2019-10-05 13:24:46.024  - info: host.iobroker-host iobroker del shelly
2019-10-05 13:24:47.014  - info: host.iobroker-host iobroker Delete adapter "shelly"
2019-10-05 13:24:47.037  - info: host.iobroker-host iobroker host.iobroker-host Counted 1 instances of shelly
2019-10-05 13:24:47.042  - info: host.iobroker-host iobroker host.iobroker-host Counted 1 adapter for shelly
2019-10-05 13:24:47.060  - info: host.iobroker-host iobroker host.iobroker-host Counted 1 channels of shelly
2019-10-05 13:24:47.158  - info: host.iobroker-host iobroker host.iobroker-host Counted 14 states of shelly
2019-10-05 13:24:47.211  - info: host.iobroker-host iobroker host.iobroker-host Deleting 17 object(s).
2019-10-05 13:24:48.038  - info: host.iobroker-host object deleted system.adapter.shelly.0
2019-10-05 13:24:48.043  - info: host.iobroker-host iobroker npm uninstall iobroker.shelly --silent --save --prefix "/opt/iobroker" (System call)
2019-10-05 13:24:48.204  - error: host.iobroker-host Cannot write files: /opt/iobroker/iobroker-data/files/shelly.admin/_data.json: ENOENT: no such file or directory, open '/opt/iobroker/iobroker-data/files/shelly.admin/_data.json'
2019-10-05 13:25:00.031  - info: host.iobroker-host instance system.adapter.poolcontroller.0 started with pid 27732
2019-10-05 13:25:14.532  - info: host.iobroker-host iobroker exit 0

SBorg

@apollon77 sagte in [Aufruf] js-controller 2.0 Beta Test:

@SBorg Ok, Dashboard wie gesagt tut bei mir und anderen ... ich weiss nicht hilfreich für dich, aber ürde ich gerade nicht dem controller anlasten

Ist auch keinerlei Kritik und ich kann es auch nachvollziehen, gerade wenn man es selbst nicht provozieren kann. Ich weiß deinen Einsatz und Engagement auch zu würdigen :+1:
Ich weiß halt nicht wie viele Tester das Dashboard nutzen. Broadlink und DasWetter fielen ja auch zuerst nicht auf, außer bei mir ;) Irgendwie denke ich halt auch an das Release, gerade wenn es dann mehr User nutzen und es dann nicht geht...
Und nun natürlich noch das obligatorische a b e r...
Unter 1.5.14 und der Vor-Node-RED Version läuft das Dashboard. Nur mit der alten Red-Version kommt es ja zu Timing-Problemen mit dem JS 2.x. Da ich in der FireFox-Konsole Fehler mit socket.io (da gab es doch Änderungen?) habe beim aufrufen des Dashboards, tippe ich leider doch auf den JS-Controller?

apollon77

@sven12 beides ignore. Haben beide nichts zu sagen. Es halt alles geklappt.

apollon77

@SBorg dein Feedback und Tests sind auch super. Die Web Meldungen haben damit zu tun das der Browser Probleme hat mit dem Webserver den der node-red Prozess auf macht zu reden.
Das hat mit dem was der Controller da geändert hat nichts zu tun. Hast finden Browser Fehler bitte nochmal für mich? Mal danach gegoogelt?

lobomau

bei mir funktioniert der g-homa Adapter nicht mehr. Hat den jemand im Einsatz und hat Probleme seit js-controller 2.x?
Wenn ich anschalten will schaltet er gleich wieder aus. Dabei zappelt der VIS-Schalter (fancyswitch - Giva Labs iButton) paar mal hin- und her. Schaltbefehl über telegram geht auch nicht mehr und füht zum selben Problem. Ich habe auch dabei alle javascript Adapter ausgeschaltet um hier Fehler auszuschließen.
Log sagt:

g-homa.0	2019-10-05 14:52:23.574	debug	(3918) system.adapter.admin.1: logging true
g-homa.0	2019-10-05 14:51:29.908	debug	(3918) powerFactor in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:29.908	debug	(3918) power in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:29.907	debug	(3918) current in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:29.907	debug	(3918) voltage in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:29.810	debug	(3918) voltage != null: false
g-homa.0	2019-10-05 14:51:29.810	debug	(3918) power != null: false
g-homa.0	2019-10-05 14:51:29.810	debug	(3918) powerFactor != null: false
g-homa.0	2019-10-05 14:51:29.810	debug	(3918) current != null: false
g-homa.0	2019-10-05 14:51:29.809	debug	(3918) extendPlug: type = normal, energyMeasurement != null: true
g-homa.0	2019-10-05 14:51:29.806	debug	type: normal
g-homa.0	2019-10-05 14:51:29.806	debug	switched from: remote
g-homa.0	2019-10-05 14:51:29.806	debug	state: off
g-homa.0	2019-10-05 14:51:29.806	debug	(3918) Got updated info for Plug 4ce7ac:
g-homa.0	2019-10-05 14:51:28.616	debug	(3918) powerFactor in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:28.615	debug	(3918) power in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:28.615	debug	(3918) current in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:28.614	debug	(3918) voltage in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:28.553	debug	(3918) voltage != null: false
g-homa.0	2019-10-05 14:51:28.553	debug	(3918) power != null: false
g-homa.0	2019-10-05 14:51:28.552	debug	(3918) powerFactor != null: false
g-homa.0	2019-10-05 14:51:28.552	debug	(3918) current != null: false
g-homa.0	2019-10-05 14:51:28.552	debug	(3918) extendPlug: type = normal, energyMeasurement != null: true
g-homa.0	2019-10-05 14:51:28.549	debug	type: normal
g-homa.0	2019-10-05 14:51:28.549	debug	switched from: remote
g-homa.0	2019-10-05 14:51:28.549	debug	state: off
g-homa.0	2019-10-05 14:51:28.549	debug	(3918) Got updated info for Plug 4ce7ac:
g-homa.0	2019-10-05 14:51:28.500	debug	(3918) powerFactor in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:28.500	debug	(3918) power in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:28.499	debug	(3918) current in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:28.499	debug	(3918) voltage in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:28.405	debug	(3918) voltage != null: false
g-homa.0	2019-10-05 14:51:28.404	debug	(3918) power != null: false
g-homa.0	2019-10-05 14:51:28.404	debug	(3918) powerFactor != null: false
g-homa.0	2019-10-05 14:51:28.404	debug	(3918) current != null: false
g-homa.0	2019-10-05 14:51:28.403	debug	(3918) extendPlug: type = normal, energyMeasurement != null: true
g-homa.0	2019-10-05 14:51:28.400	debug	type: normal
g-homa.0	2019-10-05 14:51:28.400	debug	switched from: remote
g-homa.0	2019-10-05 14:51:28.400	debug	state: on
g-homa.0	2019-10-05 14:51:28.400	debug	(3918) Got updated info for Plug 4ce7ac:
g-homa.0	2019-10-05 14:51:27.392	debug	(3918) powerFactor in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:27.392	debug	(3918) power in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:27.392	debug	(3918) current in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:27.391	debug	(3918) voltage in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:51:27.335	debug	(3918) voltage != null: false
g-homa.0	2019-10-05 14:51:27.335	debug	(3918) power != null: false
g-homa.0	2019-10-05 14:51:27.335	debug	(3918) powerFactor != null: false
g-homa.0	2019-10-05 14:51:27.335	debug	(3918) current != null: false
g-homa.0	2019-10-05 14:51:27.334	debug	(3918) extendPlug: type = normal, energyMeasurement != null: true
g-homa.0	2019-10-05 14:51:27.332	debug	type: normal
g-homa.0	2019-10-05 14:51:27.332	debug	switched from: remote
g-homa.0	2019-10-05 14:51:27.332	debug	state: on
g-homa.0	2019-10-05 14:51:27.332	debug	(3918) Got updated info for Plug 4ce7ac:
g-homa.0	2019-10-05 14:51:13.232	debug	(3918) system.adapter.admin.1: logging false
g-homa.0	2019-10-05 14:49:15.644	debug	(3918) powerFactor in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:49:15.644	debug	(3918) power in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:49:15.643	debug	(3918) current in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:49:15.642	debug	(3918) voltage in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:49:15.519	debug	(3918) voltage != null: false
g-homa.0	2019-10-05 14:49:15.519	debug	(3918) power != null: false
g-homa.0	2019-10-05 14:49:15.519	debug	(3918) powerFactor != null: false
g-homa.0	2019-10-05 14:49:15.518	debug	(3918) current != null: false
g-homa.0	2019-10-05 14:49:15.518	debug	(3918) extendPlug: type = normal, energyMeasurement != null: true
g-homa.0	2019-10-05 14:49:15.516	debug	type: normal
g-homa.0	2019-10-05 14:49:15.516	debug	switched from: remote
g-homa.0	2019-10-05 14:49:15.516	debug	state: off
g-homa.0	2019-10-05 14:49:15.516	debug	(3918) Got updated info for Plug 4ce7ac:
g-homa.0	2019-10-05 14:49:14.392	debug	(3918) powerFactor in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:49:14.392	debug	(3918) power in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:49:14.392	debug	(3918) current in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:49:14.391	debug	(3918) voltage in plug.energyMeasurement => false
g-homa.0	2019-10-05 14:49:14.294	debug	(3918) voltage != null: false
g-homa.0	2019-10-05 14:49:14.293	debug	(3918) power != null: false
g-homa.0	2019-10-05 14:49:14.293	debug	(3918) powerFactor != null: false
g-homa.0	2019-10-05 14:49:14.292	debug	(3918) current != null: false
g-homa.0	2019-10-05 14:49:14.292	debug	(3918) extendPlug: type = normal, energyMeasurement != null: true
g-homa.0	2019-10-05 14:49:14.289	debug	type: normal
g-homa.0	2019-10-05 14:49:14.289	debug	switched from: remote
g-homa.0	2019-10-05 14:49:14.289	debug	state: off
g-homa.0	2019-10-05 14:49:14.289	debug	(3918) Got updated info for Plug 4ce7ac:

apollon77

@lobomau Dann leg doch mal bei ghoma ein Issue an ...

apollon77

@lobomau Alternativ leg doch mal ein Subscribe im Javascript adapter auf den relevanten State und logge alle Änderungen mit state.val, state.ack und state.from Vllt wird man daraus schlauer

SBorg

@apollon77 sagte in [Aufruf] js-controller 2.0 Beta Test:

@SBorg ...Das hat mit dem was der Controller da geändert hat nichts zu tun. Hast finden Browser Fehler bitte nochmal für mich? Mal danach gegoogelt?

Da bist du der Fachmann :)
Google hat zwar einiges zu Tage gefördert, aber wie üblich, wenn es interessant wird verläuft es im Sand...
Der socket.io kommt dann im paar Sekundentakt, wobei ich nicht weiß, ob die beiden ersten schon immer da waren:
Bild 1.png

Bringt es vielleicht etwas meine Flows zu sichern und Node-RED mal kpl. zu de-/neuinstallieren? Ev. hat es bei dem testen einfach nur bei mir was zerschossen...

darkiop

@apollon77 Mein iobroker-hwr läuft unter init.d und der iobroker-master im Container.

Blöderweise schreibt das Diet Pi auch kein syslog in der Standardconfig, d.h. ich habe da aktuell auch keines.

Ich mache jetzt folgendes:

Ich nehm ne neue SD Karte und setze den HWR mit Rasbian neu auf.

Des weiteren werd ich noch einen zweiten Docker Container mit iobroker als Salve für die Tests starten.

sven12

@apollon77
Sorry für die Umstände... Shelly auf 3.1.1 hoch gezogen, keine Änderung.
Reboot ,
Jetzt läuft der Shelly Adapter ohne Probleme..

Also wie immer Reboot tut gut...

apollon77

@SBorg sagte in [Aufruf] js-controller 2.0 Beta Test:

Bringt es vielleicht etwas meine Flows zu sichern und Node-RED mal kpl. zu de-/neuinstallieren? Ev. hat es bei dem testen einfach nur bei mir was zerschossen...

versuch das mal.

Die Meldung zeigt das er versucht zum Port 1880 von node-red zu verbunden und der Server das ablehnt.

apollon77

@darkiop Ok, Ansonsten: Wenn init.d dann schau mal ob in /opt/iobroker/node_modules/iobroker.js-controller/lib ein iobroker.pid File liegt wenn der sauber gestartet wurde und läuft. Wenn ja sollte das restart eigentlich tun. Wenn nein ist da was komisch

Community Forum

NEWS

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

Support us

354

32.4k

81.4k

1.3m