2020-01-04 10:59:17.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-04 10:59:17.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-04 10:59:17.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-04 10:59:17.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-01-04 10:59:17.280 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:678d2864:node9:switch_binary --> OFF [OnOffType]
2020-01-04 10:59:17.280 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 9: Creating new message for application command SWITCH_BINARY_SET
2020-01-04 10:59:17.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported
2020-01-04 10:59:17.281 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
2020-01-04 10:59:17.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2020-01-04 10:59:17.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 1227 to queue - size 3
2020-01-04 10:59:17.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-01-04 10:59:17.282 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 09 03 25 01 00 25 24 C9
2020-01-04 10:59:17.282 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0A 00 13 09 03 25 01 00 25 24 C9
2020-01-04 10:59:17.283 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-01-04 10:59:17.283 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1227: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 36
2020-01-04 10:59:17.284 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-01-04 10:59:17.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-04 10:59:17.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-04 10:59:17.285 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9
2020-01-04 10:59:17.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1227: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 36
2020-01-04 10:59:17.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-04 10:59:17.284 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling initialised at 1800 seconds - start in 1500 milliseconds.
2020-01-04 10:59:17.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-01-04 10:59:17.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-04 10:59:17.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1227: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 36
2020-01-04 10:59:17.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-01-04 10:59:17.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1227: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 36
2020-01-04 10:59:17.288 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-04 10:59:17.288 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData was not placed on stack.
2020-01-04 10:59:17.288 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1227: Transaction CANCELLED
2020-01-04 10:59:17.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2020-01-04 10:59:17.290 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-01-04 10:59:17.290 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: CANCEL while sending message. Requeueing - 2 attempts left!
2020-01-04 10:59:17.290 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1227: Transaction RESET with 2 retries remaining.
2020-01-04 10:59:17.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2020-01-04 10:59:17.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 1227 to queue - size 3
2020-01-04 10:59:17.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-04 10:59:17.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 1227: Transaction not completed
2020-01-04 10:59:17.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-04 10:59:17.293 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-04 10:59:17.540 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-01-04 10:59:17.540 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 09 03 25 01 00 25 25 C8
2020-01-04 10:59:17.541 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0A 00 13 09 03 25 01 00 25 25 C8
2020-01-04 10:59:17.541 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-01-04 10:59:17.542 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1227: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 37
2020-01-04 10:59:17.542 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-01-04 10:59:17.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-04 10:59:17.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-04 10:59:17.544 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9
2020-01-04 10:59:17.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1227: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 37
2020-01-04 10:59:17.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-01-04 10:59:17.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-04 10:59:17.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-04 10:59:17.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-04 10:59:17.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-04 10:59:17.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1227: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 37
2020-01-04 10:59:17.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-01-04 10:59:17.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1227: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 37
2020-01-04 10:59:17.548 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-04 10:59:17.548 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData was not placed on stack.
2020-01-04 10:59:17.548 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1227: Transaction CANCELLED
2020-01-04 10:59:17.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2020-01-04 10:59:17.549 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-01-04 10:59:17.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: CANCEL while sending message. Requeueing - 1 attempts left!
2020-01-04 10:59:17.550 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1227: Transaction RESET with 1 retries remaining.
2020-01-04 10:59:17.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2020-01-04 10:59:17.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 1227 to queue - size 3
2020-01-04 10:59:17.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-04 10:59:17.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 1227: Transaction not completed