Z-Wave Binding: No status update from Fibaro Roller Shutter 3 FGR223

Sorry, but I have really no influence with OpenZWave so there’s really nothing I can do to help with OZW bugs.

I’ve just re-read this and noticed this comment - openHAB does not use OZW at all - it’s completely separate and there is no link.

Hi Chris,

thanks for your answer, I didn’t know and just believed what others wrote in other forums.
Then I’d like to ask you even more insistively to look into this issue - Fibaro and at least this user petergebruers claim that the device is continuously sending percentage reports while the roller is moving. Why don’t we see them in the Z-Wave binding?
And why do we see them only after a poll has been done (either by sending STOP command or reducing the polling interval)?

Regards,
Stefan D.

What do you see in the ZWave log? Are these messages being received?

I don’t have this device, so its hard for me to comment. This thread is very old and I’ve not seen any recent logs.

Hi Chris,

I have the same issue with my Fibaro Rollershutter 3. When I move the Rollershutters with OpenHAB (either by rule, PaperUI or App), I get the updated positon right after movement. When I move the shutters by switches, the position is updated the next time the device gets polled (polling period configured to 10min).

Today I enabled DEBUG log and logged this:

The Rollershutter (NODE 20) started at open position (0) and was moved by wall switch.

2020-04-16 11:39:16.162 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 14 0E 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 BB 
2020-04-16 11:39:16.164 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 
2020-04-16 11:39:16.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 
2020-04-16 11:39:16.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:39:16.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:DONE)
2020-04-16 11:39:16.170 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:39:16.170 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-04-16 11:39:16.171 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-04-16 11:39:16.172 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_METER
2020-04-16 11:39:16.172 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-04-16 11:39:16.173 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 20: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2020-04-16 11:39:16.174 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-04-16 11:39:16.175 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0E+1
2020-04-16 11:39:16.176 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating channel state zwave:device:57fa8ae6:node20:meter_watts1 to 0 [DecimalType]
2020-04-16 11:39:16.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2020-04-16 11:39:16.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1b3b5b2.
2020-04-16 11:39:16.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:39:16.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:39:16.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:39:16.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-16 11:39:17.897 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling...
2020-04-16 11:39:17.898 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling deferred until initialisation complete
2020-04-16 11:39:47.297 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 14 0E 60 0D 01 01 32 02 21 32 02 BD 00 00 00 00 04 
2020-04-16 11:39:47.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 60 0D 01 01 32 02 21 32 02 BD 00 00 00 00 
2020-04-16 11:39:47.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 60 0D 01 01 32 02 21 32 02 BD 00 00 00 00 
2020-04-16 11:39:47.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:39:47.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:DONE)
2020-04-16 11:39:47.304 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:39:47.305 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-04-16 11:39:47.306 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-04-16 11:39:47.306 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_METER
2020-04-16 11:39:47.307 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-04-16 11:39:47.308 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 20: Meter: Type=Electric(1), Scale=W(2), Value=70.1
2020-04-16 11:39:47.309 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-04-16 11:39:47.310 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=70.1
2020-04-16 11:39:47.311 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating channel state zwave:device:57fa8ae6:node20:meter_watts1 to 70.1 [DecimalType]
2020-04-16 11:39:47.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2020-04-16 11:39:47.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@321b44.
2020-04-16 11:39:47.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:39:47.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:39:47.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:39:47.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-16 11:39:47.898 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling...
2020-04-16 11:39:47.899 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling deferred until initialisation complete
2020-04-16 11:39:53.033 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 14 09 60 0D 01 01 26 03 3B 3B FE 5F 
2020-04-16 11:39:53.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 09 60 0D 01 01 26 03 3B 3B FE 
2020-04-16 11:39:53.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 09 60 0D 01 01 26 03 3B 3B FE 
2020-04-16 11:39:53.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:39:53.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:DONE)
2020-04-16 11:39:53.045 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:39:53.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-04-16 11:39:53.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2020-04-16 11:39:53.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2020-04-16 11:39:53.049 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2020-04-16 11:39:53.050 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 20: Switch Multi Level report, value = 59
2020-04-16 11:39:53.053 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-04-16 11:39:53.055 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=59
2020-04-16 11:39:53.056 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating channel state zwave:device:57fa8ae6:node20:blinds_control1 to 59 [PercentType]
2020-04-16 11:39:53.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2020-04-16 11:39:53.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@179b602.
2020-04-16 11:39:53.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:39:53.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:39:53.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:39:53.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-16 11:39:55.599 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 14 0E 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 BB 
2020-04-16 11:39:55.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 
2020-04-16 11:39:55.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 
2020-04-16 11:39:55.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:39:55.603 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:DONE)
2020-04-16 11:39:55.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:39:55.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-04-16 11:39:55.609 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-04-16 11:39:55.610 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_METER
2020-04-16 11:39:55.610 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-04-16 11:39:55.611 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 20: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2020-04-16 11:39:55.612 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-04-16 11:39:55.613 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0E+1
2020-04-16 11:39:55.613 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating channel state zwave:device:57fa8ae6:node20:meter_watts1 to 0 [DecimalType]
2020-04-16 11:39:55.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2020-04-16 11:39:55.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1808c9b.
2020-04-16 11:39:55.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:39:55.616 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:39:55.617 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:39:55.617 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-16 11:40:17.897 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling...
2020-04-16 11:40:17.899 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling deferred until initialisation complete
2020-04-16 11:40:25.542 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 14 0E 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 BB 
2020-04-16 11:40:25.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 
2020-04-16 11:40:25.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 
2020-04-16 11:40:25.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:40:25.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:DONE)
2020-04-16 11:40:25.548 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:40:25.549 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-04-16 11:40:25.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-04-16 11:40:25.551 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_METER
2020-04-16 11:40:25.554 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-04-16 11:40:25.555 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 20: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2020-04-16 11:40:25.557 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-04-16 11:40:25.558 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0E+1
2020-04-16 11:40:25.559 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating channel state zwave:device:57fa8ae6:node20:meter_watts1 to 0 [DecimalType]
2020-04-16 11:40:25.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2020-04-16 11:40:25.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@108bb3c.
2020-04-16 11:40:25.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:40:25.562 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:40:25.562 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:40:25.563 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-16 11:40:47.897 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling...
2020-04-16 11:40:47.898 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling deferred until initialisation complete
2020-04-16 11:40:55.030 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 04 0E 32 02 21 44 00 00 07 F8 01 2D 00 00 07 F8 9C 
2020-04-16 11:40:55.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 0E 32 02 21 44 00 00 07 F8 01 2D 00 00 07 F8 
2020-04-16 11:40:55.033 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 0E 32 02 21 44 00 00 07 F8 01 2D 00 00 07 F8 
2020-04-16 11:40:55.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:40:55.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2020-04-16 11:40:55.035 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2020-04-16 11:40:55.036 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-04-16 11:40:55.036 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2020-04-16 11:40:55.037 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-04-16 11:40:55.038 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 4: Meter: Type=Electric(1), Scale=kWh(0), Value=20.4
2020-04-16 11:40:55.039 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-04-16 11:40:55.040 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=20.4
2020-04-16 11:40:55.041 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:57fa8ae6:node4:meter_kwh to 20.4 [DecimalType]
2020-04-16 11:40:55.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2020-04-16 11:40:55.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14676a2.
2020-04-16 11:40:55.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:40:55.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:40:55.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:40:55.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

