Roller Shutter commands not working

[Matt77 (Matt)]

Hi,

i just updated the system of one of my friends from 2.1 to 2.3 and now the roller shutter items behaving quite weird.

as soon as they receive an “UP” command they changing to “0” as expected but immediately after that to “100”

its only happening after the update.

did i missed any required changes which needs to be done to the roller shutter items after the upgrade to 2.3?

2018-07-01 18:34:59.324 [ome.event.ItemCommandEvent] - Item 'S_LR_Shutter1_S' received command UP

2018-07-01 18:34:59.350 [vent.ItemStateChangedEvent] - S_LR_Shutter1_S changed from 100 to 0

2018-07-01 18:35:01.438 [vent.ItemStateChangedEvent] - S_LR_Shutter1_S changed from 0 to 100

[Udo_Hartmann]

What type of binding? What’s the configuration of items?

[Matt77 (Matt)]

@Udo_Hartmann

using the zwave binding 2.3.0 wit fibaro roller shutter FRG2222

/* Prefixes:
 * H = House
 * R = Room
 * C = Cluster
 * S = Single
 *
 * Room Abbreviations:
 * LR = Living Room
 * BR = Bedroom
 * HW = Hallway
 * GR = Guestroom
 * KR = Kidsroom
 * KI = Kitchen
 * BA_GF = Bathroom - Ground Floor
 * BA_FF = Bathroom - First Floor
 * FL = Fireside Lounge
 * OF = Office
 * DS = Dresser
 * PR = Playroom
 * ST = Storrage
 *
 * FLOOR Abbreviations:
 * GF = Ground Floor
 * FF = First Floor
 * BM = Basement
 * OD = Outdoor
 * AT = Attic
 * GA = Garage
 *
 * Postfixes:
 * S = Switch
 * D = Dimmer
 * T = Temperature
 * C = Color
 * B = Battery
 * W = Watt
 * K = KWh
 * 
 *
 * itemtype itemname ["labeltext"] [<iconname>] [(group1, group2, ...)] [{bindingconfig}]
 */

/*
 * House Shutter Groups
 */
Group H_Shutter "All Shutters"
Group:Rollershutter:OR(UP, DOWN)	H_Shutter_S	"All Shutters"
Group:Number:SUM H_Shutter_W "All Shutters Watt"	(H_POWER_W)
Group:Number:SUM H_Shutter_K "All Shutters KWh"		(H_POWER_K)

