[Solved] Rollershutter position not correctly updated in 2.4 RC1

maihacke · December 17, 2018, 7:40am

I recently upgraded to OH 2.4 RC1, since that the position of my rollershutters is not correctly measured.
I use fibaro roller shutter 2 with Aeon ZStick 5. There is no change in the setup except the upgrade from 2.3 to 2.4 M8 and then 2.4 RC1. I think the error occured with RC1, but it could be also there with M8, im not quite sure.
The problem is, that position of the shutters is not correctly reported any more. The normal behaviour is that, when is send, e.g. the UP command to the shutters, the state changed to 0 and then OH received updates from 100 to 0. And finally stored 0 (or sometimes a small other number). Now it seems like the position updates get lost. OH receives only one update some where in the middle between 0 to 100 and stores this as the final shutter position. So in the and the shutters are not “open” in OH.
Here is a part of the event.log, what happens when opening all shutters in the ground floor:

2018-12-17 08:25:02.231 [ome.event.ItemCommandEvent] - Item ' Shutter_EG _Wohnen_Schiebetuer' received command 0
2018-12-17 08:25:02.273 [ome.event.ItemCommandEvent] - Item ' Shutter_EG _Wohnen_Schiebetuer_1' received command 0
2018-12-17 08:25:02.326 [ome.event.ItemCommandEvent] - Item ' Shutter_EG _Wohnen_Strasse' received command 0
2018-12-17 08:25:02.396 [ome.event.ItemCommandEvent] - Item ' Shutter_EG _Wohnen_Essen' received command 0
2018-12-17 08:25:02.425 [ome.event.ItemCommandEvent] - Item ' Shutter_EG _Kueche' received command 0
2018-12-17 08:25:02.454 [ome.event.ItemCommandEvent] - Item ' Shutter_EG _Schlafen' received command 0
2018-12-17 08:25:02.464 [ome.event.ItemCommandEvent] - Item ' Shutter_EG _Buero_Strasse' received command 0
2018-12-17 08:25:02.598 [ome.event.ItemCommandEvent] - Item ' Shutter_EG _Buero_Garten' received command 0
2018-12-17 08:25:02.692 [nt.ItemStatePredictedEvent] - Shutter_EG _Wohnen_Schiebetuer predicted to become 0
2018-12-17 08:25:02.810 [nt.ItemStatePredictedEvent] - Shutter_EG _Wohnen_Schiebetuer_1 predicted to become 0
2018-12-17 08:25:02.831 [nt.ItemStatePredictedEvent] - Shutter_EG _Wohnen_Strasse predicted to become 0
2018-12-17 08:25:02.872 [nt.ItemStatePredictedEvent] - Shutter_EG _Wohnen_Essen predicted to become 0
2018-12-17 08:25:02.957 [nt.ItemStatePredictedEvent] - Shutter_EG _Kueche predicted to become 0
2018-12-17 08:25:02.980 [nt.ItemStatePredictedEvent] - Shutter_EG _Schlafen predicted to become 0
2018-12-17 08:25:03.003 [nt.ItemStatePredictedEvent] - Shutter_EG _Buero_Strasse predicted to become 0
2018-12-17 08:25:03.042 [nt.ItemStatePredictedEvent] - Shutter_EG _Buero_Garten predicted to become 0
2018-12-17 08:25:03.224 [GroupItemStateChangedEvent] - Shutters_EG changed from 100 to 0 through Shutter_EG _Wohnen_Schiebetuer
2018-12-17 08:25:03.246 [GroupItemStateChangedEvent] - Shutters_Sued changed from 100 to 0 through Shutter_EG _Wohnen_Schiebetuer
2018-12-17 08:25:03.256 [GroupItemStateChangedEvent] - Shutters_Wohnen_Schiebetuer changed from 100 to 0 through Shutter_EG _Wohnen_Schiebetuer
2018-12-17 08:25:03.289 [vent.ItemStateChangedEvent] - Shutter_EG _Wohnen_Schiebetuer changed from 100 to 0
2018-12-17 08:25:03.318 [vent.ItemStateChangedEvent] - Shutter_EG _Wohnen_Strasse changed from 100 to 0
2018-12-17 08:25:03.323 [vent.ItemStateChangedEvent] - Shutter_EG _Wohnen_Schiebetuer_1 changed from 100 to 0
2018-12-17 08:25:03.326 [vent.ItemStateChangedEvent] - Shutter_EG _Wohnen_Essen changed from 7 to 0
2018-12-17 08:25:03.419 [vent.ItemStateChangedEvent] - Shutter_EG _Kueche changed from 6 to 0
2018-12-17 08:25:03.455 [vent.ItemStateChangedEvent] - Shutter_EG _Schlafen changed from 3 to 0
2018-12-17 08:25:03.537 [vent.ItemStateChangedEvent] - Shutter_EG _Buero_Strasse changed from 5 to 0
2018-12-17 08:25:03.550 [vent.ItemStateChangedEvent] - Shutter_EG _Buero_Garten changed from 100 to 0
2018-12-17 08:25:03.706 [GroupItemStateChangedEvent] - Shutters_Ost changed from 100 to 0 through Shutter_EG _Schlafen
2018-12-17 08:25:06.110 [vent.ItemStateChangedEvent] - Shutter_EG _Wohnen_Schiebetuer changed from 0 to 88
2018-12-17 08:25:06.631 [vent.ItemStateChangedEvent] - Shutter_EG _Wohnen_Schiebetuer_1 changed from 0 to 88
2018-12-17 08:25:06.649 [GroupItemStateChangedEvent] - Shutters_Wohnen_Schiebetuer changed from 0 to 100 through Shutter_EG _Wohnen_Schiebetuer_1
2018-12-17 08:25:07.300 [vent.ItemStateChangedEvent] - Shutter_EG _Kueche changed from 0 to 86
2018-12-17 08:25:07.653 [vent.ItemStateChangedEvent] - Shutter_EG _Wohnen_Strasse changed from 0 to 88
2018-12-17 08:25:08.135 [vent.ItemStateChangedEvent] - Shutter_EG _Wohnen_Essen changed from 0 to 88
2018-12-17 08:25:08.779 [vent.ItemStateChangedEvent] - Shutter_EG _Schlafen changed from 0 to 88
2018-12-17 08:25:09.386 [vent.ItemStateChangedEvent] - Shutter_EG _Buero_Strasse changed from 0 to 83

