Problem Z-Wave Roller Shutter Status Update

[HABuser]

Hallo zusammen,
Ich tüftele nun schon seit ein paar Tagen mit meinen Roller Shuttern herum. Ich verwende openHAB 2.4 mit Z-Wave Binding 2.5 und unter anderem Fibaro FGR-223 Roller Shutter. Ich kann die Rolläden zwar über die Eingabe bei dem zugeordneten Item wunderbar in die gewünschte Position fahren, aber ich bekomme einfach keine Rückmeldung dass diese Position auch erreicht wurde. Wenn ich die Schalter an S1/S2 betätige um die Rolläden manuell zu verfahren, dann bekomme ich ebenfalls keine Rückmeldung. Ich kann also de facto in openHAB überhaupt nicht erkennen in welcher Position sich die Rolläden befinden.
Im Log erscheinen zwar die Meldungen für dss Command zum Setzen der Position, aber keine Meldungen für ein Status Update nach Erreichen der Zielposition und auch keine wenn ich einen Schalter betätige.
Ich habe in den Association Groups bei 1. Lifeline den Controller ausgewählt. Sonst weiß ich aber nicht was ich noch einstellen muss.
Hat jemand eine Idee?
Danke für jeden Hinweis,
Gruß
Roman

[HABuser]

Also im Eventlog erscheinen definitiv keine Nachrichten wenn ich die manuellen Taster betätige und wenn ich den Rolladen über openHAB steuere kommt auch nichts im Eventlog an wenn die Zielposition erreicht wurde.
ABER:
ich habe mal openHAB beendet (systemctl stop openhab2) und dann mit
sudo cat /dev/ttyAMA0 | hd
geschaut was so am seriellen Port ankommt.
Tatsächlich gibt es hier jede Menge Daten genau in dem Moment wenn ich einen Taster an dem Roller Shutter drücke.
Ich frage mich ( euch ) nun wo die im openHAB verschluckt werden.
Kann man das Logging im openHAB insbesondere für das Z-Wave Binding etws geschwätziger einstellen?

[sihui]

Also im Eventlog erscheinen definitiv keine Nachrichten wenn ich die manuellen Taster betätige

Association group Lifeline auf deinen Controller setzen.

Kann man das Logging im openHAB insbesondere für das Z-Wave Binding etws geschwätziger einstellen?

Du kannst jedes Binding auf DEBUG Modus setzen:

https://www.openhab.org/docs/administra ... hat-to-log

Übrigens, mal kurz in die Doku zu schauen geht oft viel schneller als in Foren auf Antworten zu warten

[HABuser]

Du kannst jedes Binding auf DEBUG Modus setzen:

https://www.openhab.org/docs/administra ... hat-to-log

Übrigens, mal kurz in die Doku zu schauen geht oft viel schneller als in Foren auf Antworten zu warten

Ja stimmt, ich bin zwischenzeitlich tatsächlich selbst drauf gekommen.

Mit

Code: Alles auswählen

log:set DEBUG org.openhab.binding.zwave

bekomme ich auch eine Menge Nachrichten angezeigt wenn ich auf S1/S2 drücke. Jetzt stellt sich die Frage was damit passiert.
Soweit wie ich das verstanden habe ist ja open-zwave die Basis des Z-Wave Bindings im openHAB. Meine Vermutung ist dass hier die Nachrichten vom FGR223 nicht richtig interpretiert und verworfen werden.

[HABuser]

Im Log erhalte ich

Code: Alles auswählen

19:32:49.290 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 0C 0A 60 0D 00 01 26 04 20 01 03 07 C1 00 45 
19:32:49.324 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0A 60 0D 00 01 26 04 20 01 03 07 C1 00 
19:32:49.333 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0A 60 0D 00 01 26 04 20 01 03 07 C1 00 
19:32:49.340 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
19:32:49.346 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE)
19:32:49.354 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false
19:32:49.362 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:32:49.369 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
19:32:49.378 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
19:32:49.385 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 unknown command 4
19:32:49.394 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1.
19:32:49.401 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@56bd62.
19:32:49.409 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
19:32:49.415 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
19:32:49.422 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:32:49.429 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
19:32:58.551 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 0C 0A 60 0D 00 01 26 04 60 01 03 07 BF 00 7B 
19:32:58.594 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0A 60 0D 00 01 26 04 60 01 03 07 BF 00 
19:32:58.603 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0A 60 0D 00 01 26 04 60 01 03 07 BF 00 
19:32:58.612 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
19:32:58.620 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE)
19:32:58.627 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false
19:32:58.636 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:32:58.646 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
19:32:58.657 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
19:32:58.664 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 unknown command 4
19:32:58.668 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1.
19:32:58.672 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@124bf2b.
19:32:58.676 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
19:32:58.679 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
19:32:58.683 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:32:58.686 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Ich finde vor allem