/*
 * Room Groups
 */

 Group R_OF_Shutter "All Office Shutters" (H_Shutter, R_OF)
 Group:Rollershutter:OR(UP, DOWN)	R_OF_Shutter_S		"Office Shutters"			(H_Shutter_S)
 Group:Number:SUM 					R_OF_Shutter_W 		"All Shutters Watt"			(H_Shutter_W)
 Group:Number:SUM 					R_OF_Shutter_K 		"All Shutters KWh"			(H_Shutter_K)
 
 Group R_BA_GF_Shutter "All Bathroom Shutters" (H_Shutter, R_BA_GF)
 Group:Rollershutter:OR(UP, DOWN)	R_BA_GF_Shutter_S	"Bathroom Shutters"			(H_Shutter_S)
 Group:Number:SUM 					R_BA_GF_Shutter_W 		"All Shutters Watt"		(H_Shutter_W)
 Group:Number:SUM 					R_BA_GF_Shutter_K 		"All Shutters KWh"		(H_Shutter_K)
 
 Group R_KI_Shutter "All Kitchen Shutters" (H_Shutter, R_KI)
 Group:Rollershutter:OR(UP, DOWN)	R_KI_Shutter_S		"Kitchen Shutters"		(H_Shutter_S)
 Group:Number:SUM 					R_KI_Shutter_W 		"Shutters Watt"			(H_Shutter_W)
 Group:Number:SUM 					R_KI_Shutter_K 		"Shutters KWh"			(H_Shutter_K)
 
 Group R_HW_Shutter "All Hallway Shutters" (H_Shutter, R_HW)
 Group:Rollershutter:OR(UP, DOWN)	R_HW_Shutter_S		"Hallway Shutters"		(H_Shutter_S)
 Group:Number:SUM 					R_HW_Shutter_W 		"Shutters Watt"			(H_Shutter_W)
 Group:Number:SUM 					R_HW_Shutter_K 		"Shutters KWh"			(H_Shutter_K)
 
 Group R_FL_Shutter "Fireside Lounge Shutters" (H_Shutter, R_FL)
 Group:Rollershutter:OR(UP, DOWN)	R_FL_Shutter_S		"Fireside Lounge Shutters"	(H_Shutter_S)
 Group:Number:SUM 					R_FL_Shutter_W 		"Shutters Watt"			(H_Shutter_W)
 Group:Number:SUM 					R_FL_Shutter_K 		"Shutters KWh"			(H_Shutter_K)

 Group R_LR_Shutter "All Livingroom Shutters" (H_Shutter, R_LR)
 Group:Rollershutter:OR(UP, DOWN)	R_LR_Shutter_S		"Livingroom Shutters"	(H_Shutter_S)
 Group:Number:SUM 					R_LR_Shutter_W 		"Shutters Watt"			(H_Shutter_W)
 Group:Number:SUM 					R_LR_Shutter_K 		"Shutters KWh"			(H_Shutter_K)
 
 Group R_KR_Shutter "All Kids Room Shutters" (H_Shutter, R_KR)
 Group:Rollershutter:OR(UP, DOWN)	R_KR_Shutter_S		"Kids room Shutters"	(H_Shutter_S)
 Group:Number:SUM 					R_KR_Shutter_W 		"Shutters Watt"			(H_Shutter_W)
 Group:Number:SUM 					R_KR_Shutter_K 		"Shutters KWh"			(H_Shutter_K)
 

 /*
 * Cluster Shutter
 */
 Group C_LR_Shutter "Livingroom Shutter" <light> (R_LR_Shutter)
 Group:Rollershutter:OR(UP, DOWN)	C_LR_Shutter_S 		"Livingroom Shutter" (R_LR_Shutter_S, R_LR_Shutter)
 Group:Number:SUM 					C_LR_Shutter_W 		"Shutters Watt"			(R_LR_Shutter_W)
 Group:Number:SUM 					C_LR_Shutter_K 		"Shutters KWh"			(R_LR_Shutter_K)
 
 
/*
 * Single Shutter
 */

/*
 * Office
 */ 
 Rollershutter 	S_OF_Shutter_S		"Rollo Büro" (R_OF_Shutter_S, R_OF_Shutter, H_Shutter_S)	[ Switchable ]	{channel="zwave:device:15c214568f7:node5:blinds_control"}
 Number			S_OF_Shutter_W 		"All Shutters Watt"		(R_OF_Shutter_W, R_OF_Shutter)
 Number			S_OF_Shutter_K 		"All Shutters KWh"		(R_OF_Shutter_K, R_OF_Shutter)
 
/*
 * Bathroom GF  
 */
 Rollershutter 	S_BA_GF_Shutter_S 	"Rollo Badezimmer" (R_BA_GF_Shutter_S, R_BA_GF_Shutter, H_Shutter_S)	[ Switchable ]	{channel="zwave:device:15c214568f7:node6:blinds_control"}
 Number			S_BA_GF_Shutter_W 	"Bathroom Shutters Watt"		(R_BA_GF_Shutter_W, R_BA_GF_Shutter)
 Number			S_BA_GF_Shutter_K 	"Bathroom Shutters KWh"		(R_BA_GF_Shutter_K, R_BA_GF_Shutter)
 
