Beta Test js-controller 3.0.x auf GitHub

[Chaot]

@apollon77 Ich habe seit dem Update auf 3.0.11 eine Fehlermeldung im Unifi Script.

javascript.0	2020-04-16 21:16:34.365	error	(26891) at processTicksAndRejections (internal/process/task_queues.js:97:5)
javascript.0	2020-04-16 21:16:34.364	error	(26891) at runMicrotasks (<anonymous>:null:null)
javascript.0	2020-04-16 21:16:34.364	error	(26891) at Object.<anonymous> (script.js.Unifi.UnifiNeu:1947:26)
javascript.0	2020-04-16 21:16:34.364	error	(26891) at workVouchers (script.js.Unifi.UnifiNeu:1104:14)
javascript.0	2020-04-16 21:16:34.363	error	(26891) at JSON.parse (<anonymous>:null:null)
javascript.0	2020-04-16 21:16:34.360	error	(26891) script.js.Unifi.UnifiNeu: SyntaxError: Unexpected token o in JSON at position 1
javascript.0	2020-04-16 21:16:34.274	error	(26891) at processTicksAndRejections (internal/process/task_queues.js:97:5)
javascript.0	2020-04-16 21:16:34.274	error	(26891) at runMicrotasks (<anonymous>:null:null)
javascript.0	2020-04-16 21:16:34.273	error	(26891) at Object.<anonymous> (script.js.Unifi.UnifiNeu:1947:26)
javascript.0	2020-04-16 21:16:34.273	error	(26891) at workVouchers (script.js.Unifi.UnifiNeu:1104:14)
javascript.0	2020-04-16 21:16:34.272	error	(26891) at JSON.parse (<anonymous>:null:null)
javascript.0	2020-04-16 21:16:34.267	error	(26891) script.js.Unifi.UnifiNeu: SyntaxError: Unexpected token o in JSON at position 1

[apollon77]

@Chaot Keine Ahnung was das Skript tut aber in dem Fall ist es klar ... da ist ein JSON ungültig und das wird nicht abgefangen. Man müsste jetzt mal das Skript genau sehen was da in und um Zeile 1104 so getrieben wird und wo das JSON herkommt

[e-i-k-e]

@apollon77 said in Beta Test js-controller 3.0.x auf GitHub:

@e-i-k-e Autsch ... 61 Sekunden ... Es kann nur irgendeine Schleife über irgendetwas grosses sein oder etwas anderes was sehr lange dauert und damit alles blockiert und nicht asynchron abläuft.

Ich konnte nun nicht auffälliges finden.
Habe aber den verdacht, dass sourceanalytix die Neustarts/Abstürze verursacht. Habe diesen aktuell deaktiviert, alle JS Instanzen sind jetzt grün.

Wollte mir gerad die log-Datei vom Master ziehen, diese ist 1,58 GB groß...
Die CPU-Auslastung hat sich auch deutlich reduziert, nachdem ich sourceanalytix beendet habe.

[apollon77]

@e-i-k-e Wie viele Werte überwachst Du denn so?

[crunchip]

@e-i-k-e sagte in Beta Test js-controller 3.0.x auf GitHub:

CPU-Auslastung hat sich auch deutlich reduziert,

war auch schon im Source-Adapter Thread das Thema....solltest du Geräte haben, die alle 1-2 sec synchronisieren, steigt deine CPU stark an, desweiteren sollte die Logstufe aktuell auf WARN stehen, solltest du die aktuelle Version verwenden
siehe https://forum.iobroker.net/post/412759

[ilovegym]

@apollon77 sagte in Beta Test js-controller 3.0.x auf GitHub:

@ilovegym mal manuell ein rebuild versucht? (Iobroker rebuild Kommando)

ja, war das erste, was ich tat, rebuild, rebuild --install, instanz gelöscht, adapter gelöscht, neu installiert von Git, wieder rebuild, naja, hat alles nicht geholfen, irgendwie rendert der nicht mehr.. leider wirft das log nicht viel raus..

