Fehler nach Update auf js-controller auf 1.5.2

[cvidal]

Ich musste gerade ein Update von 1.5.1 auch 1.5.2 machen

Version 1.5.1 brachte leider etwas Unruhe in die Nacht, mihome Gateways haben öfters ein Alarm gegeben, Rollladen über KNX gingen auf und zu etc.

Nach Installation auf 1.5.2 läuft es anscheinend besser, ich bekomme aber noch Folgende fehler ins Log:

hm-rpc.1 sind hmIP geräte.

hm-rpc.1	2018-09-15 02:38:01.055	error	Error: XML-RPC fault: Generic error
hm-rpc.1	2018-09-15 02:38:01.054	error	xmlrpc -> setValue ["4","STATE",true] BOOL

hm-rpc.1	2018-09-15 02:47:20.677	error	Char:
hm-rpc.1	2018-09-15 02:47:20.677	error	Column: 0
hm-rpc.1	2018-09-15 02:47:20.677	error	Line: 0
hm-rpc.1	2018-09-15 02:47:20.677	error	Error: Unexpected end
hm-rpc.1	2018-09-15 02:47:20.676	error	xmlrpc -> setValue ["4","STATE",false] BOOL
hm-rpc.1	2018-09-15 02:47:07.721	error	Error: XML-RPC fault: Generic error
hm-rpc.1	2018-09-15 02:47:07.721	error	xmlrpc -> setValue ["4","STATE",true] BOOL

admin.0	2018-09-15 02:52:17.232	warn	Disable logging, because no one socket connected

Mein Hmip Lichtschalter schaltet auch sehr Zeitverzögert, true reagiert direkt false ca. nach 10sek.

NodeJS Version: V10.2.1

NPM Version: 4.6.1

Alle Adapter sind Aktuell auf latest.

Gruß Christian

[Homer.J.]

Morgen,

bei mir funktionieren keine Homematic IP Geräte mehr mit 1.5.1 nicht und jetzt mit 1.5.2 auch nicht.

Iobroker neu gestartet auch kein Erfolg.

hm-rpc.2	2018-09-15 07:13:51.757	error	xmlrpc -> setValue: no dpType for hm-rpc.2.000855699C3D42.4.STATE!
hm-rpc.2	2018-09-15 07:13:49.598	error	xmlrpc -> setValue: no dpType for hm-rpc.2.000855699C3D42.4.STATE!

5373_88e2aaf9-25a2-4d93-8cc7-2715f114b82c.png

[apollon77]

Bitte versucht mal die Geräte neu zu syncen der Haken in der konfig der Instanz.

Danach besser?

Gesendet vom Handy …

[lobomau]

Bei mir war selbst nach zurück gehen auf 1.4.2. HM IP gestört. Bei mir hat ein Neustart der CCU2 eine Verbesserung gebracht. Adapter wurde grün. Ein hmip Bewegungsmelder streikt noch. Das konnte ich noch nicht weiter untersuchen.

[lobomau]

@lobomau:

Bei mir war selbst nach zurück gehen auf 1.4.2. HM IP gestört. Bei mir hat ein Neustart der CCU2 eine Verbesserung gebracht. Adapter wurde grün. Ein hmip Bewegungsmelder streikt noch. Das konnte ich noch nicht weiter untersuchen. `
Ich habe es mir eben angeschaut. Wie vorher geschrieben liegt es wohl am hm IP Lichtschalter.

Der Bewegungsmelder ändert seinen state.

Hm IP Adapter ist zwar grün, aber die Fehler sind da. Adapter habe ich neu synchronisiert. Merkwürdigerweise bin ich zurück auf 1.4.2 per snapshot, aber Problem bleibt

[apollon77]

Du meinst den „no dptype“ Fehler?!

Dann könntest du noch eines versuchen: Instanz stoppen. Dann die Geräte die den Fehler haben die Objekte komplett löschen. Dann nochmal syncen klicken und Instanz neu starten.

Dann log schauen.

Nicht das die nicht sauber angelegt wurden oder Objekte fehlen.

Gesendet vom Handy …

[lobomau]

Ok, probiere ich danach Mal. Leider weiß man bei dem Fehler nie welche Gerät die Ursache ist.

[cvidal]

So die HMIP Geschichte läuft jetzt, nach neustart des Raspberrymatic war der Lichtschalter nicht mehr angemeldet, nach Neuanmeldung in der CCU läuft es jetzt wieder.

Momentan habe ich noch eine Warnung:

admin.0	2018-09-15 08:50:58.433	warn	Disable logging, because no one socket connected

Gruß Christian

[apollon77]

@lobomau:

Ok, probiere ich danach Mal. Leider weiß man bei dem Fehler nie welche Gerät die Ursache ist.
filename="Screenshot_20180915-082426.jpg" index="0">~~ `
Diese Fehler sollten sich eigentlich durch ein resync der Objekte beheben.