The PaperUI still showed the 0 as position. To poll an update of the item, I pressed the “stop” button in PaperUI:

==> /var/log/openhab2/events.log <==
2020-04-16 11:40:56.001 [ome.event.ItemCommandEvent] - Item 'AZ_Rollladen_Schalter' received command STOP
==> /var/log/openhab2/openhab.log <==
2020-04-16 11:40:56.008 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Command received zwave:device:57fa8ae6:node20:blinds_control --> STOP [StopMoveType]
2020-04-16 11:40:56.009 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 20: Creating new message for command SWITCH_MULTILEVEL_STOP_LEVEL_CHANGE
2020-04-16 11:40:56.010 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2020-04-16 11:40:56.010 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2020-04-16 11:40:56.012 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 20: Creating new message for command SWITCH_MULTILEVEL_GET
2020-04-16 11:40:56.016 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2020-04-16 11:40:56.017 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2020-04-16 11:40:56.018 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 04 0E 32 02 21 54 00 00 00 00 01 2D 00 00 00 00 8C 
2020-04-16 11:40:56.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Adding to device queue
2020-04-16 11:40:56.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added 2925 to queue - size 1
2020-04-16 11:40:56.021 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-16 11:40:56.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 0E 32 02 21 54 00 00 00 00 01 2D 00 00 00 00 
2020-04-16 11:40:56.022 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 14 02 26 05 25 61 94 
2020-04-16 11:40:56.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 0E 32 02 21 54 00 00 00 00 01 2D 00 00 00 00 
2020-04-16 11:40:56.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:40:56.025 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2020-04-16 11:40:56.025 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2020-04-16 11:40:56.027 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-04-16 11:40:56.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2020-04-16 11:40:56.027 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 20: Sending REQUEST Message = 01 09 00 13 14 02 26 05 25 61 94 
2020-04-16 11:40:56.029 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-04-16 11:40:56.030 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-04-16 11:40:56.032 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 4: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2020-04-16 11:40:56.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2925: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 97
2020-04-16 11:40:56.034 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-04-16 11:40:56.035 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-04-16 11:40:56.035 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-16 11:40:56.035 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1
2020-04-16 11:40:56.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Adding to device queue
2020-04-16 11:40:56.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:57fa8ae6:node4:meter_watts to 0 [DecimalType]
2020-04-16 11:40:56.040 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-04-16 11:40:56.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:40:56.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2020-04-16 11:40:56.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added 2926 to queue - size 1
2020-04-16 11:40:56.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1bb132e.
2020-04-16 11:40:56.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:40:56.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-04-16 11:40:56.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-04-16 11:40:56.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-16 11:40:56.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2925: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 97
2020-04-16 11:40:56.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-04-16 11:40:56.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:40:56.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2925: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 97
2020-04-16 11:40:56.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-16 11:40:56.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2925: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 97
2020-04-16 11:40:56.060 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:40:56.061 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: sentData successfully placed on stack.
2020-04-16 11:40:56.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2925: Advanced to WAIT_REQUEST
2020-04-16 11:40:56.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 2925: Transaction not completed
2020-04-16 11:40:56.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:40:56.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:40:56.188 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 61 00 00 0F 85 
2020-04-16 11:40:56.190 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=97, payload=61 00 00 0F 
2020-04-16 11:40:56.190 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=97, payload=61 00 00 0F 
2020-04-16 11:40:56.193 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2925: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 97
2020-04-16 11:40:56.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-16 11:40:56.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2925: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 97
2020-04-16 11:40:56.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2925: (Callback 97)
2020-04-16 11:40:56.197 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-04-16 11:40:56.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2925: callback 97
2020-04-16 11:40:56.198 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=97, payload=61 00 00 0F 
2020-04-16 11:40:56.199 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: SendData Request. CallBack ID = 97, Status = Transmission complete and ACK received(0)
2020-04-16 11:40:56.201 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:40:56.202 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2925: Transaction COMPLETED
2020-04-16 11:40:56.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Response processed after 172ms
2020-04-16 11:40:56.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 2925: Transaction completed
2020-04-16 11:40:56.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:2925 DONE
2020-04-16 11:40:56.206 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-04-16 11:40:56.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:40:56.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-16 11:40:56.210 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 14 02 26 02 25 62 90 
2020-04-16 11:40:56.211 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 20: Sending REQUEST Message = 01 09 00 13 14 02 26 02 25 62 90 
2020-04-16 11:40:56.211 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-04-16 11:40:56.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2926: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 98
2020-04-16 11:40:56.215 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-04-16 11:40:56.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-16 11:40:56.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-16 11:40:56.219 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2926: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 98
2020-04-16 11:40:56.220 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-04-16 11:40:56.221 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:40:56.222 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-04-16 11:40:56.224 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:40:56.225 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:40:56.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:40:56.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2926: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 98
2020-04-16 11:40:56.228 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-16 11:40:56.229 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2926: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 98
2020-04-16 11:40:56.231 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:40:56.232 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: sentData successfully placed on stack.
2020-04-16 11:40:56.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2926: Advanced to WAIT_REQUEST
2020-04-16 11:40:56.234 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 2926: Transaction not completed
2020-04-16 11:40:56.235 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:40:56.236 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:40:56.264 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 62 00 00 04 8D 
2020-04-16 11:40:56.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=98, payload=62 00 00 04 
2020-04-16 11:40:56.265 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=98, payload=62 00 00 04 
2020-04-16 11:40:56.265 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2926: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 98
2020-04-16 11:40:56.265 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-16 11:40:56.265 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2926: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 98
2020-04-16 11:40:56.265 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2926: (Callback 98)
2020-04-16 11:40:56.266 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-04-16 11:40:56.266 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2926: callback 98
2020-04-16 11:40:56.266 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=98, payload=62 00 00 04 
2020-04-16 11:40:56.266 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: SendData Request. CallBack ID = 98, Status = Transmission complete and ACK received(0)
2020-04-16 11:40:56.267 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:40:56.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2926: Advanced to WAIT_DATA
2020-04-16 11:40:56.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 2926: Transaction not completed
2020-04-16 11:40:56.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:40:56.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:40:56.338 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 14 05 26 03 3B 3B FE 3A 
2020-04-16 11:40:56.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 05 26 03 3B 3B FE 
2020-04-16 11:40:56.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 05 26 03 3B 3B FE 
2020-04-16 11:40:56.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:40:56.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:DONE)
2020-04-16 11:40:56.340 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:40:56.340 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2020-04-16 11:40:56.340 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2020-04-16 11:40:56.341 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2020-04-16 11:40:56.341 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 20: Switch Multi Level report, value = 59
2020-04-16 11:40:56.341 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-04-16 11:40:56.342 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=59
2020-04-16 11:40:56.342 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating channel state zwave:device:57fa8ae6:node20:blinds_control to 41 [PercentType]
2020-04-16 11:40:56.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
==> /var/log/openhab2/events.log <==
2020-04-16 11:40:56.343 [vent.ItemStateChangedEvent] - AZ_Rollladen_Schalter changed from 0 to 41
==> /var/log/openhab2/openhab.log <==
2020-04-16 11:40:56.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3aaf56.
2020-04-16 11:40:56.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3aaf56.
2020-04-16 11:40:56.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:2926 DONE
2020-04-16 11:40:56.344 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-04-16 11:40:56.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-04-16 11:40:56.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:40:56.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:40:56.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
==> /var/log/openhab2/events.log <==
2020-04-16 11:40:56.345 [GroupItemStateChangedEvent] - gBlinds changed from 0 to UNDEF through AZ_Rollladen_Schalter
2020-04-16 11:40:56.345 [GroupItemStateChangedEvent] - gBlindsHabitableRoom changed from 0 to UNDEF through AZ_Rollladen_Schalter