/*
 * Kitchen 
 */
 Rollershutter 	S_KI_Shutter_S 		"Rollo Küche" (R_KI_Shutter_S, R_KI_Shutter, H_Shutter_S)	[ Switchable ]	{channel="zwave:device:15c214568f7:node7:blinds_control"}
 Number			S_KI_Shutter_W 		"Kitchen Shutter Watt"		(R_KI_Shutter_W, R_KI_Shutter)
 Number			S_KI_Shutter_K 		"Kitchen Shutter KWh"		(R_KI_Shutter_K, R_KI_Shutter)
 
/*
 * Entrance 
 */
 Rollershutter 	S_HW_Shutter_S 		"Rollo Eingang" (R_HW_Shutter_S, R_HW_Shutter, H_Shutter_S)	[ Switchable ]	{channel="zwave:device:15c214568f7:node8:blinds_control"}
 Number			S_HW_Shutter_W 		"Entrance Shutter Watt"		(R_KI_Shutter_W, R_KI_Shutter)
 Number			S_HW_Shutter_K 		"Entrance Shutter KWh"		(R_KI_Shutter_K, R_KI_Shutter)
 
/*
 * Fireside Lounge 
 */
 Rollershutter 	S_FL_Shutter_S 		"Rollo Kaminzimmer" (R_FL_Shutter_S, R_FL_Shutter, H_Shutter_S)	[ Switchable ]	{channel="zwave:device:15c214568f7:node11:blinds_control"}
 Number			S_FL_Shutter_W 		"Fireside Lounge Shutters Watt"		(R_FL_Shutter_W, R_FL_Shutter)
 Number			S_FL_Shutter_K 		"Fireside Lounge Shutters KWh"		(R_FL_Shutter_K, R_FL_Shutter)
 
/*
 * Living Room
 */ 
 Rollershutter 	S_LR_Shutter1_S 	"Rollo Wohnzimmer links" (C_LR_Shutter_S, C_LR_Shutter, H_Shutter_S)	[ Switchable ]	{channel="zwave:device:15c214568f7:node9:blinds_control"}
 Number			S_LR_Shutter1_W 	"Livingroom Shutters Watt"		(C_LR_Shutter_W, C_LR_Shutter)
 Number			S_LR_Shutter1_K 	"Livingroom Shutters KWh"		(C_LR_Shutter_K, C_LR_Shutter)
 Rollershutter	S_LR_Shutter2_S 	"Rollo Wohnzimmer rechts" (C_LR_Shutter_S, C_LR_Shutter, H_Shutter_S)	[ Switchable ]	{channel="zwave:device:15c214568f7:node10:blinds_control"}
 Number			S_LR_Shutter2_W 	"Livingroom Shutters Watt"		(C_LR_Shutter_W, C_LR_Shutter)
 Number			S_LR_Shutter2_K 	"Livingroom Shutters KWh"		(C_LR_Shutter_K, C_LR_Shutter)
 
/*
 * Kids Room 
 */
 Rollershutter	S_KR_Shutter_S		"Rollo Kinderzimmer"	(R_KR_Shutter_S, R_KR_Shutter, H_Shutter_S)	[ Switchable ]	{channel="zwave:device:15c214568f7:node4:blinds_control"}
 Number			S_KR_Shutter_W 		"Julians Room Shutter Watt"		(R_KR_Shutter_W, R_KR_Shutter)	{channel="zwave:device:15c214568f7:node4:meter_watts"}
 Number			S_KR_Shutter_K 		"Julians Room Shutter KWh"		(R_KR_Shutter_K, R_KR_Shutter)	{channel="zwave:device:15c214568f7:node4:meter_kwh"}
 

[Udo_Hartmann]

Maybe @chris has a suggestion?

[chris (Chris Jackson)]

Sorry, but I’ve not really any ideas - at least not from the information supplied. Please check the debug logs to see what commands are being sent. The other thing to check is that there’s no invert option set on the channel.

[Matt77 (Matt)]

Hi @chris,

i noticed that the items is getting changed to 100% after the UP command, ist that correct?
cause if i remember right in OH 2.1 it was 0%
Node 9 is the on i tested it in this case.