Gesendet vom Handy …

[apollon77]

@cvidal:

So die HMIP Geschichte läuft jetzt, nach neustart des Raspberrymatic war der Lichtschalter nicht mehr angemeldet, nach Neuanmeldung in der CCU läuft es jetzt wieder.

Momentan habe ich noch eine Warnung:

admin.0	2018-09-15 08:50:58.433	warn	Disable logging, because no one socket connected

Gruß Christian `
Ok also war das Gerät irgendwie komisch.

Der Fehler bedeutet glaube ich das du zwei tabs offen hast mit Admin. Kann das sein? Bzw kann es sein das der Fehler kommt kurz nachdem du Admin im Browser zu gemacht hast? Dann deaktiviert er das logging..

Ich glaube Bluefox hat die Warnung eingebaut um Themen zu untersuchen warum Logs manchmal nicht angezeigt werden.

Gesendet vom Handy …

[dslraser]

@apollon77:

@cvidal:

So die HMIP Geschichte läuft jetzt, nach neustart des Raspberrymatic war der Lichtschalter nicht mehr angemeldet, nach Neuanmeldung in der CCU läuft es jetzt wieder.

Momentan habe ich noch eine Warnung:

admin.0	2018-09-15 08:50:58.433	warn	Disable logging, because no one socket connected

Gruß Christian `
Ok also war das Gerät irgendwie komisch.

Der Fehler bedeutet glaube ich das du zwei tabs offen hast mit Admin. Kann das sein? Bzw kann es sein das der Fehler kommt kurz nachdem du Admin im Browser zu gemacht hast? Dann deaktiviert er das logging..

Ich glaube Bluefox hat die Warnung eingebaut um Themen zu untersuchen warum Logs manchmal nicht angezeigt werden.

Gesendet vom Handy … `

Diese Meldung habe ich auch im Log, abwohl ich auf 1.4.2 zurück bin. Ich habe zwei Admin Instanzen, den Admin mit Port 8081 bekam ich mit 1.5.1 nur kurz grün, dann sofort wieder rot(Fehlermeldung mit Sinngemäß das der Port schon in Benutzung sei ?). Dann habe ich mal 8089 draus gemacht, das ging. Dann diese Instanz gelöscht und wieder neu mit 8081 installiert und es ging, aber trotz 1.4.2 habe habe ich nun diese Warnmeldung, aber es läuft erstmal alles.

[Marc_St]

Mir scheint, Modbus läuft nicht mehr?

host.tinkerboard	2018-09-15 11:10:27.109	error	instance system.adapter.modbus.0 terminated with code 0 (OK)
Caught	2018-09-15 11:10:27.109	error	by controller[0]: port: 502 }
Caught	2018-09-15 11:10:27.109	error	by controller[0]: address: '0.0.0.0',
Caught	2018-09-15 11:10:27.109	error	by controller[0]: syscall: 'listen',
Caught	2018-09-15 11:10:27.109	error	by controller[0]: errno: 'EACCES',
Caught	2018-09-15 11:10:27.109	error	by controller[0]: code: 'EACCES',
Caught	2018-09-15 11:10:27.109	error	by controller[0]: at process._tickCallback (internal/process/next_tick.js:180:9)
Caught	2018-09-15 11:10:27.108	error	by controller[0]: at _combinedTickCallback (internal/process/next_tick.js:141:11)
Caught	2018-09-15 11:10:27.108	error	by controller[0]: at doListen (net.js:1505:7)
Caught	2018-09-15 11:10:27.108	error	by controller[0]: at listenInCluster (net.js:1396:12)
Caught	2018-09-15 11:10:27.108	error	by controller[0]: at Server.setupListenHandle [as _listen2] (net.js:1338:19)
Caught	2018-09-15 11:10:27.108	error	by controller[0]: at _exceptionWithHostPort (util.js:1014:20)
Caught	2018-09-15 11:10:27.108	error	by controller[0]: at Object._errnoException (util.js:992:11)
Caught	2018-09-15 11:10:27.107	error	by controller[0]: { Error: listen EACCES 0.0.0.0:502
modbus.0	2018-09-15 11:10:27.081	info	terminating
modbus.0	2018-09-15 11:10:27.063	error	at process._tickCallback (internal/process/next_tick.js:180:9)
modbus.0	2018-09-15 11:10:27.063	error	at _combinedTickCallback (internal/process/next_tick.js:141:11)
modbus.0	2018-09-15 11:10:27.063	error	at doListen (net.js:1505:7)
modbus.0	2018-09-15 11:10:27.063	error	at listenInCluster (net.js:1396:12)
modbus.0	2018-09-15 11:10:27.063	error	at Server.setupListenHandle [as _listen2] (net.js:1338:19)
modbus.0	2018-09-15 11:10:27.063	error	at _exceptionWithHostPort (util.js:1014:20)
modbus.0	2018-09-15 11:10:27.063	error	at Object._errnoException (util.js:992:11)
modbus.0	2018-09-15 11:10:27.063	error	Error: listen EACCES 0.0.0.0:502
modbus.0	2018-09-15 11:10:27.062	error	uncaught exception: listen EACCES 0.0.0.0:502

[smartboart]

admin.0 2018-09-15 08:50:58.433 warn Disable logging, because no one socket connected

Diese meldung kam mit dem letzten update des admin.

Das update war in etwa zeitgleich mit jscontoller 1.5.1 verfügbar…hat denke ich mit dem js controller nix zu tun...

Das problem mit iobroker stop und den states kam mit dem jscontroller ...

aber ich hatte all die beschriebenen probleme auch... bis ich mein backup wieder eingespielt habe...

Das update hatte es in sich...

Meine nachbarn haben sich über die beschallung gefreut...

Gesendet von meinem SM-G800F mit Tapatalk

[cvidal]

@smartboart:

Meine nachbarn haben sich über die beschallung gefreut… `