The post is getting too long for the Forum, so I’ll continue in next post …

Then I opened the shutter again using the wall switch. The PaperUI showed still the 41% position and so and waited for the regular poll period to update the tem position to 0 (~15minutes later):

 2020-04-16 11:57:00.882 [vent.ItemStateChangedEvent] - AZ_Rollladen_Schalter changed from 41 to 0
==> /var/log/openhab2/openhab.log <==
2020-04-16 11:57:00.882 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-04-16 11:57:00.882 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2938: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 112
==> /var/log/openhab2/events.log <==
2020-04-16 11:57:00.883 [GroupItemStateChangedEvent] - gBlindsHabitableRoom changed from UNDEF to 0 through AZ_Rollladen_Schalter
2020-04-16 11:57:00.883 [GroupItemStateChangedEvent] - gBlinds changed from UNDEF to 0 through AZ_Rollladen_Schalter
==> /var/log/openhab2/openhab.log <==
2020-04-16 11:57:00.885 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-04-16 11:57:00.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-16 11:57:00.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-16 11:57:00.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2938: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 112
2020-04-16 11:57:00.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-04-16 11:57:00.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:57:00.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:57:00.892 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-04-16 11:57:00.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:57:00.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:57:00.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2938: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 112
2020-04-16 11:57:00.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-16 11:57:00.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2938: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 112
2020-04-16 11:57:00.893 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:57:00.893 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: sentData successfully placed on stack.
2020-04-16 11:57:00.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2938: Advanced to WAIT_REQUEST
2020-04-16 11:57:00.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 2938: Transaction not completed
2020-04-16 11:57:00.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:57:00.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:57:00.996 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 70 00 00 0B 90 
2020-04-16 11:57:00.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=112, payload=70 00 00 0B 
2020-04-16 11:57:00.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=112, payload=70 00 00 0B 
2020-04-16 11:57:00.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2938: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 112
2020-04-16 11:57:00.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-16 11:57:00.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2938: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 112
2020-04-16 11:57:00.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2938: (Callback 112)
2020-04-16 11:57:00.997 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-04-16 11:57:00.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2938: callback 112
2020-04-16 11:57:00.997 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=112, payload=70 00 00 0B 
2020-04-16 11:57:00.998 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: SendData Request. CallBack ID = 112, Status = Transmission complete and ACK received(0)
2020-04-16 11:57:00.998 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:57:00.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2938: Advanced to WAIT_DATA
2020-04-16 11:57:00.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 2938: Transaction not completed
2020-04-16 11:57:00.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:57:00.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:57:01.235 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 14 0E 32 02 21 44 00 00 00 12 00 00 00 00 00 00 B2 
2020-04-16 11:57:01.236 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 32 02 21 44 00 00 00 12 00 00 00 00 00 00 
2020-04-16 11:57:01.236 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0E 32 02 21 44 00 00 00 12 00 00 00 00 00 00 
2020-04-16 11:57:01.236 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:57:01.236 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:DONE)
2020-04-16 11:57:01.237 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:57:01.237 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-04-16 11:57:01.237 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_METER
2020-04-16 11:57:01.237 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-04-16 11:57:01.237 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 20: Meter: Type=Electric(1), Scale=kWh(0), Value=0.18
2020-04-16 11:57:01.238 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-04-16 11:57:01.238 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0.18
2020-04-16 11:57:01.238 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating channel state zwave:device:57fa8ae6:node20:meter_kwh to 0.18 [DecimalType]
2020-04-16 11:57:01.238 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2020-04-16 11:57:01.239 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@d814eb.
2020-04-16 11:57:01.239 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@d814eb.
2020-04-16 11:57:01.239 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:2938 DONE
2020-04-16 11:57:01.239 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-04-16 11:57:01.239 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-04-16 11:57:01.239 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:57:01.240 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:57:01.240 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-16 11:57:01.240 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 14 03 32 01 10 25 71 86 
2020-04-16 11:57:01.240 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 20: Sending REQUEST Message = 01 0A 00 13 14 03 32 01 10 25 71 86 
2020-04-16 11:57:01.240 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-04-16 11:57:01.241 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2939: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 113
2020-04-16 11:57:01.244 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-04-16 11:57:01.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-16 11:57:01.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-16 11:57:01.245 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2939: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 113
2020-04-16 11:57:01.245 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-04-16 11:57:01.245 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:57:01.245 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:57:01.251 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-04-16 11:57:01.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:57:01.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:57:01.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2939: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 113
2020-04-16 11:57:01.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-16 11:57:01.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2939: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 113
2020-04-16 11:57:01.252 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-04-16 11:57:01.252 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: sentData successfully placed on stack.
2020-04-16 11:57:01.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2939: Advanced to WAIT_REQUEST
2020-04-16 11:57:01.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 2939: Transaction not completed
2020-04-16 11:57:01.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:57:01.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:57:01.346 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 71 00 00 0A 90 
2020-04-16 11:57:01.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=113, payload=71 00 00 0A 
2020-04-16 11:57:01.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=113, payload=71 00 00 0A 
2020-04-16 11:57:01.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2939: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
2020-04-16 11:57:01.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-16 11:57:01.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2939: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
2020-04-16 11:57:01.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2939: (Callback 113)
2020-04-16 11:57:01.347 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-04-16 11:57:01.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2939: callback 113
2020-04-16 11:57:01.347 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=113, payload=71 00 00 0A 
2020-04-16 11:57:01.347 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: SendData Request. CallBack ID = 113, Status = Transmission complete and ACK received(0)
2020-04-16 11:57:01.348 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:57:01.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2939: Advanced to WAIT_DATA
2020-04-16 11:57:01.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 2939: Transaction not completed
2020-04-16 11:57:01.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:57:01.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-16 11:57:01.575 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 14 0A 32 02 21 32 00 00 00 00 00 00 D6 
2020-04-16 11:57:01.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0A 32 02 21 32 00 00 00 00 00 00 
2020-04-16 11:57:01.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0A 32 02 21 32 00 00 00 00 00 00 
2020-04-16 11:57:01.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-04-16 11:57:01.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:DONE)
2020-04-16 11:57:01.577 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: resetResendCount initComplete=true isDead=false
2020-04-16 11:57:01.577 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-04-16 11:57:01.577 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY NOT required on COMMAND_CLASS_METER
2020-04-16 11:57:01.577 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-04-16 11:57:01.577 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 20: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2020-04-16 11:57:01.577 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-04-16 11:57:01.578 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1
2020-04-16 11:57:01.578 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating channel state zwave:device:57fa8ae6:node20:meter_watts to 0 [DecimalType]
2020-04-16 11:57:01.578 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2020-04-16 11:57:01.578 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@19c2a04.
2020-04-16 11:57:01.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@19c2a04.
2020-04-16 11:57:01.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:2939 DONE
2020-04-16 11:57:01.579 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-04-16 11:57:01.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-04-16 11:57:01.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-04-16 11:57:01.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-16 11:57:01.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