Code: Alles auswählen

 NODE 12: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 unknown command 4 

auffällig.

[sihui]

Im Log erhalte ich

Das Log ist ein wenig kurz:



Nimm mal das ganze Log (so etwa 10 MB) und jage es durch den Viewer:

https://www.cd-jackson.com/index.php/op ... log-viewer

[sihui]

Ich würde einfach mal warten bis die ganzen Änderungen in der Datenbank vom FGR223 im Binding gelandet sind, voraussichtlich in den nächsten Tagen, der Entwickler kommt heute aus dem Urlaub zurück.
Und dann: upgraden auf das neueste 2.5 Zwave Snapshot

[HABuser]

Ich würde einfach mal warten bis die ganzen Änderungen in der Datenbank vom FGR223 im Binding gelandet sind, voraussichtlich in den nächsten Tagen, der Entwickler kommt heute aus dem Urlaub zurück.
Und dann: upgraden auf das neueste 2.5 Zwave Snapshot

Wenn Chris das Problem überhaupt auf dem Schirm hat.
Sobald ich kann mache ich ein längeres Log

[HABuser]

Ich habe nochmal ein Zwave DEBUG Log gemacht.
Tatsächlch kommt aber nicht mehr als im Beispiel oben wenn man S1 oder S2 drückt. Ich kann natürlich öfter drücken um ein längeres Log zu erhalten, es sind dann aber immer die gleichen Nachrichten.
Der Log-Viewer scheint ja nur die Zeile mit "Receive Message" auszuwerten. Die ist aber einfach nicht länger und es kommen auch keine anderen.

Dann habe ich noch ein Log aufgezeichnet wenn ich per openHAB eine neue Position setze. Der entscheidende Punkt ist aber, dass bei erreichen der Zielposition überhaupt keine Nachricht kommt.

Beispiel:
Der Rolladen bei Node_12 ist zunächst komplett geschlossen = 0%. Ich setze über PaperUI den Schieberegler auf 78%. Der Rolladen öffnet sich und hat nach etwa 15 Sekunden die gewünschte Zielposition bei 78% erreicht. Der Schieberegler springt aber etwa zwei Sekunden nach dem Start nur auf 7% und bleibt dort während der Rolladen sich weiter öffnet. Wenn der Rolladen dann bei 78% angekommen ist kommt definitiv keine weitere Nachricht mehr im Log an. Auch wesentlich später nicht. Es wird mir solange 7% angezeigt bis ich wieder eine neue Position anfahre, obwohl der Rolladen bis dahin die ganze Zeit ca. 3/4 offen war. Das kann man doch nicht gebrauchen.

Code: Alles auswählen