Meine auch und meine Frau erst… um 02:30h musste ich dann nochmal ran Also an den PC :roll:

@apollon77

Momentan tritt die Warnung nicht auf, Admin habe ich immer nur einen offen,

ich hatte die Raspberrymatic am 13.09. neu aufgesetzt eventuell lag der Fehler da irgendwo...

Momentan läuft bei mir alles ruhig.

Gruß Christian

[Homer.J.]

Bin auch wieder zurück auf die 1.5.0 Version hat am Anfang auch nicht funktioniert, erst nach einem Neustart der Raspberrymatic.

[Neuschwansteini]

bei mir hats auch geschossen, hatte vorher auf npm6.4.1 und node 8.12 geupdated, und dann von js-controller 1.4.2 auf 1.5.0 -> 1.5.1 … dann hat sich hier alles selbstständig gemacht.. :geek: :geek: :geek:

backup wieder eingespielt, mit node 8.11.4 npm6.4.0 und js-controller 1.5.2 läuft alles wieder perfekt.

Fazit: ruhig schläft, wer täglich Backup macht, die auch funktionieren mit einem Restore..

[ltsalvatore]

also bei mir läuft nach dem update auf 1.5.2 das log file auch hochtouren und meine ganzen scripte werden nicht mehr verarbeitet.

ich kann mit den fehlermeldungen leider nicht viel anfangen…

ich habe bereits den javascript.0 adapter neu installiert…dadurch dummerweise meine ganzen objekte verloren...

glücklicherweise hat das backup heute nacht funktioniert, sodass ich das minimal backup aufgespielt habe und alles wieder (stand heute nacht) da ist.

jetzt muss ich nur noch schauen, wie ich den fehler dort weg bekomme, damit die scrpits auch wieder funktionieren und die fehlermeldungen im log verschwinden.

sollte ich auf die vorherige version zurückspringen? wenn ja, wie geht das? ich weis auch leider nicht mehr, welche version vorher drauf war..

oder sollte ich ein fullbackup wiederherstellen und anschließend das aktuelle minimakbackup einspielen?

[sigi234]

cd /opt/iobroker 
iobroker stop
npm i iobroker.js-controller@1.4.2 --production
iobroker start

[aftershowman]

Hallo,

hatte die selben Probleme nach dem Update JS-Controller 1.42 -> 1.151