I hope this helps for debugging the issue.
In my humble opinion it could maybe help if the ZWave Binding would frequently poll the device status when other values (e.g. motor power) change.

If you need get hands on a Rollershutter3 device to solve the issue, I would volunteer to sponsor one. Do you have a public Amazon wishlist where you could put it on? Then I’ll order it for you.

Cheers, Roland

What channel are you using? Is it the “Blinds Control” or “Blinds Control 1”?

It’s “blinds_control”. This is the item definition:

Rollershutter   AZ_Rollladen_Schalter           "Rollladen [%.0f]"      <blinds>    (gArbeitszimmer,gBlinds,gBlindsHabitableRoom)               {channel="zwave:device:57fa8ae6:node20:blinds_control"}

Try using blinds_control1 instead - that is the channel that is receiving the reports as far as I can tell. If that works ok, then we should remove the blinds_control channel from the database.

1 Like

Dear all,

I am using the FIBARO Roller Shutter since about a year. I think at the end it is no much of a problem.

Example to ensure, that the status is correctly updated:

rule "Status Refresh Jalousie SR"
when
  Item LIV_Jalousie_SR_Leistung1 changed
then
  if (LIV_Jalousie_SR_Leistung1.state == 0) LIV_Jalousie_SR_Schalter1.sendCommand("REFRESH")
