2020-12-03 20:12:36.370 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 25 01 FF 25 01 1F
2020-12-03 20:12:36.371 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-03 20:12:36.371 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 346: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 1
2020-12-03 20:12:36.372 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-03 20:12:36.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-03 20:12:36.373 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-03 20:12:36.373 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 346: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 1
2020-12-03 20:12:36.373 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-03 20:12:36.373 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-03 20:12:36.373 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-03 20:12:38.372 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 5: TID 346: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2020-12-03 20:12:38.373 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2020-12-03 20:12:38.373 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 346: Transaction ABORTED
2020-12-03 20:12:38.374 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA
2020-12-03 20:12:38.374 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA
2020-12-03 20:12:38.375 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-03 20:12:38.375 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-03 20:12:43.592 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-12-03 20:12:43.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-12-03 20:12:43.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-12-03 20:12:43.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 346: [ABORTED] priority=Set, requiresResponse=true, callback: 1
2020-12-03 20:12:43.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-03 20:12:43.594 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 346: [ABORTED] priority=Set, requiresResponse=true, callback: 1
2020-12-03 20:12:43.594 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-12-03 20:12:43.594 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack.
2020-12-03 20:12:43.594 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 346: Transaction not completed
2020-12-03 20:12:43.594 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-03 20:12:43.594 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-03 20:12:43.599 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2020-12-03 20:12:43.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2020-12-03 20:12:43.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2020-12-03 20:12:43.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 346: [ABORTED] priority=Set, requiresResponse=true, callback: 1
2020-12-03 20:12:43.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2020-12-03 20:12:43.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 346: Resetting transaction
2020-12-03 20:12:43.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:346 ABORTED
2020-12-03 20:12:43.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue
2020-12-03 20:12:43.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Added 346 to queue - size 21
2020-12-03 20:12:43.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-12-03 20:12:43.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-03 20:12:43.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-12-03 20:12:43.691 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 01 01 02 DC 35
2020-12-03 20:12:43.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=1, payload=01 01 02 DC
2020-12-03 20:12:43.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=1, payload=01 01 02 DC
2020-12-03 20:12:43.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-03 20:12:43.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2020-12-03 20:12:43.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2020-12-03 20:12:43.693 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=1, payload=01 01 02 DC
2020-12-03 20:12:43.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-03 20:12:43.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-12-03 20:12:43.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-03 20:12:43.851 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 25 01 FF 25 01 1F
2020-12-03 20:12:43.852 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 25 01 FF 25 01 1F
2020-12-03 20:12:43.852 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-03 20:12:43.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 346: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 1
2020-12-03 20:12:43.853 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-03 20:12:43.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-03 20:12:43.854 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-03 20:12:43.854 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 346: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 1
2020-12-03 20:12:43.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-03 20:12:43.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-03 20:12:43.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-03 20:12:45.330 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 16: Command received zwave:device:d4120aaf:node16:switch_binary1 --> ON [OnOffType]
2020-12-03 20:12:45.333 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 16: Creating new message for application command SWITCH_BINARY_SET
2020-12-03 20:12:45.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: Encapsulating message, instance / endpoint 1
2020-12-03 20:12:45.334 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 16: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2020-12-03 20:12:45.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2020-12-03 20:12:45.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-12-03 20:12:45.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Adding to device queue
2020-12-03 20:12:45.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Transaction already in queue - removed original
2020-12-03 20:12:45.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Added 397 to queue - size 20
2020-12-03 20:12:45.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-03 20:12:45.339 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 16: Polling initialised at 86400 seconds - start in 1500 milliseconds.
2020-12-03 20:12:45.853 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 5: TID 346: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2020-12-03 20:12:45.853 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2020-12-03 20:12:45.854 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 346: Transaction ABORTED
2020-12-03 20:12:45.854 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA
2020-12-03 20:12:45.854 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA
2020-12-03 20:12:45.855 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-03 20:12:45.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-03 20:12:46.567 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 16: Command received zwave:device:d4120aaf:node16:switch_binary1 --> OFF [OnOffType]
2020-12-03 20:12:46.568 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 16: Creating new message for application command SWITCH_BINARY_SET
2020-12-03 20:12:46.574 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: Encapsulating message, instance / endpoint 1
2020-12-03 20:12:46.574 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 16: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2020-12-03 20:12:46.575 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2020-12-03 20:12:46.575 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-12-03 20:12:46.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Adding to device queue
2020-12-03 20:12:46.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Transaction already in queue - removed original
2020-12-03 20:12:46.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Added 398 to queue - size 20
2020-12-03 20:12:46.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-03 20:12:46.578 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 16: Polling initialised at 86400 seconds - start in 1500 milliseconds.
2020-12-03 20:12:48.078 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 16: Polling...
2020-12-03 20:12:48.079 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 16: Polling zwave:device:d4120aaf:node16:switch_binary1
2020-12-03 20:12:48.080 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 16: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 1
2020-12-03 20:12:48.080 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 16: Creating new message for application command SWITCH_BINARY_GET
2020-12-03 20:12:48.081 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: Encapsulating message, instance / endpoint 1
2020-12-03 20:12:48.081 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 16: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2020-12-03 20:12:48.081 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2020-12-03 20:12:48.082 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-12-03 20:12:48.082 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 16: Polling skipped for zwave:device:d4120aaf:node16:switch_binary1 on COMMAND_CLASS_BASIC
2020-12-03 20:12:48.083 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Adding to device queue
2020-12-03 20:12:48.083 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Transaction already in queue - removed original
2020-12-03 20:12:48.084 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Added 399 to queue - size 20
2020-12-03 20:12:48.084 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.