I have installed the above switch and encounter a very strange behaviour. The switch is able to switch two lights on/off. The zwave binding for switch1 and switch2 is configured exactly the same. Switch1 works fine. If Switch2 comes into play it gets strange (at least in my view): According to the zwave logs it reports to the configuration item switch_binary instead of switch_binary2. Is this an error in the binding?
This is my working config (2.2 snapshot)
Switch FibFGS223_1_Sw_1 (gRestore) { channel="zwave:device:15ca6a108b9:node30:switch_binary1" }
Switch FibFGS223_1_Sw_2 (gRestore) { channel="zwave:device:15ca6a108b9:node30:switch_binary2" }
So it looks correct to me. But I never debugged the switch because it is working 
We’d need to see the debug logs to see what data the device is sending to understand what is happening…
Here is the Debug Log when I manually press the Switch2 (Second Switch) once and after a couple of seconds the 2nd time:
18:37:32.916 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 08 05 03 20 01 FF 22
18:37:32.978 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:37:32.986 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 20 01 FF 2A
18:37:33.015 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 08 05 03 20 01 FF 22
18:37:33.069 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 08 05 04 26 01 FF 00 20
18:37:33.092 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 08 05 03 20 01 FF 22
18:37:33.139 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 05 04 26 01 FF 00 28
18:37:33.163 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=08 05 03 20 01 FF
18:37:33.249 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Application Command Request (ALIVE:DONE)
18:37:33.285 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
18:37:33.321 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@14ef683 already registered
18:37:33.377 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Incoming command class BASIC
18:37:33.411 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Received Basic Request
18:37:33.444 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic Set sent to the controller will be processed as Basic Report
18:37:33.487 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic report, value = 0xFF
18:37:33.520 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
18:37:33.559 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:37:33.602 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
18:37:33.654 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:2fb19967:node5:switch_binary to ON [OnOffType]
18:37:33.708 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=5, callback=70, payload=05 07 60 0D 01 02 32 01 10
18:37:33.771 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=08 05 03 20 01 FF
18:37:33.835 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=70, expected=ApplicationCommandHandler, cancelled=false transaction complete!
18:37:33.896 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:37:33.934 [DEBUG] [ecurityCommandClassWithInitialization] - NODE 5: updating lastSentMessageTimestamp
18:37:33.971 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:37:34.013 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=2
18:37:34.049 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 05 03 20 01 FF 2A
18:37:34.087 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 05 03 20 01 FF 2A
18:37:34.122 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 05 03 20 01 FF
18:37:34.175 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Application Command Request (ALIVE:DONE)
18:37:34.211 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
18:37:34.247 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@14ef683 already registered
18:37:34.303 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Incoming command class BASIC
18:37:34.337 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Received Basic Request
18:37:34.369 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic Set sent to the controller will be processed as Basic Report
18:37:34.411 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic report, value = 0xFF
18:37:34.446 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
18:37:34.485 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:37:34.528 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
18:37:34.579 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:2fb19967:node5:switch_binary to ON [OnOffType]
18:37:34.632 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=5, callback=70, payload=05 07 60 0D 01 02 32 01 10
18:37:34.694 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 05 03 20 01 FF
18:37:34.758 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=70, expected=ApplicationCommandHandler, cancelled=false transaction complete!
18:37:34.819 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:37:34.856 [DEBUG] [ecurityCommandClassWithInitialization] - NODE 5: updating lastSentMessageTimestamp
18:37:34.891 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:37:35.016 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=1
18:37:35.046 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 08 05 04 26 01 FF 00 20
18:37:35.085 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 08 05 04 26 01 FF 00 20
18:37:35.121 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=08 05 04 26 01 FF 00
18:37:35.174 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Application Command Request (ALIVE:DONE)
18:37:35.210 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
18:37:35.245 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@14ef683 already registered
18:37:35.301 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Incoming command class SWITCH_MULTILEVEL
18:37:35.337 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Received SWITCH_MULTILEVEL command V0
18:37:35.372 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Switch Multi Level SET
18:37:35.403 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Switch Multi Level report, value = 255
18:37:35.439 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
18:37:35.478 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:37:35.521 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_MULTILEVEL, value = 255
18:37:35.574 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=5, callback=70, payload=05 07 60 0D 01 02 32 01 10
18:37:35.636 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=08 05 04 26 01 FF 00
18:37:35.699 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=70, expected=ApplicationCommandHandler, cancelled=false transaction complete!
18:37:35.761 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:37:35.799 [DEBUG] [ecurityCommandClassWithInitialization] - NODE 5: updating lastSentMessageTimestamp
18:37:35.833 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:37:35.874 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:37:35.904 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 05 04 26 01 FF 00 28
18:37:35.944 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 05 04 26 01 FF 00 28
18:37:35.980 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 05 04 26 01 FF 00
18:37:36.039 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Application Command Request (ALIVE:DONE)
18:37:36.075 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
18:37:36.110 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@14ef683 already registered
18:37:36.165 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Incoming command class SWITCH_MULTILEVEL
18:37:36.201 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Received SWITCH_MULTILEVEL command V0
18:37:36.236 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Switch Multi Level SET
18:37:36.270 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Switch Multi Level report, value = 255
18:37:36.306 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
18:37:36.344 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:37:36.387 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_MULTILEVEL, value = 255
18:37:36.441 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=5, callback=70, payload=05 07 60 0D 01 02 32 01 10
18:37:36.504 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 05 04 26 01 FF 00
18:37:36.568 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=70, expected=ApplicationCommandHandler, cancelled=false transaction complete!
18:37:36.630 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:37:36.668 [DEBUG] [ecurityCommandClassWithInitialization] - NODE 5: updating lastSentMessageTimestamp
18:37:36.703 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:37:51.565 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 08 05 03 20 01 00 DD
18:37:51.623 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:37:51.628 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 20 01 00 D5
18:37:51.663 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 08 05 03 20 01 00 DD
18:37:51.703 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 08 05 04 26 01 00 00 DF
18:37:51.738 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 08 05 03 20 01 00 DD
18:37:51.854 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 05 04 26 01 00 00 D7
18:37:51.885 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=08 05 03 20 01 00
18:37:51.997 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Application Command Request (ALIVE:DONE)
18:37:52.035 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
18:37:52.072 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@14ef683 already registered
18:37:52.132 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Incoming command class BASIC
18:37:52.167 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Received Basic Request
18:37:52.201 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic Set sent to the controller will be processed as Basic Report
18:37:52.246 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic report, value = 0x00
18:37:52.285 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
18:37:52.328 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:37:52.381 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
18:37:52.434 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:2fb19967:node5:switch_binary to OFF [OnOffType]
18:37:52.491 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=5, callback=70, payload=05 07 60 0D 01 02 32 01 10
18:37:52.554 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=08 05 03 20 01 00
18:37:52.616 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=70, expected=ApplicationCommandHandler, cancelled=false transaction complete!
18:37:52.677 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:37:52.713 [DEBUG] [ecurityCommandClassWithInitialization] - NODE 5: updating lastSentMessageTimestamp
18:37:52.747 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:37:52.789 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=2
18:37:52.818 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 05 03 20 01 00 D5
18:37:52.855 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 05 03 20 01 00 D5
18:37:52.890 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 05 03 20 01 00
18:37:52.942 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Application Command Request (ALIVE:DONE)
18:37:52.978 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
18:37:53.012 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@14ef683 already registered
18:37:53.067 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Incoming command class BASIC
18:37:53.100 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Received Basic Request
18:37:53.130 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic Set sent to the controller will be processed as Basic Report
18:37:53.172 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic report, value = 0x00
18:37:53.204 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
18:37:53.242 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:37:53.284 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
18:37:53.333 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:2fb19967:node5:switch_binary to OFF [OnOffType]
18:37:53.390 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=5, callback=70, payload=05 07 60 0D 01 02 32 01 10
18:37:53.452 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 05 03 20 01 00
18:37:53.515 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=70, expected=ApplicationCommandHandler, cancelled=false transaction complete!
18:37:53.576 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:37:53.612 [DEBUG] [ecurityCommandClassWithInitialization] - NODE 5: updating lastSentMessageTimestamp
18:37:53.646 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:37:53.688 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=1
18:37:53.717 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 08 05 04 26 01 00 00 DF
18:37:53.754 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 08 05 04 26 01 00 00 DF
18:37:53.789 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=08 05 04 26 01 00 00
18:37:53.842 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Application Command Request (ALIVE:DONE)
18:37:53.877 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
18:37:53.910 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@14ef683 already registered
18:37:54.042 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Incoming command class SWITCH_MULTILEVEL
18:37:54.077 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Received SWITCH_MULTILEVEL command V0
18:37:54.111 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Switch Multi Level SET
18:37:54.142 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Switch Multi Level report, value = 0
18:37:54.177 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
18:37:54.214 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:37:54.255 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_MULTILEVEL, value = 0
18:37:54.307 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=5, callback=70, payload=05 07 60 0D 01 02 32 01 10
18:37:54.368 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=08 05 04 26 01 00 00
18:37:54.431 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=70, expected=ApplicationCommandHandler, cancelled=false transaction complete!
18:37:54.492 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:37:54.528 [DEBUG] [ecurityCommandClassWithInitialization] - NODE 5: updating lastSentMessageTimestamp
18:37:54.563 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:37:54.605 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:37:54.635 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 05 04 26 01 00 00 D7
18:37:54.673 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 05 04 26 01 00 00 D7
18:37:54.709 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 05 04 26 01 00 00
18:37:54.762 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Application Command Request (ALIVE:DONE)
18:37:54.797 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
18:37:54.831 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@14ef683 already registered
18:37:54.885 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 5: Incoming command class SWITCH_MULTILEVEL
18:37:54.920 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Received SWITCH_MULTILEVEL command V0
18:37:54.956 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Switch Multi Level SET
18:37:54.987 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Switch Multi Level report, value = 0
18:37:55.029 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
18:37:55.069 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:37:55.114 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_MULTILEVEL, value = 0
18:37:55.169 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=5, callback=70, payload=05 07 60 0D 01 02 32 01 10
18:37:55.235 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 05 04 26 01 00 00
18:37:55.309 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=70, expected=ApplicationCommandHandler, cancelled=false transaction complete!
18:37:55.373 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:37:55.413 [DEBUG] [ecurityCommandClassWithInitialization] - NODE 5: updating lastSentMessageTimestamp
18:37:55.450 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEventNone of the commands are formatted in the multi-channel format, so the binding can’t tell the difference between the switches.
I suspect that this is a change in Fibaro firmware. In the past, they always sent status using the MC commands. There has been a change in the recommended way to do this in recent versions of the standards, and I guess that they have now implemented this…
Probably this is solved in the latest development binding where I added support for autosetting the lifeline association group using multi channel associations, and this should (I hope) trigger a change in the way the device works.
I tried the new binding and seem to have a total mess now, not sure what created this - I’ve tried to reinstate my z-wave stick and apart from receiving a new thing id where I have to change all other items the sticks reports an error
zwave.thingstate.serial_notfound
but I’m pretty sure that with my Raspberry /dev/ttyAMA0 should be the proper one - any help appreciated.
That should have not been necessary, during adding the stick to your setup you can choose the name freely, even to your old “thing id”.
Even if you are 100% sure that the correct port is /dev/ttyAMA0 you should double check with dmesg -T | grep tty :
pi@raspberrypi3:~ $ dmesg -T | grep tty
[Do Sep 28 09:45:25 2017] Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=/dev/sda2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[Do Sep 28 09:45:25 2017] console [tty1] enabled
[Do Sep 28 09:45:25 2017] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[Do Sep 28 09:45:35 2017] cdc_acm 1-1.4.3:1.0: ttyACM0: USB ACM device
In my case the zwave stick is attached to [Do Sep 28 09:45:35 2017] cdc_acm 1-1.4.3:1.0: ttyACM0: USB ACM device
I must admit you were right - so I could correct both things ThingId and the Serial Port and it works again 
- have to figure out later if my Fibaro now works as supposed
1 Like
Now I have the following setup: My Z-Wave Stick apart from all the other devices which I can’t control anymore. Dows it have something to do with the fact that I have a secure z-wave network? Hopefully I don’t have to include the things, they are behind the wall…
Check the console and type bundle:list |grep -i zwave:
You should see something like this:
openhab> bundle:list |grep -i zwave
10 │ Active │ 80 │ 2.2.0.201710012213 │ ZWave Binding
Make sure you only have one active zwave binding.
Also check you openhab.log (or zwave.log if separated) for any errors.
Edit:
If you upgraded from a stable or snapshot zwave binding to the development version of the binding the very first time, you need to delete your things and readd them again. That does not mean you have to exclude and then include your devices from the controller (that is almost never necessary).
More on how to work with the dev version of the binding can be found here:
Read the first couple of posts (from nearly 2000)
I really need help here… I removed everything, installed the zwave latest binding again - that is 2.2.0.201710012213
(strange enough it showed the old version 2.2.0.201709201937 again). Then I start adding the Z-Wave Stick and the first device and it shows the same as in the picture above: The devices are connected to each other but not to the z-wave stick. Does it have anything todo with the fact that I had included all devices in secure mode with my stick - think I have read something regarding security but couldn’t find it again. Any help really appreceated as this is very frustrating because apart from the double switch everything was working very well…
It looks like: if you reinstall your system from scratch you have to use the same security key which was used during inclusion again. If you did not do that it won’t work.
As I am not using any secure settings (I don’t have any locks or other secure devices which would need that) I’m not much of any help here.
But: you will find your current network security key within HABmin in the things config of your zwave stick (you need to switch to advanced settings:
Now I would go to the backups (those are created automatically) of your json db (in the userdata folder) and find the file org.eclipse.smarthome.core.thing.Thing.json
On one ot the lines you will find the “security_networkkey”
Put that key to your current config and restart openHAB: hopefully that will work 
If switching from version to version you first need to properly uninstall the old binding (verify via console and bundle:list that its really gone), then drop the jar into your addons folder and restart openHAB, again verify through the console that you have the new version.
Done as proposed uninstall, install. I upgraded to the newest snapshot version incl. the addons and now its working both switches are correctly reported when switched manually. I have a problem with starting openhab now but this is due the update I guess will need to open another discussion on this.
1 Like