Zwave binding and security issues

sihui · August 26, 2017, 5:55am

As you can see on my above log viewer picture you need to have the multi channel commands to make it work.
From my point of view it can only be a misconfiguration of the config settings.

Again, please verify each and every single line and try to adapt it to my working config. Use HABmin to change the settings.

If that does not help, delete the thing, delete the xml file, re-add the thing.

If that does not help exclude the device, reset it and include it again. Note that you will have a new (in most cases higher) node number then.

meschmesch · August 26, 2017, 7:32am

Hi @sihui,

How do I delete the XML file? Or better, where is it stored?

Thx
Michael

chris · August 26, 2017, 9:11am

Select the thing in Habmin, then in the menu to the top right, select advanced mode, then select reinitialise device. This will do exactly the same thing as deleting the xml and restarting the binding.

meschmesch · August 27, 2017, 6:04pm

@chris and @sihui

Reinitialize did not do anything. Exclusion of the device, reset of the device, inclusion resulted in node 37 as follows:

2017-08-27 19:59:18.761 [DEBUG] [rialmessage.IsFailedNodeMessageClass] - NODE 30: Requesting IsFailedNode status from controller.
2017-08-27 19:59:18.766 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@20fc93
2017-08-27 19:59:18.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Adding to controller queue
2017-08-27 19:59:18.772 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added to queue - size 1
2017-08-27 19:59:18.773 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-08-27 19:59:18.774 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 28: listening == false, frequentlyListening == false, awake == false
2017-08-27 19:59:18.774 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Node not awake!
2017-08-27 19:59:18.775 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 28: listening == false, frequentlyListening == false, awake == false
2017-08-27 19:59:18.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Node not awake!
2017-08-27 19:59:18.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-08-27 19:59:18.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
2017-08-27 19:59:18.778 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 62 1E 87
2017-08-27 19:59:18.779 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 62 1E 87
2017-08-27 19:59:18.781 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2017-08-27 19:59:18.782 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2930: Transaction Start type IsFailedNodeID
2017-08-27 19:59:18.783 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2930: [WAIT_RESPONSE] requiresResponse=true callback: 0
2017-08-27 19:59:18.783 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2017-08-27 19:59:18.783 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
2017-08-27 19:59:18.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: null
2017-08-27 19:59:18.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-08-27 19:59:18.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
2017-08-27 19:59:18.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-08-27 19:59:18.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Sun Aug 27 19:59:20 CEST 2017 - 2000ms
2017-08-27 19:59:18.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-08-27 19:59:18.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 2930: [WAIT_RESPONSE] requiresResponse=true callback: 0
2017-08-27 19:59:18.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2930: [WAIT_RESPONSE] requiresResponse=true callback: 0
2017-08-27 19:59:18.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2017-08-27 19:59:18.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-08-27 19:59:18.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-08-27 19:59:18.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
2017-08-27 19:59:18.787 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 62 01 99
2017-08-27 19:59:18.789 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Sun Aug 27 19:59:20 CEST 2017 - 1996ms
2017-08-27 19:59:18.790 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-08-27 19:59:18.791 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-08-27 19:59:18.792 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-08-27 19:59:18.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=IsFailedNodeID[0x62], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-27 19:59:18.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Sun Aug 27 19:59:20 CEST 2017 - 1992ms
2017-08-27 19:59:18.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=IsFailedNodeID[0x62], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-27 19:59:18.797 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=IsFailedNodeID[0x62], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-27 19:59:18.798 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2930: [WAIT_RESPONSE] requiresResponse=true callback: 0
2017-08-27 19:59:18.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2017-08-27 19:59:18.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2930: [WAIT_RESPONSE] requiresResponse=true callback: 0
2017-08-27 19:59:18.803 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=IsFailedNodeID[0x62], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-27 19:59:18.804 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 30: Is currently marked as failed by the controller!
2017-08-27 19:59:18.806 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2930: Transaction COMPLETED
2017-08-27 19:59:18.807 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2930: TransactionAdvance ST: DONE
2017-08-27 19:59:18.808 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2930: TransactionAdvance WT: null {}
2017-08-27 19:59:18.809 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2930: TransactionAdvance RX: Message: class=IsFailedNodeID[0x62], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-27 19:59:18.810 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2930: TransactionAdvance TO: DONE
2017-08-27 19:59:18.811 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 29ms
2017-08-27 19:59:18.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 2930: Transaction completed
2017-08-27 19:59:18.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:2930 DONE
2017-08-27 19:59:18.815 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-08-27 19:59:18.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2930: Transaction event listener: DONE: DONE ->
2017-08-27 19:59:18.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: -- To notify -- COMPLETE
2017-08-27 19:59:18.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-08-27 19:59:18.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ********* Transaction Response Complete 2930 --
2017-08-27 19:59:18.818 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 30: Node Init response (0) COMPLETE
2017-08-27 19:59:18.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-08-27 19:59:18.819 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 30: Node Init transaction completed with response COMPLETE
2017-08-27 19:59:18.819 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 28: listening == false, frequentlyListening == false, awake == false
2017-08-27 19:59:18.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Node not awake!
2017-08-27 19:59:18.823 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 28: listening == false, frequentlyListening == false, awake == false
2017-08-27 19:59:18.824 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Node not awake!
2017-08-27 19:59:18.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-08-27 19:59:18.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2017-08-27 19:59:18.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