I tried to recalibrate all shutters, no difference.
I also checked the z-wave-debug-log there are no errors and it seems like there is only one update per shutter with the above values. Here is the output for

> grep 'NODE 5' openhab.log

2018-12-17 08:25:02.261 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Command received zwave:device:f057dadd:node5:blinds_control --&gt; 0 [PercentType]
2018-12-17 08:25:02.273 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 5 : Creating new message for command SWITCH_MULTILEVEL_SET
2018-12-17 08:25:02.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : SECURITY not supported
2018-12-17 08:25:02.294 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2018-12-17 08:25:02.298 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Adding to device queue
2018-12-17 08:25:02.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Added 881 to queue - size 3
2018-12-17 08:25:02.339 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5 : Sending REQUEST Message = 01 0A 00 13 05 03 26 01 00 25 25 C7 
2018-12-17 08:25:02.380 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Polling intialised at 86400 seconds - start in 1500 milliseconds.
2018-12-17 08:25:02.584 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5 : sentData successfully placed on stack.
2018-12-17 08:25:02.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : TID 881: Transaction not completed
2018-12-17 08:25:02.640 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5 : SendData Request. CallBack ID = 37, Status = Transmission complete and ACK received(0)
2018-12-17 08:25:02.643 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : resetResendCount initComplete=true isDead=false
2018-12-17 08:25:02.673 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Response processed after 314ms
2018-12-17 08:25:02.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : TID 881: Transaction completed
2018-12-17 08:25:02.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : notifyTransactionResponse TID:881 DONE
2018-12-17 08:25:02.732 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-12-17 08:25:03.872 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Polling...
2018-12-17 08:25:03.877 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Polling zwave:device:f057dadd:node5:blinds_control
2018-12-17 08:25:03.883 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 5 : Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2018-12-17 08:25:03.889 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 5 : Creating new message for command SWITCH_MULTILEVEL_GET
2018-12-17 08:25:03.892 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : SECURITY not supported
2018-12-17 08:25:03.905 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2018-12-17 08:25:03.908 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Polling zwave:device:f057dadd:node5:meter_watts
2018-12-17 08:25:03.930 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 5 : Generating poll message for COMMAND_CLASS_METER, endpoint 0
2018-12-17 08:25:03.932 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 5 : Generating poll message for COMMAND_CLASS_METER, endpoint 0
2018-12-17 08:25:03.936 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5 : Creating new message for application command METER_GET
2018-12-17 08:25:03.938 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : SECURITY not supported
2018-12-17 08:25:03.941 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : Command Class COMMAND_CLASS_METER is NOT required to be secured
2018-12-17 08:25:03.943 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Polling zwave:device:f057dadd:node5:meter_kwh
2018-12-17 08:25:03.957 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 5 : Generating poll message for COMMAND_CLASS_METER, endpoint 0
2018-12-17 08:25:03.960 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 5 : Generating poll message for COMMAND_CLASS_METER, endpoint 0
2018-12-17 08:25:03.962 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5 : Creating new message for application command METER_GET
2018-12-17 08:25:03.964 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : SECURITY not supported
2018-12-17 08:25:03.974 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : Command Class COMMAND_CLASS_METER is NOT required to be secured
2018-12-17 08:25:03.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Adding to device queue
2018-12-17 08:25:03.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Added 893 to queue - size 11
2018-12-17 08:25:04.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Adding to device queue
2018-12-17 08:25:04.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Added 894 to queue - size 12
2018-12-17 08:25:04.033 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Adding to device queue
2018-12-17 08:25:04.035 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Added 895 to queue - size 13
2018-12-17 08:25:05.907 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5 : Sending REQUEST Message = 01 09 00 13 05 02 26 02 25 31 D2 
2018-12-17 08:25:05.967 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5 : sentData successfully placed on stack.
2018-12-17 08:25:05.977 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : TID 893: Transaction not completed
2018-12-17 08:25:06.003 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5 : SendData Request. CallBack ID = 49, Status = Transmission complete and ACK received(0)
2018-12-17 08:25:06.006 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : resetResendCount initComplete=true isDead=false
2018-12-17 08:25:06.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : TID 893: Transaction not completed
2018-12-17 08:25:06.017 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Application Command Request (ALIVE:DONE)
2018-12-17 08:25:06.019 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : resetResendCount initComplete=true isDead=false
2018-12-17 08:25:06.021 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2018-12-17 08:25:06.023 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : SECURITY not supported
2018-12-17 08:25:06.025 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5 : Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2018-12-17 08:25:06.027 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 5 : Switch Multi Level report, value = 88
2018-12-17 08:25:06.029 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-12-17 08:25:06.032 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 88
2018-12-17 08:25:06.034 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Updating channel state zwave:device:f057dadd:node5:blinds_control to 88 [PercentType]
2018-12-17 08:25:06.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Commands processed 1.
2018-12-17 08:25:06.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1f6c842.
2018-12-17 08:25:06.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1f6c842.
2018-12-17 08:25:06.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : notifyTransactionResponse TID:893 DONE
2018-12-17 08:25:06.049 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-12-17 08:25:06.078 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5 : Sending REQUEST Message = 01 0A 00 13 05 03 32 01 10 25 32 D4 
2018-12-17 08:25:06.157 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5 : sentData successfully placed on stack.
2018-12-17 08:25:06.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : TID 894: Transaction not completed
2018-12-17 08:25:06.171 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Application Command Request (ALIVE:DONE)
2018-12-17 08:25:06.184 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : resetResendCount initComplete=true isDead=false
2018-12-17 08:25:06.186 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : Incoming command class COMMAND_CLASS_METER, endpoint 0
2018-12-17 08:25:06.189 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : SECURITY not supported
2018-12-17 08:25:06.191 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5 : Received COMMAND_CLASS_METER V2 METER_REPORT
2018-12-17 08:25:06.193 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5 : Meter: Type=Electric(1), Scale=W(2), Value=103.4
2018-12-17 08:25:06.195 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got an event from Z-Wave network: ZWaveMeterValueEvent
2018-12-17 08:25:06.198 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 103.4
2018-12-17 08:25:06.200 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Updating channel state zwave:device:f057dadd:node5:meter_watts to 103.4 [DecimalType]
2018-12-17 08:25:06.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Commands processed 1.
2018-12-17 08:25:06.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1f1539e.
2018-12-17 08:25:06.243 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5 : SendData Request. CallBack ID = 50, Status = Transmission complete and ACK received(0)
2018-12-17 08:25:06.245 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : resetResendCount initComplete=true isDead=false
2018-12-17 08:25:06.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : TID 894: Transaction not completed
2018-12-17 08:25:06.256 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Application Command Request (ALIVE:DONE)
2018-12-17 08:25:06.258 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : resetResendCount initComplete=true isDead=false
2018-12-17 08:25:06.260 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : Incoming command class COMMAND_CLASS_METER, endpoint 0
2018-12-17 08:25:06.262 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : SECURITY not supported
2018-12-17 08:25:06.264 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5 : Received COMMAND_CLASS_METER V2 METER_REPORT
2018-12-17 08:25:06.267 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5 : Meter: Type=Electric(1), Scale=W(2), Value=103.4
2018-12-17 08:25:06.269 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got an event from Z-Wave network: ZWaveMeterValueEvent
2018-12-17 08:25:06.271 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 103.4
2018-12-17 08:25:06.274 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Updating channel state zwave:device:f057dadd:node5:meter_watts to 103.4 [DecimalType]
2018-12-17 08:25:06.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Commands processed 1.
2018-12-17 08:25:06.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1430a50.
2018-12-17 08:25:06.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1430a50.
2018-12-17 08:25:06.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : notifyTransactionResponse TID:894 DONE
2018-12-17 08:25:06.288 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-12-17 08:25:06.304 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5 : Sending REQUEST Message = 01 0A 00 13 05 03 32 01 00 25 33 C5 
2018-12-17 08:25:06.335 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5 : sentData successfully placed on stack.
2018-12-17 08:25:06.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : TID 895: Transaction not completed
2018-12-17 08:25:06.364 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5 : SendData Request. CallBack ID = 51, Status = Transmission complete and ACK received(0)
2018-12-17 08:25:06.366 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : resetResendCount initComplete=true isDead=false
2018-12-17 08:25:06.371 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : TID 895: Transaction not completed
2018-12-17 08:25:06.377 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Application Command Request (ALIVE:DONE)
2018-12-17 08:25:06.379 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : resetResendCount initComplete=true isDead=false
2018-12-17 08:25:06.381 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : Incoming command class COMMAND_CLASS_METER, endpoint 0
2018-12-17 08:25:06.383 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5 : SECURITY not supported
2018-12-17 08:25:06.385 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5 : Received COMMAND_CLASS_METER V2 METER_REPORT
2018-12-17 08:25:06.388 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5 : Meter: Type=Electric(1), Scale=kWh(0), Value=3.43
2018-12-17 08:25:06.390 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got an event from Z-Wave network: ZWaveMeterValueEvent
2018-12-17 08:25:06.393 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 3.43
2018-12-17 08:25:06.395 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Updating channel state zwave:device:f057dadd:node5:meter_kwh to 3.43 [DecimalType]
2018-12-17 08:25:06.401 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Commands processed 1.
2018-12-17 08:25:06.407 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@74e9d2.
2018-12-17 08:25:06.410 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@74e9d2.
2018-12-17 08:25:06.414 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5 : notifyTransactionResponse TID:895 DONE
2018-12-17 08:25:06.417 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5 : Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