2018-07-01 21:15:05.890 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:15c214568f7:node9:blinds_control --> UP
2018-07-01 21:15:05.920 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 9: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2018-07-01 21:15:05.936 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2018-07-01 21:15:05.939 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-07-01 21:15:05.954 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 09 05 26 04 20 00 FF 25 10 24 
2018-07-01 21:15:05.967 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0C 00 13 09 05 26 04 20 00 FF 25 10 24 
2018-07-01 21:15:05.991 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-07-01 21:15:05.996 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:06.000 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2018-07-01 21:15:06.005 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2018-07-01 21:15:06.009 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2018-07-01 21:15:06.016 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: Sent Data successfully placed on stack.
2018-07-01 21:15:06.013 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 10 00 00 02 F9 
2018-07-01 21:15:06.023 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:06.026 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 10 00 00 02 00 00 F7 
2018-07-01 21:15:06.030 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 10 00 00 02 00 00 F7 
2018-07-01 21:15:06.034 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=10 00 00 02 
2018-07-01 21:15:06.037 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 16, Status = Transmission complete and ACK received(0)
2018-07-01 21:15:06.041 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2018-07-01 21:15:06.045 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1c584b2 already registered
2018-07-01 21:15:06.048 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=9, callback=16, payload=09 05 26 04 20 00 FF 
2018-07-01 21:15:06.052 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=10 00 00 02 
2018-07-01 21:15:06.055 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=16, expected=SendData, cancelled=false        transaction complete!
2018-07-01 21:15:06.058 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-07-01 21:15:06.062 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-07-01 21:15:06.065 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 9: Response processed after 85ms/4955ms.
2018-07-01 21:15:06.382 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 16 03 80 03 45 21 
2018-07-01 21:15:06.386 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:06.390 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 16 03 80 03 45 21 
2018-07-01 21:15:06.394 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 16 03 80 03 45 21 
2018-07-01 21:15:06.398 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 16 03 80 03 45 
2018-07-01 21:15:06.402 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Application Command Request (ALIVE:DONE)
2018-07-01 21:15:06.405 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 22: Starting initialisation from DONE
2018-07-01 21:15:06.409 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@be8bc7 already registered
2018-07-01 21:15:06.413 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Incoming command class BATTERY
2018-07-01 21:15:06.416 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 22: Received Battery Request
2018-07-01 21:15:06.420 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 22: Battery report value = 69
2018-07-01 21:15:06.424 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2018-07-01 21:15:06.428 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 22: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-07-01 21:15:06.432 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 16 06 43 03 01 42 01 90 75 
2018-07-01 21:15:06.432 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 22: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 69
2018-07-01 21:15:06.435 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 22: Updating channel state zwave:device:15c214568f7:node22:battery-level to 69 [DecimalType]
2018-07-01 21:15:06.445 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2018-07-01 21:15:06.450 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:06.454 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 16 06 43 03 01 42 01 90 75 
2018-07-01 21:15:06.457 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 16 06 43 03 01 42 01 90 75 
2018-07-01 21:15:06.460 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 16 06 43 03 01 42 01 90 
2018-07-01 21:15:06.463 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Application Command Request (ALIVE:DONE)
2018-07-01 21:15:06.466 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 22: Starting initialisation from DONE
2018-07-01 21:15:06.469 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@be8bc7 already registered
2018-07-01 21:15:06.473 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Incoming command class THERMOSTAT_SETPOINT
2018-07-01 21:15:06.476 [DEBUG] [.ZWaveThermostatSetpointCommandClass] - NODE 22: Received Thermostat Setpoint Request
2018-07-01 21:15:06.479 [DEBUG] [.ZWaveThermostatSetpointCommandClass] - NODE 22: Thermostat Setpoint report Scale = 0
2018-07-01 21:15:06.482 [DEBUG] [.ZWaveThermostatSetpointCommandClass] - NODE 22: Thermostat Setpoint Value = 4
2018-07-01 21:15:06.485 [DEBUG] [.ZWaveThermostatSetpointCommandClass] - NODE 22: Thermostat Setpoint Report, Type Heating (1), value = 4
2018-07-01 21:15:06.488 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveThermostatSetpointValueEvent
2018-07-01 21:15:06.491 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 22: Got an event from Z-Wave network: ZWaveThermostatSetpointValueEvent
2018-07-01 21:15:06.494 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 22: Got a value event from Z-Wave network, endpoint = 0, command class = THERMOSTAT_SETPOINT, value = 4
2018-07-01 21:15:06.497 [DEBUG] [converter.ZWaveCommandClassConverter] - Converted temperature from 4C to 4C
2018-07-01 21:15:06.500 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 22: Updating channel state zwave:device:15c214568f7:node22:thermostat_setpoint_heating to 4 [DecimalType]
2018-07-01 21:15:06.501 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 16 04 46 08 00 7F D2 
2018-07-01 21:15:06.508 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2018-07-01 21:15:06.511 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:06.515 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 16 04 46 08 00 7F D2 
2018-07-01 21:15:06.517 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 16 04 46 08 00 7F D2 
2018-07-01 21:15:06.520 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 16 04 46 08 00 7F 
2018-07-01 21:15:06.523 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Application Command Request (ALIVE:DONE)
2018-07-01 21:15:06.525 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 22: Starting initialisation from DONE
2018-07-01 21:15:06.528 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@be8bc7 already registered
2018-07-01 21:15:06.530 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Incoming command class CLIMATE_CONTROL_SCHEDULE
2018-07-01 21:15:06.533 [DEBUG] [veClimateControlScheduleCommandClass] - NODE 22: Received CLIMATE_CONTROL_SCHEDULE command V1
2018-07-01 21:15:06.535 [INFO ] [veClimateControlScheduleCommandClass] - NODE 22 reported: Override type: NO_OVERRIDE, ScheduleState: [UNUSED]
2018-07-01 21:15:06.538 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2018-07-01 21:15:06.569 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 16 02 84 07 64 
2018-07-01 21:15:06.572 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:06.575 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 04 00 16 02 84 07 64 
2018-07-01 21:15:06.578 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 08 00 04 00 16 02 84 07 64 
2018-07-01 21:15:06.581 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 16 02 84 07 
2018-07-01 21:15:06.583 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Application Command Request (ALIVE:DONE)
2018-07-01 21:15:06.586 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 22: Starting initialisation from DONE
2018-07-01 21:15:06.588 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@be8bc7 already registered
2018-07-01 21:15:06.591 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Incoming command class WAKE_UP
2018-07-01 21:15:06.594 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 22: Received Wake Up Request
2018-07-01 21:15:06.596 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 22: Received WAKE_UP_NOTIFICATION
2018-07-01 21:15:06.598 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 22: Is awake with 0 messages in the wake-up queue.
2018-07-01 21:15:06.601 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveWakeUpEvent
2018-07-01 21:15:06.604 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 22: Got an event from Z-Wave network: ZWaveWakeUpEvent
2018-07-01 21:15:06.619 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2018-07-01 21:15:07.620 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 22: No more messages, go back to sleep
2018-07-01 21:15:07.623 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 22: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2018-07-01 21:15:07.627 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-07-01 21:15:07.630 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 16 02 84 08 25 11 49 
2018-07-01 21:15:07.634 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 22: Sending REQUEST Message = 01 09 00 13 16 02 84 08 25 11 49 
2018-07-01 21:15:07.636 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 0. Queue={}
2018-07-01 21:15:07.650 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-07-01 21:15:07.654 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:07.658 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2018-07-01 21:15:07.661 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2018-07-01 21:15:07.665 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2018-07-01 21:15:07.668 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 22: Sent Data successfully placed on stack.
2018-07-01 21:15:07.687 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 11 00 00 04 FE 
2018-07-01 21:15:07.691 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:07.694 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 11 00 00 04 00 00 F0 
2018-07-01 21:15:07.697 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 11 00 00 04 00 00 F0 
2018-07-01 21:15:07.700 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=11 00 00 04 
2018-07-01 21:15:07.702 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 22: SendData Request. CallBack ID = 17, Status = Transmission complete and ACK received(0)
2018-07-01 21:15:07.705 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 22: Starting initialisation from DONE
2018-07-01 21:15:07.708 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@be8bc7 already registered
2018-07-01 21:15:07.711 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=22, callback=17, payload=16 02 84 08 
2018-07-01 21:15:07.714 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=11 00 00 04 
2018-07-01 21:15:07.717 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=17, expected=SendData, cancelled=false        transaction complete!
2018-07-01 21:15:07.720 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-07-01 21:15:07.723 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 22: Went to sleep
2018-07-01 21:15:07.726 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 22: Is sleeping
2018-07-01 21:15:07.728 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 22: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-07-01 21:15:07.731 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 22: Response processed after 90ms/4955ms.
2018-07-01 21:15:08.062 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 26 03 63 BE 
2018-07-01 21:15:08.065 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:08.068 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 26 03 63 BE 
2018-07-01 21:15:08.071 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 26 03 63 BE 
2018-07-01 21:15:08.074 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 26 03 63 
2018-07-01 21:15:08.077 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2018-07-01 21:15:08.079 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2018-07-01 21:15:08.081 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 09 06 31 05 04 22 00 00 EA 
2018-07-01 21:15:08.084 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1c584b2 already registered
2018-07-01 21:15:08.087 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class SWITCH_MULTILEVEL
2018-07-01 21:15:08.089 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 9: Received SWITCH_MULTILEVEL command V3
2018-07-01 21:15:08.091 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 9: Switch Multi Level report, value = 99
2018-07-01 21:15:08.094 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2018-07-01 21:15:08.096 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-07-01 21:15:08.098 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_MULTILEVEL, value = 99
2018-07-01 21:15:08.101 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:15c214568f7:node9:blinds_control to 100 [PercentType]
2018-07-01 21:15:08.106 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Transaction not completed: node address inconsistent.  lastSent=22, incoming=255
2018-07-01 21:15:08.125 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-07-01 21:15:08.130 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 09 06 31 05 04 22 00 00 EA 
2018-07-01 21:15:08.133 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 09 06 31 05 04 22 00 00 EA 
2018-07-01 21:15:08.136 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 06 31 05 04 22 00 00 
2018-07-01 21:15:08.139 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2018-07-01 21:15:08.141 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2018-07-01 21:15:08.144 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1c584b2 already registered
2018-07-01 21:15:08.147 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2018-07-01 21:15:08.148 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V2
2018-07-01 21:15:08.150 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2018-07-01 21:15:08.152 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Power(4), Scale = 0
2018-07-01 21:15:08.153 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 0E+1
2018-07-01 21:15:08.155 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2018-07-01 21:15:08.156 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2018-07-01 21:15:08.158 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 0E+1
2018-07-01 21:15:08.160 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:15c214568f7:node9:sensor_power to 0 [DecimalType]
2018-07-01 21:15:08.163 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 22: Transaction not completed: node address inconsistent.  lastSent=22, incoming=255