Adapter ist das letzte Mal vor nem Jahr aktualisiert worden..

[sigi234]

@apollon77
Info

Version 3.0.11

Einzige Warn kommt nur mehr vom admin.0

admin.0	2020-04-17 08:11:30.999	warn	(13756) adapter.objects.getObjectView is deprecated, and will be removed in the future. Please use adapter.getObjectView/Async. Report this to Developer!

[apollon77]

@sigi234 Nee kommt noch von mehr Aber wir gehen die gerade alle durch, wenn du also immer latest aktualisierst ja dann sollte es weniger werden. habe noch ein paar Kandidaten vor mir

[apollon77]

Nach den Adapter-Lösch Erfahrungen von @ilovegym haben @foxriver76 und Ich nochmal ein bissl was gedreht.

Daher ab jetzt auf GitHub:

3.0.12 (2020-04-15) Release Elena

• (Apollon77) prevent logged error from file DB where it ws tried to write meta file for already deleted folders
• (Apollon77) display errors on npm uninstall
• (foxriver76) delete adapter and adapter.admin folders even if no corresponding meta objects exist
• (foxriver76) streamline dependencies/globalDependency checks in all places and allow different versions

Bitte gern nochmal Adapter installs oder upgrades von adaptern mit Dependencies testen und gern auch uninstallations von Instanzen und vor allem Adaptern

[Kueppert]

@apollon77 Update unauffällig - was auffällt, dass viel weniger Einträge im Log sind

[ilovegym]

@apollon77 Die einzigen Warnings kommen jetzt nur noch von broadlink, admin, mihome-vacuum. Ansonsten sehr ruhig.
Dankeschön!

Löschen des Adapters Onvif brachte folgendes:

readFile	2020-04-17 19:50:35.081	warn	will not read this file (openuv.png) in future versions: openuv.admin is not an object of type "meta"
host.iobroker	2020-04-17 19:50:34.522	info	iobroker exit 0
host.iobroker	2020-04-17 19:50:33.521	error	iobroker Error deleting adapter onvif from disk: Error: host.iobroker: Cannot uninstall iobroker.onvif: 1You might have to delete it yourself!
host.iobroker	2020-04-17 19:50:33.492	info	iobroker npm ERR! A complete log of this run can be found in:npm ERR! /home/iobroker/.npm/_logs/2020-04-17T17_50_33_448Z-debug.log
host.iobroker	2020-04-17 19:50:33.491	info	iobroker
host.iobroker	2020-04-17 19:50:33.439	info	iobroker Could not install from "node_modules/iobroker-react-components" as it does not contain a package.json file.
host.iobroker	2020-04-17 19:50:33.438	info	iobroker npm ERR!
host.iobroker	2020-04-17 19:50:33.437	info	iobroker ERR! code ENOLOCAL
host.iobroker	2020-04-17 19:50:33.436	info	iobroker npm
host.iobroker	2020-04-17 19:50:15.039	info	iobroker WARN deprecated request@2.88.2: request has been deprecated, see https://github.com/request/request/issues/3142
host.iobroker	2020-04-17 19:50:15.038	info	iobroker npm
javascript.0	2020-04-17 19:50:00.006	info	(3369) script.js.common.Message.LebensmittelwarnungTabelle: RSS-Feed https://www.lebensmittelwarnung.de/bvl-lmw-de/opensaga/feed/alle/hessen.rss eingelesen
host.iobroker	2020-04-17 19:49:58.672	info	iobroker npm uninstall iobroker.onvif --error --prefix "/opt/iobroker" (System call)
host.iobroker	2020-04-17 19:49:58.593	info	object deleted system.adapter.onvif.0
host.iobroker	2020-04-17 19:49:51.322	error	Cannot write files: /opt/iobroker/iobroker-data/files/onvif.admin/_data.json: ENOENT: no such file or directory, open '/opt/iobroker/iobroker-data/files/onvif.admin/_data.json'
host.iobroker	2020-04-17 19:49:50.330	info	iobroker host.iobroker Deleting 88 object(s).
host.iobroker	2020-04-17 19:49:50.329	info	iobroker host.iobroker object onvif.admin deleted
host.iobroker	2020-04-17 19:49:50.323	info	iobroker host.iobroker file onvif.admin deleted
readDir	2020-04-17 19:49:50.316	warn	will not read this directory () in future versions: onvif is not an object of type "meta"
host.iobroker	2020-04-17 19:49:50.209	info	iobroker host.iobroker Counted 14 states of system.adapter.onvif
host.iobroker	2020-04-17 19:49:50.196	info	iobroker host.iobroker Counted 57 states of onvif
host.iobroker	2020-04-17 19:49:50.175	info	iobroker host.iobroker Counted 14 devices of onvif
host.iobroker	2020-04-17 19:49:50.164	info	iobroker host.iobroker Counted 1 adapter for onvif
host.iobroker	2020-04-17 19:49:50.162	info	iobroker host.iobroker Counted 1 meta of onvif
host.iobroker	2020-04-17 19:49:50.151	info	iobroker host.iobroker Counted 1 instances of onvif
host.iobroker	2020-04-17 19:49:50.132	info	iobroker Delete adapter "onvif"
host.iobroker	2020-04-17 19:49:49.118	info	iobroker del onvif

