2019-07-20 20:16:53.150 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Configuration update received
2019-07-20 20:16:53.152 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Configuration update group_4 to [node_26_0]
2019-07-20 20:16:53.153 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 12: Creating new message for application command ASSOCIATIONCMD_SET
2019-07-20 20:16:53.153 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2019-07-20 20:16:53.153 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2019-07-20 20:16:53.153 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 12: Creating new message for command MULTI_ASSOCIATIONCMD_GET group 4
2019-07-20 20:16:53.153 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 0C 04 85 01 04 1A 25 77 27 
2019-07-20 20:16:53.153 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0B 00 13 0C 04 85 01 04 1A 25 77 27 
2019-07-20 20:16:53.153 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2019-07-20 20:16:53.161 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2019-07-20 20:16:53.161 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2019-07-20 20:16:53.161 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2019-07-20 20:16:53.161 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2019-07-20 20:16:53.161 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2019-07-20 20:16:53.161 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: Sent Data successfully placed on stack.
2019-07-20 20:16:53.177 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 77 00 00 03 9F 
2019-07-20 20:16:53.177 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2019-07-20 20:16:53.177 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 77 00 00 03 00 00 91 
2019-07-20 20:16:53.177 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 77 00 00 03 00 00 91 
2019-07-20 20:16:53.177 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=77 00 00 03 
2019-07-20 20:16:53.178 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 119, Status = Transmission complete and ACK received(0)
2019-07-20 20:16:53.178 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 12: Starting initialisation from DONE
2019-07-20 20:16:53.178 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@64fa45b6 already registered
2019-07-20 20:16:53.178 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Config, dest=12, callback=119, payload=0C 04 85 01 04 1A 
2019-07-20 20:16:53.178 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=77 00 00 03 
2019-07-20 20:16:53.178 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=119, expected=SendData, cancelled=false        transaction complete!
2019-07-20 20:16:53.178 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2019-07-20 20:16:53.178 [DEBUG] [curityCommandClassWithInitialization] - NODE 12: updating lastSentMessageTimestamp
2019-07-20 20:16:53.178 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-20 20:16:53.178 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 12: Response processed after 25ms/4944ms.
2019-07-20 20:16:53.178 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2019-07-20 20:16:53.178 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 8E 02 04 25 78 3C 
2019-07-20 20:16:53.178 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 8E 02 04 25 78 3C 
2019-07-20 20:16:53.186 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2019-07-20 20:16:53.186 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2019-07-20 20:16:53.186 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2019-07-20 20:16:53.186 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2019-07-20 20:16:53.187 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2019-07-20 20:16:53.187 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: Sent Data successfully placed on stack.
2019-07-20 20:16:53.202 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 78 00 00 02 91 
2019-07-20 20:16:53.203 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2019-07-20 20:16:53.203 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 78 00 00 02 00 00 9F 
2019-07-20 20:16:53.203 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 78 00 00 02 00 00 9F 
2019-07-20 20:16:53.203 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=78 00 00 02 
2019-07-20 20:16:53.203 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 120, Status = Transmission complete and ACK received(0)
2019-07-20 20:16:53.203 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 12: Starting initialisation from DONE
2019-07-20 20:16:53.203 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@64fa45b6 already registered
2019-07-20 20:16:53.203 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=12, callback=120, payload=0C 03 8E 02 04 
2019-07-20 20:16:53.203 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=78 00 00 02 
2019-07-20 20:16:53.203 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=120, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2019-07-20 20:16:53.214 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 0C 06 8E 03 04 08 00 1A 66 
2019-07-20 20:16:53.215 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2019-07-20 20:16:53.215 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 0C 06 8E 03 04 08 00 1A 66 
2019-07-20 20:16:53.215 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 0C 06 8E 03 04 08 00 1A 66 
2019-07-20 20:16:53.215 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0C 06 8E 03 04 08 00 1A 
2019-07-20 20:16:53.215 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 12: Application Command Request (ALIVE:DONE)
2019-07-20 20:16:53.215 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 12: Starting initialisation from DONE
2019-07-20 20:16:53.215 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@64fa45b6 already registered
2019-07-20 20:16:53.215 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 12: Incoming command class MULTI_INSTANCE_ASSOCIATION
2019-07-20 20:16:53.215 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 12: Received MULTI_INSTANCE_ASSOCIATION command V3
2019-07-20 20:16:53.215 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 12: association group 4 has max associations 8
2019-07-20 20:16:53.215 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 12: Association group 4 includes the following nodes:
2019-07-20 20:16:53.215 [DEBUG] [ss.ZWaveMultiAssociationCommandClass] - NODE 12: Associated with Node 26 in group 4
2019-07-20 20:16:53.215 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAssociationEvent
2019-07-20 20:16:53.215 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAssociationEvent
2019-07-20 20:16:53.215 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = ASSOCIATION, value = 0
2019-07-20 20:16:53.216 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Update ASSOCIATION group_4
2019-07-20 20:16:53.216 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Update ASSOCIATION group_4: Adding node_26_0
2019-07-20 20:16:53.216 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Update ASSOCIATION group_4: 1 members
2019-07-20 20:16:53.216 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Config updated
2019-07-20 20:16:53.667 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=12, callback=120, payload=0C 03 8E 02 04 
2019-07-20 20:16:53.667 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0C 06 8E 03 04 08 00 1A 
2019-07-20 20:16:53.668 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=120, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
2019-07-20 20:16:53.668 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2019-07-20 20:16:53.668 [DEBUG] [curityCommandClassWithInitialization] - NODE 12: updating lastSentMessageTimestamp
2019-07-20 20:16:53.668 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-20 20:16:53.668 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 12: Response processed after 489ms/4944ms.