mstormi · December 17, 2018, 8:28am

There were changes (addition of FGRM-222 proprietary reporting Fibaro style) from 2.3 to 2.4 which likely is what you’re seeing, but it’s neither a bug nor anything recent related to milestone builds.
Search the forum and adjust your ZWave parameters (one of the first params is reporting type).

maihacke · December 17, 2018, 8:45am

Ah sorry did not see that.
I tried changing report type from zwave to fibaro, but that did not make any difference.
Maybe this helps [Solved] Rollershutter's position percentage not updating?

mstormi · December 17, 2018, 8:55am

You need to delete+regenerate all of your zwave things and .xml files, see zwave binding thread.

maihacke · December 17, 2018, 9:05am

I did this already, strictly following the migration guide.

mstormi · December 17, 2018, 9:10am

IIRC XML deletion isn’t part of migration guide, so force that (delete file, force regeneration through habmin)

maihacke · December 17, 2018, 3:08pm

Tried to

(A)

Delete Thing
Delete XML
Re-Add Thing

–> No position updates*

(B)

Changing Reports type from Z-Wave to Fibaro

–> No position updates*

©

Configuring all assiociation groups to controller

–> No position updates*

Sometime I see updates to position 254:

2018-12-17 15:00:23.406 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 254

maihacke · December 17, 2018, 6:37pm