Ansicht in Admin (Refresh gemacht):

Verzeichnis in iobroker-data/files ist weg.
Verzeichnis in node_modules ist noch komplett da:

ilovegym@iobroker /opt/iobroker $ ls node_modules/iobroker.onvif
admin        iob_npm.done     lib      main.js       package.json       README.md  tsconfig.json
gulpfile.js  io-package.json  LICENSE  main.test.js  package-lock.json  test

mikeal created this issue in request/request

open Request’s Past, Present and Future #3142

[apollon77]

@Kueppert Wie weiteroben schon gesagt: Das kommt wenn davon das Ihr ggf weitere Adapter aus Latest aktualisiert ... ich gehe gerade die am meisten verwendeten durch.

[apollon77]

@ilovegym sagte in Beta Test js-controller 3.0.x auf GitHub:

iobroker-react-components

Da liegt der "Hase im Pfeffer begraben" Dieses node-js modul kann er nicht sauber bearbeiten und daher kommt ein npm fehler und daher bleibt der Adater Code da.

Dieses Modul kommt vom ble adapter. Am besten lösch das genannte Verzeichnis (/opt/iobroker/node_modules/iobroker-react-components) und installier ble nochmal neu.

Hat nichts mit dem Controller zu tun.

[Kueppert]

Hm,
hab jetzt doch relativ viele neue Fehlermeldungen, die vorher nicht da waren die letzten Tage. Bin mir aber nicht sicher, ob die wirklich vom Update kommen