[chris (Chris Jackson)]

@Matt77

i noticed that the items is getting changed to 100% after the UP command, ist that correct?
cause if i remember right in OH 2.1 it was 0%

If it used to be 0, and now it’s 100, have you checked to see if there’s an invert option set on the channel?

[Matt77 (Matt)]

yes checked, and none of the channels is inverted.
if i use a percentage, with the slider on habpanel for the roller shutter, and put it on 0 or 100 the roller shutter is behaving as expected.

[chris (Chris Jackson)]

Looking at the log, the binding doesn’t set the value to 0 - only 100% -:

It sends the start level change command, then queries the state which comes back as 100%, and the binding is then setting the state to 100%. I don’t see a problem from this log at least and I don’t see where the 0% is coming from, but it doesn’t seem to be the binding.

[Matt77 (Matt)]

correct, the part i try to understand is:

1. what should be send to device with the Up command ? 0 or 100
2. why could i see the below in the logs if i send the UP command from habpanel or basic UI.
   where its first getting changed to 0 but the back to 100?

2018-07-01 21:15:05.864 [ome.event.ItemCommandEvent] - Item 'S_LR_Shutter1_S' received command UP
2018-07-01 21:15:05.893 [GroupItemStateChangedEvent] - C_LR_Shutter_S changed from 100 to 0 through S_LR_Shutter1_S
2018-07-01 21:15:05.917 [GroupItemStateChangedEvent] - R_LR_Shutter_S changed from 100 to 0 through C_LR_Shutter_S
2018-07-01 21:15:05.935 [vent.ItemStateChangedEvent] - S_LR_Shutter1_S changed from 100 to 0
2018-07-01 21:15:05.957 [GroupItemStateChangedEvent] - H_Shutter_S changed from 100 to 0 through R_LR_Shutter_S
2018-07-01 21:15:05.965 [GroupItemStateChangedEvent] - H_Shutter_S changed from 100 to 0 through S_LR_Shutter1_S
2018-07-01 21:15:08.119 [GroupItemStateChangedEvent] - R_LR_Shutter_S changed from 0 to 100 through C_LR_Shutter_S
2018-07-01 21:15:08.122 [GroupItemStateChangedEvent] - H_Shutter_S changed from 0 to 100 through S_LR_Shutter1_S
2018-07-01 21:15:08.124 [GroupItemStateChangedEvent] - C_LR_Shutter_S changed from 0 to 100 through S_LR_Shutter1_S
2018-07-01 21:15:08.127 [vent.ItemStateChangedEvent] - S_LR_Shutter1_S changed from 0 to 100