Problem solved.
Deleted all z-wave devices again, this time including the z-stick.
Uninstalled z-wave-binding.
Stopped OH.
Deleted all Zwave-XMLs
Restarted OH.
Reinstalled Z-Wave-Binding
Readded Z-Stick
Readded all other Z-Wave-Things
Set new Z-Stick-ID in Zwave-Items

@mstormi: Thanks für your help

maihacke · December 17, 2018, 6:49pm

Maybe @chris should consider to upgrade the migration guide.
I think it is much safer to delete the controller also…

chris · December 17, 2018, 7:08pm

This should not be necessary with 2.4 as we removed the USB discovery.

maihacke · December 17, 2018, 8:20pm

I did reuse my existing z-stick-thing and things broke. I did another upgrade on another platform, were a new zstick appeared which I used, and things worked. Some my conclusion was that it might be better do delete and readd the zstick. But you have better insights, since this is your code. Maybe just something very weird happened on my system.

Btw thanks for the great binding

mstormi · December 31, 2018, 1:31pm

A post was split to a new topic: Exception on rollershutter item

Artyom_Syomushkin · August 18, 2019, 2:03pm

I think I have same problem with OpenHabian v1.5.
I have Rollershutter Fibaro FGR222. I have attached Rollershutter item to Blinds Control Channel.

The problem is that when I set the blinds to specific position, e.g. 0%, 100%, 77% etc Openhab reads the current shutter position after timeout defined in Command Poll Period (by default 1500ms). As shutters are only started at this moment, the returned position is something intermediate.
The solution would be to increase Command Poll Period to be longer than maximum rollershutter working time, but this parameter is limited to 15s. I had to disable command polling completely in order for OH to show command value and not response from actuator.

Thus the question - is there any way to get back set position of Rollershutter, and not intermediate?
I’ve tried both Blind positon report using Fibaro and Z-wave type - result is the same.

maihacke · August 18, 2019, 2:21pm

Try to assign your controller in the association group settings for the device for „controller updates“.