end

This can be used for the two relevant “switches” that control the position of the blinds and the lamellas.

I am controlling the blinds with scenes. Example:

rule "Szenen Jalousien Bibliothek"
when
  Item LIB_Jalousie_Szene received command
then
  var Timer tLamellen   = null
  var Timer tRefresh    = null
  switch(receivedCommand.toString) {
// Jalousien schliessen
    case "1": {
      LIB_Jalousie_Lamellen.sendCommand(100)
      LIB_Jalousie.sendCommand(100)
      if ( tRefresh !== null ) {
        tRefresh.cancel
        tRefresh = null
      }
      tRefresh = createTimer(now.plusMinutes(1)) [
        LIB_Jalousie_Lamellen.sendCommand("REFRESH")
        LIB_Jalousie.sendCommand("REFRESH")
      ]
    }
// Jalousien öffnen
    case "2": {
      LIB_Jalousie_Lamellen.sendCommand(0)
      LIB_Jalousie.sendCommand(0)
      if ( tRefresh !== null ) {
        tRefresh.cancel
        tRefresh = null
      }
      tRefresh = createTimer(now.plusMinutes(1)) [
        LIB_Jalousie.sendCommand("REFRESH")
        LIB_Jalousie_Lamellen.sendCommand("REFRESH")
      ]
    }
// Jalousien Sonnenschutz
    case "3": {
      LIB_Jalousie.sendCommand(75)
      if ( tLamellen !== null ) {
        tLamellen.cancel
        tLamellen = null
      }
      tLamellen = createTimer(now.plusMinutes(1)) [ LIB_Jalousie_Lamellen.sendCommand(60) ]
      if ( tRefresh !== null ) {
        tRefresh.cancel
        tRefresh = null
      }
      tRefresh = createTimer(now.plusMinutes(1)) [
        LIB_Jalousie_Lamellen.sendCommand("REFRESH")
        LIB_Jalousie.sendCommand("REFRESH")
      ]
    }
// Jalousien lüften
    case "4": {
      LIB_Jalousie_Lamellen.sendCommand(60)
      if ( tRefresh !== null ) {
        tRefresh.cancel
        tRefresh = null
      }
      tRefresh = createTimer(now.plusMinutes(1)) [ LIB_Jalousie_Lamellen.sendCommand("REFRESH") ]
    }
    default: {
      logInfo("Szenen.rules", "Das Programm sollte nie hier her kommen.\nSzene Jalousie Bibliothek")
    }
  }
