2019-06-23 16:21:29.627 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 14: Node Status event - Node is AWAKE
2019-06-23 16:21:29.627 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Commands processed 1.
2019-06-23 16:21:29.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandCl
assPayload@7ea81cd.
2019-06-23 16:21:29.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-06-23 16:21:29.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-06-23 16:21:29.629 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:29.629 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-23 16:21:29.629 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 14: isNonceAvailable = null
2019-06-23 16:21:29.630 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1
2019-06-23 16:21:29.630 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0E 02 98 40 25 14 00 
2019-06-23 16:21:29.630 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 14: Sending REQUEST Message = 01 09 00 13 0E 02 98 40 25 14 00 
2019-06-23 16:21:29.632 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-23 16:21:29.632 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 309: [WAIT_RESPONSE] priority=High, require
sResponse=true, callback: 20
2019-06-23 16:21:29.633 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-23 16:21:29.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, 
callback=0, payload=
2019-06-23 16:21:29.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload
=
2019-06-23 16:21:29.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 309: [WAIT_RESPONSE] priority=High, requiresResponse=true, callb
ack: 20
2019-06-23 16:21:29.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-23 16:21:29.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:29.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-23 16:21:29.641 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2019-06-23 16:21:29.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], 
dest=255, callback=0, payload=01 
2019-06-23 16:21:29.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=
0, payload=01 
2019-06-23 16:21:29.644 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 309: [WAIT_RESPONSE] priority=High, requiresResponse=true, callb
ack: 20
2019-06-23 16:21:29.644 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-23 16:21:29.644 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 309: [WAIT_RESPONSE] priority=High, requiresResponse=true, cal
lback: 20
2019-06-23 16:21:29.644 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=
0, payload=01 
2019-06-23 16:21:29.644 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 14: sentData successfully placed on stack.
2019-06-23 16:21:29.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 309: Advanced to WAIT_REQUEST
2019-06-23 16:21:29.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: TID 309: Transaction not completed
2019-06-23 16:21:29.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:29.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-23 16:21:29.661 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 14 00 00 03 FC 
2019-06-23 16:21:29.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=20, payload=14 00 00 03 
2019-06-23 16:21:29.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=20, payload=14 00 00 03 
2019-06-23 16:21:29.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 309: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 20
2019-06-23 16:21:29.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-23 16:21:29.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 309: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 20
2019-06-23 16:21:29.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 309: (Callback 20)
2019-06-23 16:21:29.665 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-06-23 16:21:29.666 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 309: callback 20
2019-06-23 16:21:29.666 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=20, payload=14 00 00 03 
2019-06-23 16:21:29.666 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 14: SendData Request. CallBack ID = 20, Status = Transmission complete and ACK received(0)
2019-06-23 16:21:29.667 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 309: Advanced to WAIT_DATA
2019-06-23 16:21:29.667 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: TID 309: Transaction not completed
2019-06-23 16:21:29.667 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:29.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-23 16:21:29.683 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 0E 0A 98 80 D0 81 7E C9 A4 79 BB 2F 58 
2019-06-23 16:21:29.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=14, callback=0, payload=00 0E 0A 98 80 D0 81 7E C9 A4 79 BB 2F 
2019-06-23 16:21:29.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=14, callback=0, payload=00 0E 0A 98 80 D0 81 7E C9 A4 79 BB 2F 
2019-06-23 16:21:29.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-23 16:21:29.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Application Command Request (ALIVE:SECURITY_REPORT)
2019-06-23 16:21:29.691 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2019-06-23 16:21:29.691 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: SECURITY check internal
2019-06-23 16:21:29.696 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 14: Received COMMAND_CLASS_SECURITY V0 SECURITY_NONCE_REPORT
2019-06-23 16:21:29.696 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 14: NONCE Received start...
2019-06-23 16:21:29.696 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 14: NONCE Received start null
2019-06-23 16:21:29.697 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 14: NONCE Received ZWaveNonce [nonceBytes=(D0 81 7E C9 A4 79 BB 2F ), timer=42311, valid=true]
2019-06-23 16:21:29.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Commands processed 1.
2019-06-23 16:21:29.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5cc13651.
2019-06-23 16:21:29.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5cc13651.
2019-06-23 16:21:29.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: notifyTransactionResponse TID:309 DONE
2019-06-23 16:21:29.698 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-23 16:21:29.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2019-06-23 16:21:29.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-06-23 16:21:29.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:29.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-23 16:21:29.700 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_MESSAGE_ENCAPSULATION version 1
2019-06-23 16:21:29.700 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 14: SECURITY_TXD 98 02 
2019-06-23 16:21:29.700 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1D 00 13 0E 16 98 81 96 4C A4 C4 57 BF 81 A8 37 A3 6D D0 78 4C 19 3D CA 00 55 BA 25 15 A7 
2019-06-23 16:21:29.701 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 14: Sending REQUEST Message = 01 1D 00 13 0E 16 98 81 96 4C A4 C4 57 BF 81 A8 37 A3 6D D0 78 4C 19 3D CA 00 55 BA 25 15 A7 
2019-06-23 16:21:29.702 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-23 16:21:29.702 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 250: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 21
2019-06-23 16:21:29.704 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-23 16:21:29.704 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-23 16:21:29.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-23 16:21:29.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 250: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 21
2019-06-23 16:21:29.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-23 16:21:29.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:29.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-23 16:21:29.713 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2019-06-23 16:21:29.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2019-06-23 16:21:29.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2019-06-23 16:21:29.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 250: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 21
2019-06-23 16:21:29.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-23 16:21:29.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 250: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 21
2019-06-23 16:21:29.717 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2019-06-23 16:21:29.717 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 14: sentData successfully placed on stack.
2019-06-23 16:21:29.718 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 250: Advanced to WAIT_REQUEST
2019-06-23 16:21:29.718 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: TID 250: Transaction not completed
2019-06-23 16:21:29.718 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:29.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-23 16:21:29.737 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 15 00 00 03 FD 
2019-06-23 16:21:29.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=21, payload=15 00 00 03 
2019-06-23 16:21:29.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=21, payload=15 00 00 03 
2019-06-23 16:21:29.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 250: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 21
2019-06-23 16:21:29.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-23 16:21:29.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 250: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 21
2019-06-23 16:21:29.741 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 250: (Callback 21)
2019-06-23 16:21:29.741 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-06-23 16:21:29.741 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 250: callback 21
2019-06-23 16:21:29.741 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=21, payload=15 00 00 03 
2019-06-23 16:21:29.741 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 14: SendData Request. CallBack ID = 21, Status = Transmission complete and ACK received(0)
2019-06-23 16:21:29.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 250: Advanced to WAIT_DATA
2019-06-23 16:21:29.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: TID 250: Transaction not completed
2019-06-23 16:21:29.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:29.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-23 16:21:30.872 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: WakeupTimerTask 1 Messages waiting, state SECURITY_REPORT
2019-06-23 16:21:32.121 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: WakeupTimerTask 1 Messages waiting, state SECURITY_REPORT
2019-06-23 16:21:32.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: No more messages, go back to sleep
2019-06-23 16:21:32.122 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 14: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2019-06-23 16:21:32.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: SECURITY NOT required on COMMAND_CLASS_WAKE_UP
2019-06-23 16:21:32.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
2019-06-23 16:21:32.122 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1861438e
2019-06-23 16:21:32.145 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Bump transaction 310 priority from Immediate to Immediate
2019-06-23 16:21:32.146 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Adding to device queue
2019-06-23 16:21:32.146 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Added 310 to queue - size 5
2019-06-23 16:21:32.146 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-23 16:21:32.652 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 0A 09 71 05 00 00 00 FF 07 08 00 73 
2019-06-23 16:21:32.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=10, callback=0, payload=00 0A 09 71 05 00 00 00 FF 07 08 00 
2019-06-23 16:21:32.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=10, callback=0, payload=00 0A 09 71 05 00 00 00 FF 07 08 00 
2019-06-23 16:21:32.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-23 16:21:32.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Application Command Request (ALIVE:SECURITY_REPORT)
2019-06-23 16:21:34.743 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 14: TID 250: Timeout at state WAIT_DATA. 3 retries remaining.
2019-06-23 16:21:34.743 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 250: Transaction CANCELLED
2019-06-23 16:21:34.743 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-23 16:21:34.744 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: notifyTransactionResponse TID:250 CANCELLED
2019-06-23 16:21:34.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-23 16:21:34.750 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0E 02 84 08 25 16 56 
2019-06-23 16:21:34.750 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 14: Sending REQUEST Message = 01 09 00 13 0E 02 84 08 25 16 56 
2019-06-23 16:21:34.752 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-23 16:21:34.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 310: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 22
2019-06-23 16:21:34.753 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-23 16:21:34.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-23 16:21:34.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-23 16:21:34.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-23 16:21:34.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-23 16:21:34.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:34.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-23 16:21:34.755 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 250: Transaction event listener: DONE: CANCELLED -> 
2019-06-23 16:21:34.755 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 14: Node Init response (1) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1a3a5f39
2019-06-23 16:21:34.755 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 14: No data from device, but it was ACK'd. Possibly not supported? (Try 1)
2019-06-23 16:21:34.761 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2019-06-23 16:21:34.763 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2019-06-23 16:21:34.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2019-06-23 16:21:34.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 310: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 22
2019-06-23 16:21:34.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-23 16:21:34.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 310: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 22
2019-06-23 16:21:34.764 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2019-06-23 16:21:34.764 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 14: sentData successfully placed on stack.
2019-06-23 16:21:34.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 310: Advanced to WAIT_REQUEST
2019-06-23 16:21:34.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: TID 310: Transaction not completed
2019-06-23 16:21:34.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:34.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-23 16:21:34.780 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 16 00 00 03 FE 
2019-06-23 16:21:34.782 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=22, payload=16 00 00 03 
2019-06-23 16:21:34.782 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=22, payload=16 00 00 03 
2019-06-23 16:21:34.783 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 310: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 22
2019-06-23 16:21:34.783 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-23 16:21:34.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 310: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 22
2019-06-23 16:21:34.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 310: (Callback 22)
2019-06-23 16:21:34.784 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-06-23 16:21:34.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 310: callback 22
2019-06-23 16:21:34.785 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=22, payload=16 00 00 03 
2019-06-23 16:21:34.786 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 14: SendData Request. CallBack ID = 22, Status = Transmission complete and ACK received(0)
2019-06-23 16:21:34.786 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 310: Transaction COMPLETED
2019-06-23 16:21:34.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Response processed after 35ms
2019-06-23 16:21:34.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: TID 310: Transaction completed
2019-06-23 16:21:34.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: notifyTransactionResponse TID:310 DONE
2019-06-23 16:21:34.788 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-23 16:21:34.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-23 16:21:34.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-23 16:21:34.789 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 310: Transaction event listener: DONE: DONE -> 
2019-06-23 16:21:34.797 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: Went to sleep COMPLETE
2019-06-23 16:21:37.012 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 14: ZWaveCommandClassTransactionPayload - send to node
2019-06-23 16:21:37.012 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: SECURITY check internal
2019-06-23 16:21:37.012 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 14: Command Class COMMAND_CLASS_SECURITY is NOT required to be secured
2019-06-23 16:21:37.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1e05c8a6
2019-06-23 16:21:37.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Bump transaction 311 priority from Immediate to Immediate
2019-06-23 16:21:37.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Adding to device queue
2019-06-23 16:21:37.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 14: Added 311 to queue - size 5
2019-06-23 16:21:37.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.