Hi there,
i tried the Fibaro Roller Shutter 3 FGR223 with openHAB 2.4 with Zwave binding snapshot 2.5 build from yesterday. I’d like to share my experience i got so far.
Inclusion, exclusion, calibration and controlling the blinds with blinds control items (instead of dimmers) works as expected. Thanks to everyone who makes this possible.
But there is one big problem with status updates and position feedback which makes this device nearly unusable in a home automation environment.
If the blind is moved by zwave command and reaches the target position, the controller receives no update about the new position. If the blind is moved by the hardware switches S1/S2, the controller doesn’t perceive the move. I can not be sure about the current position at any time.
I captured some debug lines while moving the blind from 0% to 78%
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.
The movement takes about 12 seconds but at the end comes no update with the 78% position.