00:59:02.343 [INFO ] [smarthome.event.ItemCommandEvent     ] - Item 'ZWaveNode012FGR223RollerShutter3Kueche_Dimmer' received command 78
00:59:02.356 [INFO ] [arthome.event.ItemStatePredictedEvent] - ZWaveNode012FGR223RollerShutter3Kueche_Dimmer predicted to become 78
00:59:02.370 [INFO ] [smarthome.event.ItemStateChangedEvent] - ZWaveNode012FGR223RollerShutter3Kueche_Dimmer changed from 34 to 78
00:59:02.370 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Command received zwave:device:ad3f9d85:node12:switch_dimmer --> 78 [PercentType]
00:59:02.386 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 12: Creating new message for command SWITCH_MULTILEVEL_SET
00:59:02.398 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
00:59:02.405 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
00:59:02.412 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Adding to device queue
00:59:02.419 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Added 183 to queue - size 1
00:59:02.427 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
00:59:02.438 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 26 01 4E 25 9E 3B 
00:59:02.447 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 26 01 4E 25 9E 3B 
00:59:02.455 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
00:59:02.458 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
00:59:02.464 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
00:59:02.465 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
00:59:02.468 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
00:59:02.474 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
00:59:02.478 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
00:59:02.482 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
00:59:02.485 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 183: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 158
00:59:02.491 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
00:59:02.491 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling intialised at 86400 seconds - start in 1000 milliseconds.
00:59:02.512 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
00:59:02.518 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 9E 00 00 02 00 BE 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 D7 
00:59:02.523 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
00:59:02.526 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 183: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 158
00:59:02.531 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
00:59:02.535 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 183: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 158
00:59:02.540 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
00:59:02.545 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack.
00:59:02.550 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 183: Advanced to WAIT_REQUEST
00:59:02.553 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 02 00 BE 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 
00:59:02.557 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: TID 183: Transaction not completed
00:59:02.563 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 02 00 BE 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 
00:59:02.569 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 183: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 158
00:59:02.573 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
00:59:02.579 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 183: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 158
00:59:02.583 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 183: (Callback 158)
00:59:02.589 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
00:59:02.593 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 183: callback 158
00:59:02.598 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 02 00 BE 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 
00:59:02.603 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 158, Status = Transmission complete and ACK received(0)
00:59:02.608 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false
00:59:02.611 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 183: Transaction COMPLETED
00:59:02.618 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 133ms
00:59:02.623 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: TID 183: Transaction completed
00:59:02.627 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:183 DONE
00:59:02.631 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
00:59:02.635 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
00:59:02.638 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
00:59:03.491 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling...
00:59:03.499 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling zwave:device:ad3f9d85:node12:switch_dimmer
00:59:03.507 [DEBUG] [verter.ZWaveMultiLevelSwitchConverter] - NODE 12: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
00:59:03.513 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 12: Creating new message for command SWITCH_MULTILEVEL_GET
00:59:03.522 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
00:59:03.528 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
00:59:03.535 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling zwave:device:ad3f9d85:node12:switch_dimmer1
00:59:03.542 [DEBUG] [verter.ZWaveMultiLevelSwitchConverter] - NODE 12: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
00:59:03.549 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 12: Creating new message for command SWITCH_MULTILEVEL_GET
00:59:03.556 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Encapsulating message, instance / endpoint 1
00:59:03.560 [DEBUG] [dclass.ZWaveMultiInstanceCommandClass] - NODE 12: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
00:59:03.563 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
00:59:03.568 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
00:59:03.571 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Adding to device queue
00:59:03.575 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Added 184 to queue - size 1
00:59:03.579 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
00:59:03.585 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0C 02 26 02 25 9F 75 
00:59:03.589 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 09 00 13 0C 02 26 02 25 9F 75 
00:59:03.595 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
00:59:03.600 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
00:59:03.604 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
00:59:03.610 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
00:59:03.610 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
00:59:03.616 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
00:59:03.621 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
00:59:03.622 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
00:59:03.628 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 184: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 159
00:59:03.629 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
00:59:03.633 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Adding to device queue
00:59:03.641 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Added 185 to queue - size 1
00:59:03.643 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 9F 00 00 02 00 BF 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 D7 
00:59:03.645 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
00:59:03.652 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
00:59:03.658 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
00:59:03.664 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 184: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 159
00:59:03.668 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
00:59:03.673 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 184: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 159
00:59:03.678 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
00:59:03.683 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 02 00 BF 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 
00:59:03.683 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack.
00:59:03.691 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 184: Advanced to WAIT_REQUEST
00:59:03.691 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 0C 05 26 03 07 4E 0B BF 00 27 
00:59:03.696 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: TID 184: Transaction not completed
00:59:03.704 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 02 00 BF 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 
00:59:03.711 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 184: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 159
00:59:03.716 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
00:59:03.720 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 184: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 159
00:59:03.725 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 184: (Callback 159)
00:59:03.730 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
00:59:03.733 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 05 26 03 07 4E 0B BF 00 
00:59:03.735 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 184: callback 159
00:59:03.743 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 02 00 BF 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 
00:59:03.748 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 159, Status = Transmission complete and ACK received(0)
00:59:03.751 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false
00:59:03.755 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 184: Advanced to WAIT_DATA
00:59:03.759 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: TID 184: Transaction not completed
00:59:03.763 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 05 26 03 07 4E 0B BF 00 
00:59:03.767 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
00:59:03.771 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE)
00:59:03.776 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false
00:59:03.779 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
00:59:03.783 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
00:59:03.788 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
00:59:03.792 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 12: Switch Multi Level report, value = 7
00:59:03.797 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
00:59:03.801 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 7
00:59:03.805 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:ad3f9d85:node12:switch_dimmer to 7 [PercentType]
00:59:03.810 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1.
00:59:03.814 [INFO ] [smarthome.event.ItemStateChangedEvent] - ZWaveNode012FGR223RollerShutter3Kueche_Dimmer changed from 78 to 7
00:59:03.819 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c5fb72.
00:59:03.826 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c5fb72.
00:59:03.833 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:184 DONE
00:59:03.839 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
00:59:03.844 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
00:59:03.848 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
00:59:03.853 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
00:59:03.858 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
00:59:03.865 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 0C 06 60 0D 01 01 26 02 25 A0 27 
00:59:03.870 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0D 00 13 0C 06 60 0D 01 01 26 02 25 A0 27 
00:59:03.877 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
00:59:03.881 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
00:59:03.887 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
00:59:03.902 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
00:59:03.905 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 185: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 160
00:59:03.909 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
00:59:03.913 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 185: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 160
00:59:03.917 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
00:59:03.920 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
00:59:03.924 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
00:59:03.932 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
00:59:03.936 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
00:59:03.938 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 A0 00 00 02 00 BF 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 E8 
00:59:03.940 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 185: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 160
00:59:03.946 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
00:59:03.950 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 185: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 160
00:59:03.954 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
00:59:03.957 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack.
00:59:03.961 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 185: Advanced to WAIT_REQUEST
00:59:03.964 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: TID 185: Transaction not completed
00:59:03.968 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
00:59:03.974 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 02 00 BF 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 
00:59:03.979 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
00:59:03.981 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 0C 09 60 0D 01 01 26 03 09 4E 0B BF 00 54 
00:59:03.985 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 02 00 BF 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 
00:59:03.990 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 185: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 160
00:59:03.994 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
00:59:04.000 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 185: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 160
00:59:04.004 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 185: (Callback 160)
00:59:04.009 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
00:59:04.013 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 185: callback 160
00:59:04.017 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 09 60 0D 01 01 26 03 09 4E 0B BF 00 
00:59:04.019 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 02 00 BF 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 
00:59:04.026 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 160, Status = Transmission complete and ACK received(0)
00:59:04.030 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false
00:59:04.035 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 185: Advanced to WAIT_DATA
00:59:04.039 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: TID 185: Transaction not completed
00:59:04.045 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 09 60 0D 01 01 26 03 09 4E 0B BF 00 
00:59:04.050 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
00:59:04.055 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE)
00:59:04.059 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false
00:59:04.064 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
00:59:04.068 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
00:59:04.072 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
00:59:04.077 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
00:59:04.081 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 12: Switch Multi Level report, value = 9
00:59:04.086 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
00:59:04.091 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 9
00:59:04.096 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:ad3f9d85:node12:switch_dimmer1 to 9 [PercentType]
00:59:04.102 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1.
00:59:04.106 [INFO ] [smarthome.event.ItemStateChangedEvent] - ZWaveNode012FGR223RollerShutter3Kueche_Dimmer1 changed from 32 to 9
00:59:04.113 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f0b223.
00:59:04.121 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f0b223.
00:59:04.126 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:185 DONE
00:59:04.132 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
00:59:04.137 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
00:59:04.141 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
00:59:04.145 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
00:59:04.149 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Wenn es richtig funktionieren würde, dann müssten eigentlich während der 15 Sekunden Verfahrweg laufend Status Updates kommen und der Schieberegler müsste sich kontinuierlich mit bewegen bis er schließlich bei 78% stehen bleibt.

[sihui]

Was interessieren dich denn die Werte während des Hoch- oder Runterfahrens???
Die Command Poll Period ist 1500, es wird also alle 1,5 Sekunden der Status abgefragt wenn das Gerät nicht selbst einen Wert sendet.