end

These scenes are called depending on the time of the day, by selections in the sitemap, by the weather forecast, etc.
Example:

rule "Sonnenschutz Kernzeiten"
when
  Item Azimut changed
then
  var Helligkeit      = ((Sensor1_Illuminance.state as Number).floatValue)
  var int Sonnenstand = (Azimut.state as Number).intValue
  var Winkel          = (Sonnenhoehe.state as Number).floatValue
  if ( Winkel >= 3 && Sonnenstand >= 60 && Sonnenstand <= 155 ) {
    if ( LIV_Sonnenschutz_O.state != ON ) LIV_Sonnenschutz_O.postUpdate(ON)
  } else {
    LIV_Sonnenschutz_O.postUpdate(OFF)
  }
  if ( Winkel >= 3 && Sonnenstand >= 80 && Sonnenstand <= 195 ) {
    if ( LIB_Sonnenschutz.state   != ON ) LIB_Sonnenschutz.postUpdate(ON)
  } else {
    LIB_Sonnenschutz.postUpdate(OFF)
  }
  if ( Winkel >= 3 && Sonnenstand >= 80 && Sonnenstand <= 245 ) {
    if ( LIV_Sonnenschutz_S.state != ON ) LIV_Sonnenschutz_S.postUpdate(ON)
  } else {
    LIV_Sonnenschutz_S.postUpdate(OFF)
  }
  if ( Winkel >= 3 && Sonnenstand >= 80 && Sonnenstand <= 245 ) {
    if ( BED_Sonnenschutz.state   != ON ) BED_Sonnenschutz.postUpdate(ON)
  } else {
    BED_Sonnenschutz.postUpdate(OFF)
  }
  if ( Winkel >= 3 && Sonnenstand >= 260 && Sonnenstand <= 305 ) {
    if ( KUC_Sonnenschutz.state   != ON ) KUC_Sonnenschutz.postUpdate(ON)
  } else {
    KUC_Sonnenschutz.postUpdate(OFF)
  }
