And for completeness I tried yet another device, a Qubino ZWAVE+ Dimmer ZMNHDD. It only switches one dimmable load, but offers also up to 3 endpoints which can be wired to different switches or sensors.
The result is the same: When physically switching Input Switch I1 or Input Switch I2 the reporting to OH controller is always with endpoint 0. Endpoints 1 and 2 are never updated (the same story as with Qubino ZMNHBD and Fibaro FGS223).
I finally believe that here is really an issue with the OH binding as already 3 new devices show the same behaviour.
It would be great if we could get the additional endpoints usable (which was easy with the old ZWAVE-Generation devices with multi instance endpoints). At the moment the new devices are a step back with OH as they cannot be used completely.
Hi Chris,
here comes the debug log when trying to set the association on node 101:
2017-01-07 02:07:51.609 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Configuration update received
2017-01-07 02:07:51.632 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 06 00 49 81 00 00 31
2017-01-07 02:07:51.633 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:51.633 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 06 00 49 81 00 00 31
2017-01-07 02:07:51.633 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 06 00 49 81 00 00 31
2017-01-07 02:07:51.633 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=81 00 00
2017-01-07 02:07:51.633 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 255: Application update request. Node Info Request Failed.
2017-01-07 02:07:51.633 [WARN ] [essage.ApplicationUpdateMessageClass] - NODE 255: Node Info Request Failed 3x. Discarding message: Message: class=RequestNodeInfo[0x60], type=Request[0x00], priority=High, dest=255, callback=0, payload=4F
2017-01-07 02:07:51.633 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=RequestNodeInfo[0x60], type=Request[0x00], priority=High, dest=255, callback=0, payload=4F
2017-01-07 02:07:51.633 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=81 00 00
2017-01-07 02:07:51.633 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationUpdate, callback id=0, expected=ApplicationUpdate, cancelled=true MISMATCH
2017-01-07 02:07:51.647 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Configuration update group_5 to [node_1_0]
2017-01-07 02:07:51.647 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 101: Creating new message for application command ASSOCIATIONCMD_SET
2017-01-07 02:07:51.647 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-01-07 02:07:51.647 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 101: Creating new message for command MULTI_ASSOCIATIONCMD_GET group 5
2017-01-07 02:07:51.647 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 2. Queue={}
2017-01-07 02:07:51.647 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Configuration update group_2 to [node_1_0]
2017-01-07 02:07:51.647 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 101: Creating new message for application command ASSOCIATIONCMD_SET
2017-01-07 02:07:51.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 3. Queue={}
2017-01-07 02:07:51.648 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 101: Creating new message for command MULTI_ASSOCIATIONCMD_GET group 2
2017-01-07 02:07:51.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 4. Queue={}
2017-01-07 02:07:52.526 [WARN ] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Too many retries. Discarding message: Message: class=RequestNodeInfo[0x60], type=Request[0x00], priority=High, dest=255, callback=0, payload=4F
2017-01-07 02:07:52.527 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 3
2017-01-07 02:07:52.527 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 65 03 8E 02 05 25 3D 11
2017-01-07 02:07:52.527 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 101: Sending REQUEST Message = 01 0A 00 13 65 03 8E 02 05 25 3D 11
2017-01-07 02:07:52.536 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2017-01-07 02:07:52.537 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:52.537 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2017-01-07 02:07:52.537 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2017-01-07 02:07:52.537 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-01-07 02:07:52.537 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 101: Sent Data successfully placed on stack.
2017-01-07 02:07:52.680 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 3D 00 00 0F D9
2017-01-07 02:07:52.681 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:52.681 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 3D 00 00 0F 00 00 D7
2017-01-07 02:07:52.681 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 3D 00 00 0F 00 00 D7
2017-01-07 02:07:52.681 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=3D 00 00 0F
2017-01-07 02:07:52.681 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 101: SendData Request. CallBack ID = 61, Status = Transmission complete and ACK received(0)
2017-01-07 02:07:52.681 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 101: Starting initialisation from DONE
2017-01-07 02:07:52.682 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@56fc0c38 already registered
2017-01-07 02:07:52.682 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=101, callback=61, payload=65 03 8E 02 05
2017-01-07 02:07:52.682 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=3D 00 00 0F
2017-01-07 02:07:52.682 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=61, expected=ApplicationCommandHandler, cancelled=false MISMATCH
2017-01-07 02:07:52.809 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 65 07 8E 03 05 10 00 55 00 59
2017-01-07 02:07:52.810 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:52.810 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 04 00 65 07 8E 03 05 10 00 55 00 59
2017-01-07 02:07:52.810 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0D 00 04 00 65 07 8E 03 05 10 00 55 00 59
2017-01-07 02:07:52.810 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 65 07 8E 03 05 10 00 55 00
2017-01-07 02:07:52.810 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 101: Application Command Request (ALIVE:DONE)
2017-01-07 02:07:52.810 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 101: Starting initialisation from DONE
2017-01-07 02:07:52.811 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@56fc0c38 already registered
2017-01-07 02:07:52.811 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 101: Incoming command class MULTI_INSTANCE_ASSOCIATION
2017-01-07 02:07:52.811 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 101: Received MULTI_INSTANCE_ASSOCIATION command V3
2017-01-07 02:07:52.811 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 101: association group 5 has max associations 16
2017-01-07 02:07:52.811 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 101: Association group 5 includes the following nodes:
2017-01-07 02:07:52.811 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 101: Associated with Node 85 in group 5
2017-01-07 02:07:52.811 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAssociationEvent
2017-01-07 02:07:52.811 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Got an event from Z-Wave network: ZWaveAssociationEvent
2017-01-07 02:07:52.811 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Got a value event from Z-Wave network, endpoint = 0, command class = ASSOCIATION, value = 0
2017-01-07 02:07:52.811 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Update ASSOCIATION group_5
2017-01-07 02:07:52.811 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Update ASSOCIATION group_5: Adding node_85_0
2017-01-07 02:07:52.811 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Update ASSOCIATION group_5: 1 members
2017-01-07 02:07:52.811 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Config updated
2017-01-07 02:07:52.814 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=101, callback=61, payload=65 03 8E 02 05
2017-01-07 02:07:52.814 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 65 07 8E 03 05 10 00 55 00
2017-01-07 02:07:52.814 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=61, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-01-07 02:07:52.814 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-01-07 02:07:52.814 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-01-07 02:07:52.815 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 101: Response processed after 288ms/4893ms.
2017-01-07 02:07:52.815 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 2
2017-01-07 02:07:52.815 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 65 03 8E 02 02 25 3F 14
2017-01-07 02:07:52.815 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 101: Sending REQUEST Message = 01 0A 00 13 65 03 8E 02 02 25 3F 14
2017-01-07 02:07:52.824 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2017-01-07 02:07:52.825 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:52.825 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2017-01-07 02:07:52.825 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2017-01-07 02:07:52.825 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-01-07 02:07:52.825 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 101: Sent Data successfully placed on stack.
2017-01-07 02:07:53.070 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 3F 00 00 19 CD
2017-01-07 02:07:53.071 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:53.071 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 3F 00 00 19 00 00 C3
2017-01-07 02:07:53.071 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 3F 00 00 19 00 00 C3
2017-01-07 02:07:53.071 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=3F 00 00 19
2017-01-07 02:07:53.071 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 101: SendData Request. CallBack ID = 63, Status = Transmission complete and ACK received(0)
2017-01-07 02:07:53.071 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 101: Starting initialisation from DONE
2017-01-07 02:07:53.072 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@56fc0c38 already registered
2017-01-07 02:07:53.072 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=101, callback=63, payload=65 03 8E 02 02
2017-01-07 02:07:53.072 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=3F 00 00 19
2017-01-07 02:07:53.072 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=63, expected=ApplicationCommandHandler, cancelled=false MISMATCH
2017-01-07 02:07:53.196 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 65 05 8E 03 02 10 00 0F
2017-01-07 02:07:53.197 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:53.197 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 04 00 65 05 8E 03 02 10 00 0F
2017-01-07 02:07:53.197 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0B 00 04 00 65 05 8E 03 02 10 00 0F
2017-01-07 02:07:53.197 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 65 05 8E 03 02 10 00
2017-01-07 02:07:53.197 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 101: Application Command Request (ALIVE:DONE)
2017-01-07 02:07:53.197 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 101: Starting initialisation from DONE
2017-01-07 02:07:53.197 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@56fc0c38 already registered
2017-01-07 02:07:53.197 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 101: Incoming command class MULTI_INSTANCE_ASSOCIATION
2017-01-07 02:07:53.197 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 101: Received MULTI_INSTANCE_ASSOCIATION command V3
2017-01-07 02:07:53.197 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 101: association group 2 has max associations 16
2017-01-07 02:07:53.197 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAssociationEvent
2017-01-07 02:07:53.197 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Got an event from Z-Wave network: ZWaveAssociationEvent
2017-01-07 02:07:53.197 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Got a value event from Z-Wave network, endpoint = 0, command class = ASSOCIATION, value = 0
2017-01-07 02:07:53.197 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Update ASSOCIATION group_2
2017-01-07 02:07:53.197 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Update ASSOCIATION group_2: 0 members
2017-01-07 02:07:53.197 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Config updated
2017-01-07 02:07:53.220 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=101, callback=63, payload=65 03 8E 02 02
2017-01-07 02:07:53.220 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 65 05 8E 03 02 10 00
2017-01-07 02:07:53.220 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=63, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-01-07 02:07:53.220 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-01-07 02:07:53.220 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-01-07 02:07:53.220 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 101: Response processed after 405ms/4893ms.
2017-01-07 02:07:53.220 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 1
2017-01-07 02:07:53.220 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 65 04 85 01 05 01 25 3C 1F
2017-01-07 02:07:53.220 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 101: Sending REQUEST Message = 01 0B 00 13 65 04 85 01 05 01 25 3C 1F
2017-01-07 02:07:53.230 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2017-01-07 02:07:53.231 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:53.231 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2017-01-07 02:07:53.231 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2017-01-07 02:07:53.231 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-01-07 02:07:53.231 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 101: Sent Data successfully placed on stack.
2017-01-07 02:07:53.477 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 3C 00 00 1A CD
2017-01-07 02:07:53.478 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:53.478 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 3C 00 00 1A 00 00 C3
2017-01-07 02:07:53.479 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 3C 00 00 1A 00 00 C3
2017-01-07 02:07:53.479 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=3C 00 00 1A
2017-01-07 02:07:53.479 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 101: SendData Request. CallBack ID = 60, Status = Transmission complete and ACK received(0)
2017-01-07 02:07:53.479 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 101: Starting initialisation from DONE
2017-01-07 02:07:53.479 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@56fc0c38 already registered
2017-01-07 02:07:53.479 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Config, dest=101, callback=60, payload=65 04 85 01 05 01
2017-01-07 02:07:53.479 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=3C 00 00 1A
2017-01-07 02:07:53.479 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=60, expected=SendData, cancelled=false transaction complete!
2017-01-07 02:07:53.479 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-01-07 02:07:53.479 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-01-07 02:07:53.479 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 101: Response processed after 259ms/4893ms.
2017-01-07 02:07:53.479 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-01-07 02:07:53.479 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 65 04 85 01 02 01 25 3E 1A
2017-01-07 02:07:53.479 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 101: Sending REQUEST Message = 01 0B 00 13 65 04 85 01 02 01 25 3E 1A
2017-01-07 02:07:53.488 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2017-01-07 02:07:53.489 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:53.489 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2017-01-07 02:07:53.489 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2017-01-07 02:07:53.489 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-01-07 02:07:53.489 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 101: Sent Data successfully placed on stack.
2017-01-07 02:07:53.662 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 3E 00 00 12 C7
2017-01-07 02:07:53.663 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-01-07 02:07:53.663 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 3E 00 00 12 00 00 C9
2017-01-07 02:07:53.663 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 3E 00 00 12 00 00 C9
2017-01-07 02:07:53.663 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=3E 00 00 12
2017-01-07 02:07:53.663 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 101: SendData Request. CallBack ID = 62, Status = Transmission complete and ACK received(0)
2017-01-07 02:07:53.663 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 101: Starting initialisation from DONE
2017-01-07 02:07:53.663 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@56fc0c38 already registered
2017-01-07 02:07:53.663 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Config, dest=101, callback=62, payload=65 04 85 01 02 01
2017-01-07 02:07:53.663 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=3E 00 00 12
2017-01-07 02:07:53.663 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=62, expected=SendData, cancelled=false transaction complete!
2017-01-07 02:07:53.663 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-01-07 02:07:53.663 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 101: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-01-07 02:07:53.664 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 101: Response processed after 185ms/4893ms.
2017-01-07 02:07:59.322 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 51 0A 32 02 21 34 00 00 00 00 00 00 95
Hope it helps!
I think the newer fibaro devices are using the same multi channel concept as the qubino ones - I came across that a few days ago.
I’ll take a look at the log when I get a chance.
Thanks for the log - the system is not using the multi channel association SET for some reason. The GET is using the multi channel so I’m not sure why the SET isn’t… I’ll try and look at this either later tonight or tomorrow.
Thank you for the update, Chris. If you need another log or whatever let me know.
@chris:
As already stated in the FGS-223 thread, the Qubino ZMNHBD is still NOT working (I tested with Z-Wave binding build 2.1.0.201702181609). I excluded and freshly reincluded 2 of the devices to be sure. The results:
- When only the lifeline association is set, no reporting is done to endpoints 1 and 2 (neither for Switch 1/2 nor Power(Watts)1/2).
- Even when the association groups for Switch binary or Basic On/Off are set (or both, also tested), NO update comes to the controller for Switch 1/2.
- When the assocation groups for power reporting are set, the Power Consumption (Watts) is reported correctly for both endpoints separately(!)
Is there a chance to also get the status reporting of the switches correctly? I have moren than 10 of them installed and can provide any kind of debug log if you tell me what you need. Thanks for your help!
I seem to have the same issue with my newly bought Qubino flush 2 relays.
My system is a clean install of OH2. I can control both switches from the GUI but when I use the physical switches openhab never receives an update on switch 1 or 2 but only on the ‘common switch’. Hence the status on my sitemap is never updated. I have setup associations as per the following image
Here is a DEBUG log when i switch on switch 2
2017-02-28 20:23:02.542 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 11 03 25 03 FF 39
2017-02-28 20:23:02.546 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-28 20:23:02.547 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 11 03 25 03 FF 39
2017-02-28 20:23:02.549 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 11 03 25 03 FF 39
2017-02-28 20:23:02.551 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 03 25 03 FF
2017-02-28 20:23:02.552 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2017-02-28 20:23:02.553 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2017-02-28 20:23:02.554 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b01cb6 already registered
2017-02-28 20:23:02.555 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class SWITCH_BINARY
2017-02-28 20:23:02.556 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - Received Switch Binary Request for Node ID = 17
2017-02-28 20:23:02.557 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 17: Switch Binary report, value = 255
2017-02-28 20:23:02.558 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-02-28 20:23:02.559 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-02-28 20:23:02.560 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 255
2017-02-28 20:23:02.562 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:2947fb5e:node17:switch_binary to ON [OnOffType]
2017-02-28 20:23:02.565 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=17, callback=42, payload=11 07 60 0D 01 02 25 01 00
2017-02-28 20:23:02.572 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 03 25 03 FF
2017-02-28 20:23:02.573 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=42, expected=SendData, cancelled=false MISMATCH
2017-02-28 20:23:03.352 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 11 0A 32 02 21 34 00 00 00 C6 00 00 13
2017-02-28 20:23:03.356 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-28 20:23:03.358 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 11 0A 32 02 21 34 00 00 00 C6 00 00 13
2017-02-28 20:23:03.360 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 11 0A 32 02 21 34 00 00 00 C6 00 00 13
2017-02-28 20:23:03.362 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 0A 32 02 21 34 00 00 00 C6 00 00
2017-02-28 20:23:03.363 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2017-02-28 20:23:03.364 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2017-02-28 20:23:03.365 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b01cb6 already registered
2017-02-28 20:23:03.366 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class METER
2017-02-28 20:23:03.367 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 17: Received METER command V3
2017-02-28 20:23:03.368 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 17: Meter: Type=Electric(1), Scale=W(2), Value=19.8
2017-02-28 20:23:03.369 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent
2017-02-28 20:23:03.370 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveMeterValueEvent
2017-02-28 20:23:03.371 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 19.8
2017-02-28 20:23:03.373 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:2947fb5e:node17:meter_watts to 19.8 [DecimalType]
2017-02-28 20:23:03.375 [DEBUG] [ternal.converter.ZWaveMeterConverter] - Not the right scale E_KWh <> E_W
2017-02-28 20:23:03.376 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=17, callback=42, payload=11 07 60 0D 01 02 25 01 00
2017-02-28 20:23:03.378 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 0A 32 02 21 34 00 00 00 C6 00 00
2017-02-28 20:23:03.379 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=42, expected=SendData, cancelled=false MISMATCH
2017-02-28 20:23:04.353 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 11 0A 32 02 21 34 00 00 00 98 00 00 4D
2017-02-28 20:23:04.355 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-28 20:23:04.357 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 11 0A 32 02 21 34 00 00 00 98 00 00 4D
2017-02-28 20:23:04.358 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 11 0A 32 02 21 34 00 00 00 98 00 00 4D
2017-02-28 20:23:04.359 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 0A 32 02 21 34 00 00 00 98 00 00
2017-02-28 20:23:04.359 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2017-02-28 20:23:04.360 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2017-02-28 20:23:04.361 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b01cb6 already registered
2017-02-28 20:23:04.361 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class METER
2017-02-28 20:23:04.362 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 17: Received METER command V3
2017-02-28 20:23:04.363 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 17: Meter: Type=Electric(1), Scale=W(2), Value=15.2
2017-02-28 20:23:04.363 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent
2017-02-28 20:23:04.364 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveMeterValueEvent
2017-02-28 20:23:04.364 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 15.2
2017-02-28 20:23:04.365 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:2947fb5e:node17:meter_watts to 15.2 [DecimalType]
2017-02-28 20:23:04.366 [DEBUG] [ternal.converter.ZWaveMeterConverter] - Not the right scale E_KWh <> E_W
2017-02-28 20:23:04.367 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=17, callback=42, payload=11 07 60 0D 01 02 25 01 00
2017-02-28 20:23:04.368 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 0A 32 02 21 34 00 00 00 98 00 00
2017-02-28 20:23:04.369 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=42, expected=SendData, cancelled=false MISMATCH
2017-02-28 20:23:05.884 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 11 03 20 01 FF 3E
2017-02-28 20:23:05.927 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-28 20:23:05.928 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 11 03 20 01 FF 3E
2017-02-28 20:23:05.930 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 11 03 20 01 FF 3E
2017-02-28 20:23:05.932 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 03 20 01 FF
2017-02-28 20:23:05.933 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2017-02-28 20:23:05.934 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2017-02-28 20:23:05.935 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b01cb6 already registered
2017-02-28 20:23:05.936 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class BASIC
2017-02-28 20:23:05.940 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 17: Received Basic Request
2017-02-28 20:23:05.941 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 17: Basic Set sent to the controller will be processed as Basic Report
2017-02-28 20:23:05.943 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 17: Basic report, value = 0xFF
2017-02-28 20:23:05.948 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-02-28 20:23:05.949 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-02-28 20:23:05.950 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
2017-02-28 20:23:05.951 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:2947fb5e:node17:switch_binary to ON [OnOffType]
2017-02-28 20:23:05.955 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=17, callback=42, payload=11 07 60 0D 01 02 25 01 00
2017-02-28 20:23:05.957 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 03 20 01 FF
2017-02-28 20:23:05.964 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=42, expected=SendData, cancelled=false MISMATCH
2017-02-28 20:23:06.886 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 11 03 25 03 FF 39
2017-02-28 20:23:06.889 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-28 20:23:06.891 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 11 03 25 03 FF 39
2017-02-28 20:23:06.893 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 11 03 25 03 FF 39
2017-02-28 20:23:06.894 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 03 25 03 FF
2017-02-28 20:23:06.896 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2017-02-28 20:23:06.897 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2017-02-28 20:23:06.899 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b01cb6 already registered
2017-02-28 20:23:06.900 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class SWITCH_BINARY
2017-02-28 20:23:06.901 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - Received Switch Binary Request for Node ID = 17
2017-02-28 20:23:06.902 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 17: Switch Binary report, value = 255
2017-02-28 20:23:06.903 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-02-28 20:23:06.904 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-02-28 20:23:06.905 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 255
2017-02-28 20:23:06.907 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:2947fb5e:node17:switch_binary to ON [OnOffType]
2017-02-28 20:23:06.910 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=17, callback=42, payload=11 07 60 0D 01 02 25 01 00
2017-02-28 20:23:06.912 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 03 25 03 FF
2017-02-28 20:23:06.913 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=42, expected=SendData, cancelled=false MISMATCH
2017-02-28 20:23:07.916 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 11 0A 32 02 21 34 00 00 00 98 00 00 4D
2017-02-28 20:23:07.919 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-28 20:23:07.923 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 11 0A 32 02 21 34 00 00 00 98 00 00 4D
2017-02-28 20:23:07.925 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 11 0A 32 02 21 34 00 00 00 98 00 00 4D
2017-02-28 20:23:07.926 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 0A 32 02 21 34 00 00 00 98 00 00
2017-02-28 20:23:07.927 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2017-02-28 20:23:07.928 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2017-02-28 20:23:07.929 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b01cb6 already registered
2017-02-28 20:23:07.930 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class METER
2017-02-28 20:23:07.931 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 17: Received METER command V3
2017-02-28 20:23:07.932 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 17: Meter: Type=Electric(1), Scale=W(2), Value=15.2
2017-02-28 20:23:07.933 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent
2017-02-28 20:23:07.933 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveMeterValueEvent
2017-02-28 20:23:07.934 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 15.2
2017-02-28 20:23:07.935 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:2947fb5e:node17:meter_watts to 15.2 [DecimalType]
2017-02-28 20:23:07.936 [DEBUG] [ternal.converter.ZWaveMeterConverter] - Not the right scale E_KWh <> E_W
2017-02-28 20:23:07.938 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=17, callback=42, payload=11 07 60 0D 01 02 25 01 00
2017-02-28 20:23:07.939 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 0A 32 02 21 34 00 00 00 98 00 00
2017-02-28 20:23:07.940 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=42, expected=SendData, cancelled=false MISMATCH
^C
pi@raspberrypi:~ $
I am running the latest stable release 2.0.0
How did you include the device to the Z-Wave network? Make sure that you use the inclusion function from within OH2 (worked for me with both Paper UI and Habmin) when your Z-Wave stick is connected. I had trouble when I included the devices first decentrally with my Aeon Z-Stick5 and then started OH2, as in these cases the different endpoints were never usable.
So if you included it otherwise you will have to exclude and reinclude the device.
For associations I only set lifeline and this is enough. Endpoints get correct updates and also the energy consumption for each switch.
BUT: The Qubinos react relatively slow, updates take always between 4-8 seconds. While this may be ok to see if a light is still on, it is not usable if you want to react quickly in a rule to this event. For this reason I replaced some of my Qubinos with the Fibaro FGS-223 which is the better choice for quick updates.
The ZMNHBD Flush 2 relay is running fine here with OH2 2.1.0.
The way I included it was with OH2 Habmin and not with the Gen5 Z-Stick Button.
Additionally, I didn’t link the “general Channels” - the ones first in the list for “compatibiltiy”, but only the “:switch_binary1” and “:switch_binary2” as well as their meter_watts. This works fine,
with the exception that when I used OH2 Paper GUI to change properties, I got the the group asscociations wrong in the json config, ie. the Error 404 discussed here.
Hope that helps anyone.
Does power and state reporting (on/off) work reliably for both endpoints after you included via Habmin? Just wondering why including via Habmin would make a difference. Does that mean I need to move the whole server around the house to include these devices again?
Does changing parameters via Habmin work, or do you also get messed up group associations?
In any case, this sounds like a real PITA and a no-go for buying further Qubino Flush 2 Relays.
I tried exactly that, have same setup as you, doesn’t work:
- OH2 2.10 Snapshot
- Qubino Flush Relay 2
- Aeon Z-Stick Gen5
- Excluded
- Included via Habmin
Reporting back states / power doesn’t work (control does). Tried various settings for association groups.
Is this a OH2 specific problem? Are these devices the same PITA for solutions like Vera and Homeseer? What am I missing?
It used to be a problem some time ago. Since Release Version 2.0 I can confirm that it worked for me. I did not try all recent snapshot versions but they should work.
The dev branch of the Z-Wave binding has a problem with this at the moment, but snapshot should be fine.
Did you set the association group 1 to the controller?
Yes, but unfortunately setting Association Group 1 to “OpenHAB Controller” makes no difference. It also makes no difference how the device was included (directly via ZStick or via Habmin). Receiving state updates of switches/dimmers may have worked in the past for you, but it is not working in stable 2.1.0 and neither in current 2.2 snapshots (or at least for devices included in those releases). The problem is more generalized, see this thread: Status updates for Qubino Relays/Dimmers generally do not work