And now?

sihui · August 27, 2017, 7:51pm

Interesting: node number changed from 36 to 30. Should have been 37 or higher.
Sorry, no more ideas, only a not very helpful information: FGS223 works fine with the development version of the zwave binding in my setup…

Edit: Did not read

Then you should post the debug log from node 37, not 30 …

meschmesch · August 28, 2017, 8:07am

That is the problem, after inclusion node 37 remains silent even though vVoltage was applied manually to input 2. I will update the binding to the latest developement version, exclude, reinclude the node and while reinclusion run the debug mode. Maybe that will provide more information…

meschmesch · August 28, 2017, 7:56pm

@sihui & @chris

Here is the complete log with inclusion of the new node (now node 38). Binary2 remains silent… The log is uploaded. The log is available at https://ufile.io/86zd6

Interesting thing: When I set association groups for S2 switch to openHAB, then on binary I can correctly read the S2 input. Log is here:

2017-08-28 22:06:45.043 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 08 26 03 20 01 FF 01
2017-08-28 22:06:45.049 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-08-28 22:06:45.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=8, payload=08 26 03 20 01 FF
2017-08-28 22:06:45.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=8, payload=08 26 03 20 01 FF
2017-08-28 22:06:45.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=8, payload=08 26 03 20 01 FF
2017-08-28 22:06:45.064 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 26 03 20 01 FF 09
2017-08-28 22:06:45.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-08-28 22:06:45.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Application Command Request (ALIVE:DONE)
2017-08-28 22:06:45.074 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: resetResendCount initComplete=true isDead=false
2017-08-28 22:06:45.075 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2017-08-28 22:06:45.075 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-08-28 22:06:45.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: SECURITY NOT required on COMMAND_CLASS_BASIC
2017-08-28 22:06:45.078 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 38: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2017-08-28 22:06:45.080 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 38: Basic report, value = 255
2017-08-28 22:06:45.081 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-08-28 22:06:45.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=0, payload=00 26 03 20 01 FF
2017-08-28 22:06:45.083 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-08-28 22:06:45.084 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BASIC, value = 255
2017-08-28 22:06:45.086 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=0, payload=00 26 03 20 01 FF
2017-08-28 22:06:45.087 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Updating channel state zwave:device:15db442c729:node38:switch_binary to ON [OnOffType]
2017-08-28 22:06:45.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Commands processed 1.
2017-08-28 22:06:45.092 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f2a824.
2017-08-28 22:06:45.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-28 22:06:45.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-28 22:06:45.104 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=0, payload=00 26 03 20 01 FF
2017-08-28 22:06:45.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-08-28 22:06:45.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Application Command Request (ALIVE:DONE)
2017-08-28 22:06:45.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: resetResendCount initComplete=true isDead=false
2017-08-28 22:06:45.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2017-08-28 22:06:45.111 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: SECURITY NOT required on COMMAND_CLASS_BASIC
2017-08-28 22:06:45.112 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 38: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2017-08-28 22:06:45.114 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 38: Basic report, value = 255
2017-08-28 22:06:45.115 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-08-28 22:06:45.117 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-08-28 22:06:45.118 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BASIC, value = 255
2017-08-28 22:06:45.120 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Updating channel state zwave:device:15db442c729:node38:switch_binary to ON [OnOffType]
2017-08-28 22:06:45.123 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Commands processed 1.
2017-08-28 22:06:45.125 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@15e8131.
2017-08-28 22:06:45.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-28 22:06:45.129 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-28 22:06:45.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-08-28 22:06:45.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-08-28 22:06:45.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 28: listening == false, frequentlyListening == false, awake == false
2017-08-28 22:06:45.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Node not awake!
2017-08-28 22:06:45.135 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 28: listening == false, frequentlyListening == false, awake == false
2017-08-28 22:06:45.136 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Node not awake!
2017-08-28 22:06:45.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-08-28 22:06:45.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2017-08-28 22:06:50.424 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 08 26 03 20 01 00 FE
2017-08-28 22:06:50.429 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-08-28 22:06:50.432 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=8, payload=08 26 03 20 01 00
2017-08-28 22:06:50.435 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=8, payload=08 26 03 20 01 00
2017-08-28 22:06:50.437 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=8, payload=08 26 03 20 01 00
2017-08-28 22:06:50.439 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-08-28 22:06:50.440 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 26 03 20 01 00 F6
2017-08-28 22:06:50.440 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Application Command Request (ALIVE:DONE)
2017-08-28 22:06:50.442 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: resetResendCount initComplete=true isDead=false
2017-08-28 22:06:50.444 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2017-08-28 22:06:50.445 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: SECURITY NOT required on COMMAND_CLASS_BASIC
2017-08-28 22:06:50.446 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 38: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2017-08-28 22:06:50.448 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 38: Basic report, value = 0
2017-08-28 22:06:50.448 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-08-28 22:06:50.449 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-08-28 22:06:50.450 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-08-28 22:06:50.451 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BASIC, value = 0
2017-08-28 22:06:50.453 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Updating channel state zwave:device:15db442c729:node38:switch_binary to OFF [OnOffType]
2017-08-28 22:06:50.454 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=0, payload=00 26 03 20 01 00
2017-08-28 22:06:50.456 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Commands processed 1.
2017-08-28 22:06:50.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@36f848.
2017-08-28 22:06:50.458 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-28 22:06:50.459 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-28 22:06:50.460 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=0, payload=00 26 03 20 01 00
2017-08-28 22:06:50.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-08-28 22:06:50.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-08-28 22:06:50.463 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 28: listening == false, frequentlyListening == false, awake == false
2017-08-28 22:06:50.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Node not awake!
2017-08-28 22:06:50.469 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 28: listening == false, frequentlyListening == false, awake == false
2017-08-28 22:06:50.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Node not awake!
2017-08-28 22:06:50.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-08-28 22:06:50.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2017-08-28 22:06:50.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=38, callback=0, payload=00 26 03 20 01 00
2017-08-28 22:06:50.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-08-28 22:06:50.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Application Command Request (ALIVE:DONE)
2017-08-28 22:06:50.477 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: resetResendCount initComplete=true isDead=false
2017-08-28 22:06:50.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2017-08-28 22:06:50.480 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 38: SECURITY NOT required on COMMAND_CLASS_BASIC
2017-08-28 22:06:50.481 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 38: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2017-08-28 22:06:50.482 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 38: Basic report, value = 0
2017-08-28 22:06:50.483 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-08-28 22:06:50.485 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-08-28 22:06:50.486 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BASIC, value = 0
2017-08-28 22:06:50.488 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 38: Updating channel state zwave:device:15db442c729:node38:switch_binary to OFF [OnOffType]
2017-08-28 22:06:50.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Commands processed 1.
2017-08-28 22:06:50.493 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 38: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10b704b.
2017-08-28 22:06:50.494 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-28 22:06:50.498 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-28 22:06:50.499 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

Thx, Michael

sihui · August 28, 2017, 8:07pm

I’m seeing two different switch binary reports, unfortunately I am not good enough in reading zwave debug logs to find out if that are the correct ones:

meschmesch · August 28, 2017, 8:10pm

@sihui have just added a further comment above…

sihui · August 28, 2017, 8:16pm

As far as I understand the manual

The Switch 2 in 2nd to 5th group allows to control 5 regular or multichannel devices per an association group, with the exception of
“lifeline” that is reserved solely for the controller and hence only 1
node can be assigned.

those association groups are used when you want to directly control other devices without the use of the controller and therefore without the use of openHAB.
That is also the case for all other Fibaro (and even more brands) devices.

But as long as it works …

meschmesch · August 28, 2017, 8:58pm

@sihui It does not work - in this configuration a command sent to binary will automatically override any input to S2. I think I forget about the zwave- project and move to homematic. According to other people that works…

sihui · August 28, 2017, 8:59pm

Zwave works too …