javascript.0	2020-04-17 20:31:12.957	error	(26164) at processTicksAndRejections (internal/process/task_queues.js:97:5)
javascript.0	2020-04-17 20:31:12.956	error	(26164) at runMicrotasks (<anonymous>:null:null)
javascript.0	2020-04-17 20:31:12.956	error	(26164) at script.js.common.Sonstiges.UnifiAdapterErsatz-Skript:514:27
javascript.0	2020-04-17 20:31:12.956	error	(26164) at Array.sort (<anonymous>:null:null)
javascript.0	2020-04-17 20:31:12.955	error	(26164) at script.js.common.Sonstiges.UnifiAdapterErsatz-Skript:515:23
javascript.0	2020-04-17 20:31:12.954	error	(26164) script.js.common.Sonstiges.UnifiAdapterErsatz-Skript: TypeError: Cannot read property 'toLowerCase' of undefined
host.ioBroker2	2020-04-17 20:30:09.312	error	Caught by controller[0]: (node:27881) PromiseRejectionHandledWarning: Promise rejection was handled asynchronously (rejection id: 1)
zigbee.0	2020-04-17 20:30:08.505	error	(27881) Error: Timeout - 102 - 11 - 9 - 0 - 1 after 10000ms at Timeout._onTimeout (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24) at list
zigbee.0	2020-04-17 20:30:08.504	error	(27881) uncaught exception: Timeout - 102 - 11 - 9 - 0 - 1 after 10000ms
zigbee.0	2020-04-17 20:24:14.211	error	at rejected (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65)
zigbee.0	2020-04-17 20:24:14.211	error	at Generator.throw (<anonymous>)
zigbee.0	2020-04-17 20:24:14.211	error	at Endpoint.<anonymous> (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:349:23)
zigbee.0	2020-04-17 20:24:14.211	error	(22374) Error on send command to 0x8418260000000712. Error: Error: Command 0x8418260000000712/3 genLevelCtrl.moveToLevelWithOnOff({"level":38,"transtime":0}, {"timeout":10000,"manufacturerCode":null,
zigbee.0	2020-04-17 20:24:13.959	error	at processTimers (internal/timers.js:492:7)
zigbee.0	2020-04-17 20:24:13.959	error	at listOnTimeout (internal/timers.js:549:17)
zigbee.0	2020-04-17 20:24:13.959	error	at Timeout._onTimeout (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
zigbee.0	2020-04-17 20:24:13.959	error	(22374) Error: Timeout - 1681 - 3 - 8 - 8 - 11 after 10000ms
zigbee.0	2020-04-17 20:24:13.958	error	(22374) uncaught exception: Timeout - 1681 - 3 - 8 - 8 - 11 after 10000ms
zigbee.0	2020-04-17 20:19:32.438	error	at rejected (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65)
zigbee.0	2020-04-17 20:19:32.438	error	at Generator.throw (<anonymous>)
zigbee.0	2020-04-17 20:19:32.438	error	at Endpoint.<anonymous> (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:349:23)
zigbee.0	2020-04-17 20:19:32.438	error	(21232) Error on send command to 0x0017880102b1973c. Error: Error: Command 0x0017880102b1973c/11 genLevelCtrl.moveToLevelWithOnOff({"level":76,"transtime":0}, {"timeout":10000,"manufacturerCode":null
zigbee.0	2020-04-17 20:19:32.190	error	at processTimers (internal/timers.js:492:7)
zigbee.0	2020-04-17 20:19:32.190	error	at listOnTimeout (internal/timers.js:549:17)
zigbee.0	2020-04-17 20:19:32.190	error	at Timeout._onTimeout (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
zigbee.0	2020-04-17 20:19:32.190	error	(21232) Error: Timeout - 102 - 11 - 2 - 8 - 11 after 10000ms
zigbee.0	2020-04-17 20:19:32.189	error	(21232) uncaught exception: Timeout - 102 - 11 - 2 - 8 - 11 after 10000ms
zigbee.0	2020-04-17 20:18:28.748	error	at processTimers (internal/timers.js:492:7)
zigbee.0	2020-04-17 20:18:28.748	error	at listOnTimeout (internal/timers.js:549:17)
zigbee.0	2020-04-17 20:18:28.748	error	at Timeout._onTimeout (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
zigbee.0	2020-04-17 20:18:28.748	error	(14838) Error: Timeout - 23637 - 1 - 4 - 0 - 1 after 10000ms
zigbee.0	2020-04-17 20:18:28.747	error	(14838) uncaught exception: Timeout - 23637 - 1 - 4 - 0 - 1 after 10000ms
zigbee.0	2020-04-17 20:12:36.378	error	at processTimers (internal/timers.js:492:7)
zigbee.0	2020-04-17 20:12:36.378	error	at listOnTimeout (internal/timers.js:549:17)
zigbee.0	2020-04-17 20:12:36.378	error	at Timeout._onTimeout (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
zigbee.0	2020-04-17 20:12:36.378	error	(29864) Error: Timeout - 1681 - 3 - 20 - 0 - 1 after 10000ms
zigbee.0	2020-04-17 20:12:36.377	error	(29864) uncaught exception: Timeout - 1681 - 3 - 20 - 0 - 1 after 10000ms

Ich werd die ganze Kiste (inkl. Proxmox) mal neu booten...

[Chaot]

@Kueppert Die Fehlermeldungen im Unifi Script habe ich auch seit V. 3.0.9 soweit ich mich erinnere

[Kueppert]

Mist,
rebuild vom Zigbee hat auch nix geholfen

zigbee.0	2020-04-17 20:47:11.658	error	at /opt/iobroker/node_modules/iobroker.zigbee/node_modules/@serialport/stream/lib/index.js:242:12
zigbee.0	2020-04-17 20:47:11.658	error	at SerialPort._error (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/@serialport/stream/lib/index.js:198:14)
zigbee.0	2020-04-17 20:47:11.658	error	at SerialPort.<anonymous> (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:111:49)
zigbee.0	2020-04-17 20:47:11.658	error	at __awaiter (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:4:12)
zigbee.0	2020-04-17 20:47:11.658	error	at new Promise (<anonymous>)
zigbee.0	2020-04-17 20:47:11.658	error	at /opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:8:71
zigbee.0	2020-04-17 20:47:11.658	error	at Generator.next (<anonymous>)
zigbee.0	2020-04-17 20:47:11.658	error	at Znp.<anonymous> (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:113:32)
zigbee.0	2020-04-17 20:47:11.658	error	(9723) Error: Error while opening serialport 'Error: Error Resource temporarily unavailable Cannot lock port' at Znp.<anonymous> (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-her
zigbee.0	2020-04-17 20:47:11.657	error	(9723) Failed to start Zigbee
zigbee.0	2020-04-17 20:47:11.422	info	(9723) Starting Zigbee...
zigbee.0	2020-04-17 20:47:11.421	info	(9723) Try to reconnect. 1 attempts left
zigbee.0	2020-04-17 20:47:01.419	error	(9723) Error: Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms) at ZStackAdapter.<anonymous> (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/ad
zigbee.0	2020-04-17 20:47:01.418	error	(9723) Failed to start Zigbee

Vielleicht hat @arteck hier ja auch ne Idee??
Den Fehler im Unifi-Skript hab ich erst jetzt (gesehen)

Hm, hab den Stick gezogen und mal wieder in nen anderen Port gedrückt...läuft gerade wieder...

[apollon77]

@Kueppert unify: hatten wir nicht das Thema oben schonmal? Zeigt die Code stelle und wir sehen weiter. Ich tippe darauf das einfach das JavaScript ohne viel Fehlerbehandlung geschrieben wurde. Und irgendwo Ein Fall passiert der unerwartet ist. Ob es am Controller liegt bezweifle ich ziemlich. Aber muss man sehen.

[apollon77]

@Kueppert zigbee ist ein Fehler vom Adapter. Der Controller kann nicht schuldig sein.

[Ritter]

Hallo,

hab beim DWD Adapter unter Windows 10 seit dem update diese Fehler im log:

dwd.0 2020-04-17 21:48:34.064 info (2336) Terminated (NO_ERROR): Without reason
dwd.0 2020-04-17 21:48:34.064 info (2336) terminating
dwd.0 2020-04-17 21:48:33.559 error (2336) ReferenceError: lat is not defined at adapter.getForeignObjectAsync.then.sys_conf (C:\iobroker\node_modules\iobroker.dwd\main.js:260:114) at process._tickCallback (internal/process/next
dwd.0 2020-04-17 21:48:33.558 error (2336) uncaught exception: lat is not defined
dwd.0 2020-04-17 21:48:33.539 info (2336) starting. Version 2.4.6 in C:/iobroker/node_modules/iobroker.dwd, node: v10.16.0, js-controller: 3.0.12

[foxriver76]

@Ritter liegt daran dass rejections jetzt als Fehler fliegen. Muss im Adapter gefixt werden.