end

I hardly use the wall switches anymore. If someone is interested I can post all the rule files.

Hi Chris,

I quickly changed the item definition to

Rollershutter   KUE_Rollladen_Schalter          "Rollladen [%.0f]"      <blinds>    (gKueche,gBlinds,gBlindsSouth,gBlindsHabitableRoom)         {channel="zwave:device:57fa8ae6:node19:blinds_control"}

and it works like a charm! I always wondered why there are two sets of channels. So it might make sense to remove the channels without the “1”. But this would probably be a breaking change to everybody using these channels.
What is the channel blinds_control2 for?

Many thanks for your help!

Best regards,
Roland

You do not say what software you are using? OH2.4, 2.5? I suspect that it will be different depending on what version you have due to the way endpoints and associations are managed.

Yes, but new users of OH2.5 will find, as you did, that it doesn’t work with the blinds_control channel due to the way that multi channel associations are now used. So, it’s either a breaking change, or just broken :wink:

I’m not sure to be honest - I don’t have this device. It will be linked with association group 3, but it’s not clear from the manual exactly how it’s used.

1 Like

I have been using 2.5 - now production release and prior to that I used the snapshots. The RS3 did not work with 2.4

Here the item definitions for a RS3:

Rollershutter		LIV_Jalousie_SR_Schalter	"Jalousie Wohnzimmer Süd Rechts [%.0f]"		<blinds>	(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:blinds_control"}
Number			LIV_Jalousie_SR_Verbrauch	"Jalousie WZ SR Verbrauch [%.1f kW/h]"		<energy>	(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:meter_kwh"}
Number			LIV_Jalousie_SR_Leistung	"Jalousie WZ SR Leistung [%.1f Watt]"		<energy>	(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:meter_watts"}
Number			LIV_Jalousie_SR_Scene		"Jalousie WZ SR Scene [%.0f]"			<blinds>	(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:scene_number"}
Switch			LIV_Jalousie_SR_Alarm_Strom	"Überstromalarm: Jalousie WZ SR [%s]"		<alarm>		(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:alarm_power"}
Switch			LIV_Jalousie_SR_Alarm_System	"Systemalarm: Jalousie WZ SR [%s]"		<alarm>		(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:alarm_system"}
Rollershutter		LIV_Jalousie_SR_Schalter1	"Jalousie Wohnzimmer Süd Rechts [%.0f]"		<blinds>	(F3_Wohnen, gZWave, gJalousie)		["WindowCovering"] {channel="zwave:device:e3f38fb6:node17:blinds_control1"}
Number			LIV_Jalousie_SR_Verbrauch1	"Jalousie WZ SR Verbrauch 1 [%.1f kW/h]"	<energy>	(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:meter_kwh1"}
Number			LIV_Jalousie_SR_Leistung1	"Jalousie WZ SR Leistung 1 [%.1f Watt]"		<energy>	(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:meter_watts1"}
Switch			LIV_Jalousie_SR_Alarm_Strom1	"Überstromalarm: Jalousie WZ SR 1 [%s]"		<alarm>		(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:alarm_power1"}
Switch			LIV_Jalousie_SR_Alarm_System1	"Systemalarm: Jalousie WZ SR 1 [%s]"		<alarm>		(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:alarm_system1"}
Rollershutter		LIV_Jalousie_SR_Schalter2	"Lamellen Wohnzimmer Süd Rechts [%.0f]"		<blinds>	(F3_Wohnen, gZWave, gJalousie)		{channel="zwave:device:e3f38fb6:node17:blinds_control2"}

The …Schalter (Switch) controls the up and down, but I never use it in rules or the sitemap. It seams to be the same as Switch1
The …Schalter1 (Switch 1) controls the up and down of the blinds
The …Schalter2 (Switch 2) controls the tilt of the lamellas, if you have venetian blinds

Hi Roland, hi Chris,

strange - I tried the same but for me it didn’t work. The device does not send any regular updates of position neither on “blinds_control” nor on “blinds_control1”.
It only sends position shortly after receiving a command or after it gets explicitly polled.

As a sidenote: I got this FGR-223 only working since I healed my network so often such that the FGR-223 is now connected directly with the controller. When it was connected only indirectly via the mesh, nothing wanted to work properly, not even configuration updates.

Regards,
Stefan D.

Hi Stefan,

did you try to activate DEBUG log to see which updates are being sent on which channels?
Here’s how to do so: https://www.cd-jackson.com/index.php/openhab/5-zwave-debugging-openhab
Also the Log Viewer at https://www.cd-jackson.com/index.php/openhab/zwave-log-viewer is very helpful for understanding the log messages.

Cheers,
Roland

Hi, I’ve the same issue but changing to blinds_control1 doesn’t help …

12:44:53.020 [INFO ] [smarthome.event.ItemCommandEvent ] - Item ‘shutter_bad_julie’ received command 0
12:44:53.024 [INFO ] [arthome.event.ItemStatePredictedEvent] - shutter_bad_julie predicted to become 0
12:44:53.048 [INFO ] [smarthome.event.ItemStateChangedEvent] - shutter_bad_julie changed from 100 to 0
12:44:54.751 [INFO ] [smarthome.event.ItemStateChangedEvent] - shutter_bad_julie changed from 0 to 94

Rollershutter shutter_bad_julie “Julie Rolluik Bad [%d %%]” {channel=“zwave:device:e6a56118:node5:blinds_control1”}

OH 2.5.4, Raspian, frg-223 FW5.1

I have had the same issue with my roller shutters fgr223 with firmware 5.1 (openHAB 2.5.4-1). After I changed the channel to blinds_control1 now the position is returned without problem when I operate manually via the physical switches. Perfect.

The problem is that when I press the up or down buttons in basic ui the device gets polled much too quickly after the command is sent. The movement is not yet finished. I can not alter the Command Poll Period. It is fixed to 1500 ms.

My office shutter manages only 9% of the way in those 1,5 seconds. You can see that at 14:00:33.721 the predicted value is set, and at 14:00:35.308 after 1500 ms the device is polled and this value is set. The movement is not completed though, so this value is not the final position.

2020-05-25 14:00:33.682 [ome.event.ItemCommandEvent] - Item 'GF_Office_Shutter' received command UP

2020-05-25 14:00:33.701 [nt.ItemStatePredictedEvent] - GF_Office_Shutter predicted to become UP

2020-05-25 14:00:33.721 [vent.ItemStateChangedEvent] - GF_Office_Shutter changed from 100 to 0 

2020-05-25 14:00:35.308 [vent.ItemStateChangedEvent] - GF_Office_Shutter changed from 0 to 91

What I did is disable the Command Poll Period altogether, then the predicted value is kept. It would be nice to be able to give a reasonable time here though, depending on what you are controlling. I have a big awning that I also control with a fgr223, and it takes almost half a minute to fully deploy.

I put the Polling Period to the shortest possible time, which is 10 minutes. So latest after 10 minutes actual position and set value should align again.

I did the same here. The Fibaro Rollershutter seem to be a bit stupid, since the Command Poll Period is kicking when movement has started rather than when movement has ended, which would make much more sense in this case.
I “fixed” that by disabling the Command Poll Period by setting it to 0 like you did.