• Rollos fuhren selbstständig ein Stück zu und anschließend gleich wieder auf. (Die Frau war total genervt)

• Web Instance für HTTPS mit Let's Encrypt startet nicht

Hab jetzt auf JS-Conroller 1.52 geupdated.

Es kommen keine RPC Adapter bezüglichen Fehler mehr.

Der Web Adapter für HTTPS startet aber immer noch nicht.

Ist meine Node/NPM Installation zu alt?

Hardware: RASPI 3

NPM Version: 3.10.10

NodeJS Version: V6.12.3

Admin Adapter Version 3.59

Alle installierten Adapter sind auf dem aktuellen Stand

Nutze Verwahrungsort: http://download.iobroker.net/sources-dist-latest.json

Hier die Logs:

host.wg6-wetter 2018-09-15 13:40:42.792 info Restart adapter system.adapter.web.1 because enabled

host.wg6-wetter 2018-09-15 13:40:42.792 error instance system.adapter.web.1 terminated with code 0 (OK)

Caught 2018-09-15 13:40:42.791 error by controller[0]: at Decoder.add (/opt/iobroker/node_modules/socket.io-parser/index.js:246:12)

Caught 2018-09-15 13:40:42.791 error by controller[0]: at Decoder.Emitter.emit (/opt/iobroker/node_modules/component-emitter/index.js:134:20)

Caught 2018-09-15 13:40:42.791 error by controller[0]: at Decoder. <anonymous>(/opt/iobroker/node_modules/component-bind/index.js:21:15)

Caught 2018-09-15 13:40:42.790 error by controller[0]: at Manager.ondecoded (/opt/iobroker/node_modules/iobroker.js-controller/node_modules/socket.io-client/lib/manager.js:332:8)

Caught 2018-09-15 13:40:42.790 error by controller[0]: at Manager.Emitter.emit (/opt/iobroker/node_modules/iobroker.js-controller/node_modules/component-emitter/index.js:133:20)

Caught 2018-09-15 13:40:42.790 error by controller[0]: at Manager. <anonymous>(/opt/iobroker/node_modules/component-bind/index.js:21:15)

Caught 2018-09-15 13:40:42.790 error by controller[0]: at Socket.onpacket (/opt/iobroker/node_modules/iobroker.js-controller/node_modules/socket.io-client/lib/socket.js:236:12)

Caught 2018-09-15 13:40:42.790 error by controller[0]: at Socket.onack (/opt/iobroker/node_modules/iobroker.js-controller/node_modules/socket.io-client/lib/socket.js:312:9)

Caught 2018-09-15 13:40:42.789 error by controller[0]: at Socket.that.getForeignObject (/opt/iobroker/node_modules/iobroker.js-controller/lib/adapter.js:724:21)

Caught 2018-09-15 13:40:42.789 error by controller[0]: at adapter.getCertificates (/opt/iobroker/node_modules/iobroker.web/main.js:239:29)

Caught 2018-09-15 13:40:42.787 error by controller[0]: at initWebServer (/opt/iobroker/node_modules/iobroker.web/main.js:847:28)

Caught 2018-09-15 13:40:42.787 error by controller[0]: at Object.createServer (/opt/iobroker/node_modules/iobroker.js-controller/lib/letsencrypt.js:55:26)

Caught 2018-09-15 13:40:42.786 error by controller[0]: at Object.Greenlock.create (/opt/iobroker/node_modules/greenlock/index.js:258:13)

Caught 2018-09-15 13:40:42.786 error by controller[0]: at promisifyAllSelf (/opt/iobroker/node_modules/greenlock/index.js:15:20)

Caught 2018-09-15 13:40:42.786 error by controller[0]: at Array.forEach (native)

Caught 2018-09-15 13:40:42.786 error by controller[0]: at /opt/iobroker/node_modules/greenlock/index.js:17:33

Caught 2018-09-15 13:40:42.784 error by controller[0]: TypeError: util.promisify is not a function

web.1 2018-09-15 13:40:42.694 error uncaught exception: util.promisify is not a function

host.wg6-wetter 2018-09-15 13:40:37.991 info instance system.adapter.web.1 started with pid 12193</anonymous></anonymous>

[ltsalvatore]

@sigi234:

cd /opt/iobroker 
iobroker stop
npm i iobroker.js-controller@1.4.2 --production
iobroker start
```` `  

super, danke..

läuft wieder alles prima!