[Matt77 (Matt)]

just one other thing, im not sure if something changed or my understanding is wrong.
if i use % for a roller shutter in combination with the fibaro module, is 100% fully closed or fully open?

before it was fully closed.

[chris (Chris Jackson)]

@Matt77

• what should be send to device with the Up command ? 0 or 100

I don’t know - it might depend on how you have it wired. The binding doesn’t set 0 or 100 - it sends a “Start Move” command - the device itself will then interpret that however it likes.

@Matt77

1. why could i see the below in the logs if i send the UP command from habpanel or basic UI.
   where its first getting changed to 0 but the back to 100?

As I said earlier, I don’t know - it’s not coming from the binding based on the log you provided at least.

[sihui]

A long time ago I reported similar issues while using the invert percent option and those were solved via database update for the FGR222:

Fibaro Roller Shutter FGRM-222 with Venetian Blinds - Lamellas Position

Here is another example for node 32 with some more “changing” events: 2017-06-19 11:13:33.966 [ItemStateChangedEvent ] - FibFGR222_Living_South changed from 65 to 35 2017-06-19 11:13:34.228 [ItemStateChangedEvent ] - FibFGR222_Living_South changed from 35 to 65 2017-06-19 11:44:04.374 [ItemStateChangedEvent ] - FibFGR222_Living_South changed from 65 to 35 2017-06-19 11:44:04.492 [ItemStateChangedEvent ] - FibFGR222_Living_South changed from 35 to 65 2017-06-19 12:45:04.537 [Item…

Fibaro Roller Shutter FGRM-222 with Venetian Blinds - Lamellas Position

We could update the database to stop BASIC messages being interpreted in this way (i.e. uncheck the BASIC tick box in the ML switch)- I guess the question is if the device is sending enough other messages that it’s still useful? I think from the logs I saw that it is, but…….

So deleting the thing and readding it again could be a solution.