Probleme nach ZWave Update mit ZME_WALLC-S

[SirHenri]

@SirHenri noch ein zwei Sachen, die Auffallen

1. Meldung aus dem log:

zwave.0	2019-11-12 21:28:15.550	error	Unknown command: getNumberOfInstances

ist öfter nacheinander im log zu finden - problematisch?

Zum Anderen ist in den Abbildungen zu den adapter settings z.B. auf

https://www.npmjs.com/package/iobroker.zwave

die Möglichkeit abgebildet bei "Force objects re-init" einen Haken zu setzten - den gibt's bei mir nicht

[SirHenri]

@SirHenri Alles noch mal auf Anfang;
Instanz gelöscht; Adapter gelöscht
Installation der 1.7.0 aus dem Git
Adapter startet nicht sauber:

host.rockpro64	2019-11-14 07:16:17.512	info	Restart adapter system.adapter.zwave.0 because enabled
host.rockpro64	2019-11-14 07:16:17.512	error	instance system.adapter.zwave.0 terminated with code null ()
host.rockpro64	2019-11-14 07:16:17.511	warn	instance system.adapter.zwave.0 terminated due to SIGSEGV
host.rockpro64	2019-11-14 07:16:17.511	error	Caught by controller[0]: at Decoder.add (/opt/iobroker/node_modules/socket.io-parser/index.js:246:12)
host.rockpro64	2019-11-14 07:16:17.511	error	Caught by controller[0]: at Decoder.Emitter.emit (/opt/iobroker/node_modules/component-emitter/index.js:134:20)
host.rockpro64	2019-11-14 07:16:17.511	error	Caught by controller[0]: at Decoder.<anonymous> (/opt/iobroker/node_modules/component-bind/index.js:21:15)
host.rockpro64	2019-11-14 07:16:17.511	error	Caught by controller[0]: at Manager.ondecoded (/opt/iobroker/node_modules/socket.io-client/lib/manager.js:332:8)
host.rockpro64	2019-11-14 07:16:17.511	error	Caught by controller[0]: at Manager.Emitter.emit (/opt/iobroker/node_modules/socket.io-client/node_modules/component-emitter/index.js:133:20)
host.rockpro64	2019-11-14 07:16:17.511	error	Caught by controller[0]: at Manager.<anonymous> (/opt/iobroker/node_modules/component-bind/index.js:21:15)
host.rockpro64	2019-11-14 07:16:17.511	error	Caught by controller[0]: at Socket.onpacket (/opt/iobroker/node_modules/socket.io-client/lib/socket.js:236:12)
host.rockpro64	2019-11-14 07:16:17.511	error	Caught by controller[0]: at Socket.onack (/opt/iobroker/node_modules/socket.io-client/lib/socket.js:312:9)
host.rockpro64	2019-11-14 07:16:17.511	error	Caught by controller[0]: at Socket.<anonymous> (/opt/iobroker/node_modules/iobroker.zwave/main.js:536:51)
host.rockpro64	2019-11-14 07:16:17.510	error	Caught by controller[0]: at JSON.parse (<anonymous>)
host.rockpro64	2019-11-14 07:16:17.509	error	Caught by controller[0]: SyntaxError: Unexpected end of JSON input
zwave.0	2019-11-14 07:16:16.506	error	SyntaxError: Unexpected end of JSON input at JSON.parse (<anonymous>) at Socket.<anonymous> (/opt/iobroker/node_modules/iobroker.zwave/main.js:536:51) at Socket.onack (/opt/iobroker/node_m
zwave.0	2019-11-14 07:16:16.503	error	uncaught exception: Unexpected end of JSON input
zwave.0	2019-11-14 07:16:14.745	info	starting. Version 1.7.0 in /opt/iobroker/node_modules/iobroker.zwave, node: v10.17.0

vulnerabilities (49 low, 31 high) bestehen weiter

interessanter Weise sieht die Geräteübersicht anders aus, als bei der letzten 1.7. Installation

Ampel bestenfalls gelb

[cburghardt]

@SirHenri das könnte ein Bug sein, dazu bräuchte ich bitte einen GitHub Eintrag

[cburghardt]

@SirHenri said in Probleme nach ZWave Update mit ZME_WALLC-S:

ist öfter nacheinander im log zu finden - problematisch?

Da passt die Version des Admin Frontend nicht mit dem Backend zusammen. Der Adapter wurde nicht korrekt installiert.

Zum Anderen ist in den Abbildungen zu den adapter settings z.B. auf
https://www.npmjs.com/package/iobroker.zwave
die Möglichkeit abgebildet bei "Force objects re-init" einen Haken zu setzten - den gibt's bei mir nicht

Kann man ignorieren, ist noch eine alte Abbildung.

[SirHenri]

@cburghardt mach ich gerne fertig, habe ich aber noch nie gemacht

muss jetzt erst mal los, melde mich heute Nachmittag - und werde dann wohl erst mal ein Account im Git anlegen?

[SirHenri]

@cburghardt ist erledigt

[SirHenri]

Ich wollte mal einen Zwischenstand mitteilen - das Problem besteht weiter.

Da die Version 1.7.1 bei mir immer noch Fehler erzeugt, bin ich zurück auf die 1.6. Den Tatster hatte ich 3x aus dem System vollständig raus (in beiden Adapterversionen; er hat 3x eine neue ID bekommen). In allen drei Versuchen habe ich nur die schon beschriebenen states erzeugt bekommen.
Weitere Veränderungen: neue Batterie, USB Dongle mit Verlängerung versorgt, um evt. Abschirmung direkt am Rechner auszuschließen.
Der Abstand zwischen Dongle und Taster beträgt bei Inklusion Exklusion ca. 1,5m.
Das Log sieht so aus, als wenn der Taster sich zu früh schlafen legt. Kann ich das unterbinden?
Hat irgendjemand eine Idee, welche(n) weiteren Parameter ich verändern kann?

2019-11-21 12:16:50.585 Info, Node021, Response RTT 2289 Average Response RTT 1980
2019-11-21 12:16:50.585 Info, Node021,   Opening config param file /usr/local/etc/openzwave/popp/009303.xml
2019-11-21 12:16:50.589 Info, Node021, (112 - COMMAND_CLASS_CONFIGURATION) - Compatibility Flags:
2019-11-21 12:16:50.589 Info, Node021, (112 - COMMAND_CLASS_CONFIGURATION) - State Flags:
2019-11-21 12:16:50.589 Info, Node021, 	 CCVersion: 1
2019-11-21 12:16:50.589 Info, Node021, 	 InNif: true
2019-11-21 12:16:50.589 Info, Node021, 	 StaticRequests: 5
2019-11-21 12:16:50.590 Warning, Missing default list value or vindex from xml configuration: node 21, class 0x70, instance 1, index 21
2019-11-21 12:16:50.590 Warning, Missing default list value or vindex from xml configuration: node 21, class 0x70, instance 1, index 22
2019-11-21 12:16:50.591 Info, Node021, (133 - COMMAND_CLASS_ASSOCIATION) - Compatibility Flags:
2019-11-21 12:16:50.591 Info, Node021, (133 - COMMAND_CLASS_ASSOCIATION) - State Flags:
2019-11-21 12:16:50.591 Info, Node021, 	 CCVersion: 1
2019-11-21 12:16:50.591 Info, Node021, 	 InNif: true
2019-11-21 12:16:50.591 Info, Node021, 	 StaticRequests: 7
2019-11-21 12:16:50.592 Info, Node021, Received manufacturer specific report from node 21: Manufacturer=Popp & Co, Product=009303 Battery Wall Controller
2019-11-21 12:16:50.592 Info, Node021, Node Identity Codes: 0154:0100:0101
2019-11-21 12:16:50.592 Detail, Node021, Notification: Group
2019-11-21 12:16:50.592 Detail, Node021, Notification: Group
2019-11-21 12:16:50.593 Detail, Node021, Notification: Group
2019-11-21 12:16:50.593 Detail, Node021, Notification: Group
2019-11-21 12:16:50.593 Detail, Node021, Notification: Group
2019-11-21 12:16:50.593 Detail, Node021, Notification: NodeNaming
2019-11-21 12:16:58.296 Error, Node021, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-11-21 12:16:58.296 Detail, Node021, Removing current message
2019-11-21 12:16:58.297 Detail, Node021, Notification: Notification - TimeOut
2019-11-21 12:16:58.297 Detail, 
2019-11-21 12:16:58.297 Info, Node021, Sending (Query) message (Callback ID=0x40, Expected Reply=0x04) - MultiInstanceCmd_Get (Node=21): 0x01, 0x0a, 0x00, 0x13, 0x15, 0x03, 0x60, 0x04, 0x26, 0x25, 0x40, 0xd7
2019-11-21 12:16:58.297 Info, Node021, Encrypted Flag is 0
2019-11-21 12:16:58.306 Detail, Node021,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-11-21 12:16:58.307 Detail, Node021,   ZW_SEND_DATA delivered to Z-Wave stack
2019-11-21 12:16:58.491 Detail, Node021,   Received: 0x01, 0x07, 0x00, 0x13, 0x40, 0x00, 0x00, 0x13, 0xb8
2019-11-21 12:16:58.491 Detail, Node021,   ZW_SEND_DATA Request with callback ID 0x40 received (expected 0x40)
2019-11-21 12:16:58.491 Info, Node021, Request RTT 193 Average Request RTT 112
2019-11-21 12:16:58.491 Detail, Node021,   Expected callbackId was received
2019-11-21 12:17:08.298 Error, Node021, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-11-21 12:17:08.299 Detail, Node021, Removing current message
2019-11-21 12:17:08.299 Detail, Node021, Notification: Notification - TimeOut
2019-11-21 12:17:08.299 Detail, 
2019-11-21 12:17:08.299 Info, Node021, Sending (Query) message (Callback ID=0x41, Expected Reply=0x04) - MultiInstanceCmd_Get (Node=21): 0x01, 0x0a, 0x00, 0x13, 0x15, 0x03, 0x60, 0x04, 0x27, 0x25, 0x41, 0xd7
2019-11-21 12:17:08.299 Info, Node021, Encrypted Flag is 0
2019-11-21 12:17:08.307 Detail, Node021,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-11-21 12:17:08.308 Detail, Node021,   ZW_SEND_DATA delivered to Z-Wave stack
2019-11-21 12:17:08.482 Detail, Node021,   Received: 0x01, 0x07, 0x00, 0x13, 0x41, 0x00, 0x00, 0x12, 0xb8
2019-11-21 12:17:08.483 Detail, Node021,   ZW_SEND_DATA Request with callback ID 0x41 received (expected 0x41)
2019-11-21 12:17:08.483 Info, Node021, Request RTT 183 Average Request RTT 147
2019-11-21 12:17:08.483 Detail, Node021,   Expected callbackId was received
2019-11-21 12:17:18.300 Error, Node021, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-11-21 12:17:18.300 Detail, Node021, Removing current message
2019-11-21 12:17:18.300 Detail, Node021, Notification: Notification - TimeOut
2019-11-21 12:17:18.300 Detail, 
2019-11-21 12:17:18.300 Info, Node021, Sending (Query) message (Callback ID=0x42, Expected Reply=0x04) - MultiInstanceCmd_Get (Node=21): 0x01, 0x0a, 0x00, 0x13, 0x15, 0x03, 0x60, 0x04, 0x2b, 0x25, 0x42, 0xd8
2019-11-21 12:17:18.301 Info, Node021, Encrypted Flag is 0
2019-11-21 12:17:18.309 Detail, Node021,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-11-21 12:17:18.309 Detail, Node021,   ZW_SEND_DATA delivered to Z-Wave stack
2019-11-21 12:17:22.716 Detail, Node021,   Received: 0x01, 0x07, 0x00, 0x13, 0x42, 0x01, 0x01, 0xb9, 0x10
2019-11-21 12:17:22.717 Detail, Node021,   ZW_SEND_DATA Request with callback ID 0x42 received (expected 0x42)
2019-11-21 12:17:22.717 Info, Node021, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-11-21 12:17:22.717 Info, Node021,   Node 21 has been marked as asleep
2019-11-21 12:17:22.717 Info, Node021, Node not responding - moving message

[cburghardt]

@SirHenri der Fehler der Version 1.7.1 wurde nun gefixt, er ist aktuell aber nur in der GitHub Version behoben.
Wegen dem Wallplug würde ich mal bei openzwave nachfragen, da scheint es ein Kommunikationsproblem zu geben.

[SirHenri]

@cburghardt vielen Dank für die Benachrichtigung, hatte den Fix schon im Github gesehen.
Parallel zu diesem Text installiere ich die 1.7.1 noch mal.
Die Kommunikation ist seit dem Update auf die 1.6 sowie "besonders". Neben dem nicht mehr funktionierendem Taster (der ja mindestens bei einem weiteren Hersteller auch auftritt) hat sich auch bei meinen Bewegungsmeldern (3x Steinel IS 140-2) merkwürdiges Verhalten eingebürgert.
Bei Update auf die 1.6 laufen die Meldungen über detektierte Bewegung nicht mehr als Einbruchsalarm auf, sondern im Bereich "Home_Security_1" - meinetwegen. Beim Einrichten des Adapters gibt's die aber nicht zwingend (kann sein, dass das noch Verbindungsprobleme sind), mit Aktualiserung der Informationen sind sie dann irgenwann da. Ab einem Zeitpunkt, den ich nicht mehr genau benennen kann, gibt es nun Home_Security_1 und..._2; die Bwegung wird in _2 angemeldet. Nach Veränderung der Skripte wieder alles gut - bis zu dem Zeitpunkt gestern abend, oder heute Nacht, bei dem sich die BewMelder gedacht haben, es wäre eine gute Idee, die Bewegung wieder in Home_Security_1 zu melden...
Das Skript werde ich wohl nicht zurück schreiben, sondern ab sofort beide Kanäle (_1 und _2) abfragen. Ist aber irgendwie unbefriedigend.

Welchen Wechsel hat es bei dem hinterlegten OZW Modul gegeben? Damit ich das für einen möglichen Eintrag dort mit angeben kann.

Die Installation der 1.7.1 und das Erzeugen der Instanz sind hier auch sauber durchgelaufen. Ein Fehler beim Scannen der Nodes ist aufgelaufen. Node 16 ist der BewMelder, der am weitesten weg hängt. Ich update die Informationen gerade, hier sind nämlich auch wieder nur die States für/um Home_Security_1 aufgelaufen.

host.rockpro64	2019-11-25 16:32:49.313	info	Restart adapter system.adapter.zwave.0 because enabled
host.rockpro64	2019-11-25 16:32:49.312	info	instance system.adapter.zwave.0 terminated with code NaN ()
host.rockpro64	2019-11-25 16:32:49.311	warn	instance system.adapter.zwave.0 terminated due to SIGABRT
host.rockpro64	2019-11-25 16:32:49.310	error	Caught by controller[0]: what(): Manager.cpp:1514 - InvalidValueIDError (101) Msg: Invalid ValueID passed to GetValueLabel
host.rockpro64	2019-11-25 16:32:49.308	error	Caught by controller[0]: terminate called after throwing an instance of 'OpenZWave::OZWException'
web.0	2019-11-25 16:32:47.823	info	(1709) ==>Connected system.user.admin from ::ffff:192.168.178.201
zwave.0	2019-11-25 16:32:43.723	info	(14003) State created: zwave.0.NODE16.SENSOR_MULTILEVEL.Illuminance_1 = undefined, index = 3, comClass = 49, instance = 1
zwave.0	2019-11-25 16:32:43.721	info	(14003) Channel created: zwave.0.NODE16.SENSOR_MULTILEVEL = 2, index = 3, comClass = 49, instance = 1
zwave.0	2019-11-25 16:32:43.719	info	(14003) State created: zwave.0.NODE16.SENSOR_MULTILEVEL.Illuminance_1 = undefined, index = 3, comClass = 49, instance = 1

"Info aktualisieren" startet bei mir im Moment keine Prozesse (Zeile bei der Geräteübersicht wird nicht kurz blau und die Auswahl bleibt auch auf "Info aktualisieren" stehen) - Ampel ist auch 3x rot...

Vielen Dank für Eure Arbeit

[SirHenri]

Zwave Adapter neu gestartet, Ampel ist jetzt grün, "Info aktualisieren" bringt auch wie gewohnt neu Infos und Home_Security gibt es jetzt auch wieder bei Node 16 in zweifacher Ausführung.

[cburghardt]

@SirHenri der Fehler oben deutet auf falsche Informationen in dem openzwave Cache hin. Das wird normal durch das Info aktualisieren behoben.

[AlCalzone]

@cburghardt @SirHenri v1.7.2 ist jetzt auf npm

[SirHenri]

@AlCalzone hab s einmal mit einem Update probiert, danach Instanz und Adapter raus und von Null wieder neu - der Adapter behauptet in der Adapteransicht weiterhin eine 1.7.1 zu sein

[AlCalzone]

@SirHenri

Das Repo hat das Update vermutlich noch nicht gesehen. Alternativ gehts auf der Konsole:

cd /opt/iobroker
npm i iobroker.zwave

[SirHenri]

ah, ok
ist jetzt auch im Repo, Update ist sauber durchgelaufen

[Marsx79]

Hallo.

Hier besteht auch das Problem mit dem "heatit z-push button 8" das die Scenen nicht angelegt werden. Nur Scene_Count_1 und Scene_Reset_Timeout.

Grüße
Marcel

[SirHenri]

@cburghardt sagte in Probleme nach ZWave Update mit ZME_WALLC-S:

Wegen dem Wallplug würde ich mal bei openzwave nachfragen, da scheint es ein Kommunikationsproblem zu geben.

Schätze, wir kommen hier nicht weiter. Liege im Moment krankheitsbedingt flach. Soweit ich das verstanden habe liegt das Problem "hinter" iobroker.zwave; direkt in openzwave und wir müssen da mal unsere (inzwischen habe ich mindestens drei gesehen) Beoachtungen schildern. Ich würde als Grundlage dafür gerne noch raus bekommen, welcher Wechsel in der openzwave Version beim Update auf den Zwave Adapter 1.6 im Broker statt gefunden hat.
Ich vermute das Problem dort.

[cburghardt]

@SirHenri kommt drauf an von welcher Version wir sprechen. Von zwave 1.4 auf 1.6 wurde die openzwave Version grundlegend gewechselt, sprich openzwave 1.4 auf 1.6.

[SirHenri]

@cburghardt den Schritt meinte ich. Der Versionssprung heißt also in beiden Systemen gleich?!
Dann will ich mal sehen, ob mein kranker Kopf eine verständliche Beschreibung hin bekommt.
Fragen in der Google-Gruppe?

[cburghardt]

@SirHenri ja, zufällig heißen die Versionen identisch. Wobei wir auf openzwave 1.6 schon mit iobroker.zwave 1.5 gegangen sind..
Man kann entweder in der Mailinglist nachfragen oder gleich nen issue aufmachen. Aktuell im Einsatz ist die openzwave Version 1.6.953