2020-05-14 17:06:09.079 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:09.079 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:26.420 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Command received zwave:device:fc25cf47:node28:lock_door --> ON [OnOffType]
2020-05-14 17:06:26.420 [DEBUG] [al.protocol.commandclass.ZWaveDoorLockCommandClass] - NODE 28: Creating new message for application command DOORLOCK_SET, value 255
2020-05-14 17:06:26.421 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY required on COMMAND_CLASS_DOOR_LOCK
2020-05-14 17:06:26.422 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Command Class COMMAND_CLASS_DOOR_LOCK is required to be secured
2020-05-14 17:06:26.422 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Bump transaction 1414 priority from Get to Immediate
2020-05-14 17:06:26.423 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Adding to device queue
2020-05-14 17:06:26.424 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Added 1414 to queue - size 3
2020-05-14 17:06:26.424 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:26.425 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: isNonceAvailable = null
2020-05-14 17:06:26.426 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1
2020-05-14 17:06:26.427 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 1C 02 98 40 25 2C 2A
2020-05-14 17:06:26.428 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 09 00 13 1C 02 98 40 25 2C 2A
2020-05-14 17:06:26.430 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-14 17:06:26.430 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-14 17:06:26.430 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1415: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 44
2020-05-14 17:06:26.430 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:26.431 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:26.431 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:26.431 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-14 17:06:26.431 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:26.432 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:26.433 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling initialised at 86400 seconds - start in 1500 milliseconds.
2020-05-14 17:06:26.437 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-05-14 17:06:26.438 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:26.438 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:26.438 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1415: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 44
2020-05-14 17:06:26.439 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:26.439 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1415: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 44
2020-05-14 17:06:26.439 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:26.441 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: sentData successfully placed on stack.
2020-05-14 17:06:26.441 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1415: Advanced to WAIT_REQUEST
2020-05-14 17:06:26.441 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1415: Transaction not completed
2020-05-14 17:06:26.441 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:26.441 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:27.733 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2C 00 00 82 45
2020-05-14 17:06:27.735 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=44, payload=2C 00 00 82
2020-05-14 17:06:27.736 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=44, payload=2C 00 00 82
2020-05-14 17:06:27.736 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1415: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 44
2020-05-14 17:06:27.737 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:27.737 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1415: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 44
2020-05-14 17:06:27.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1415: (Callback 44)
2020-05-14 17:06:27.738 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-14 17:06:27.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 1415: callback 44
2020-05-14 17:06:27.739 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=44, payload=2C 00 00 82
2020-05-14 17:06:27.740 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: SendData Request. CallBack ID = 44, Status = Transmission complete and ACK received(0)
2020-05-14 17:06:27.740 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:27.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1415: Advanced to WAIT_DATA
2020-05-14 17:06:27.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1415: Transaction not completed
2020-05-14 17:06:27.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:27.748 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 1C 0A 98 80 0B E1 31 60 76 1C B6 1A 98
2020-05-14 17:06:27.750 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 0A 98 80 0B E1 31 60 76 1C B6 1A
2020-05-14 17:06:27.751 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 0A 98 80 0B E1 31 60 76 1C B6 1A
2020-05-14 17:06:27.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:27.753 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Application Command Request (ALIVE:DONE)
2020-05-14 17:06:27.754 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:27.755 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-05-14 17:06:27.756 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY check internal
2020-05-14 17:06:27.761 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 28: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT
2020-05-14 17:06:27.762 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: NONCE Received start...
2020-05-14 17:06:27.763 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: NONCE Received start null
2020-05-14 17:06:27.765 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: NONCE Received ZWaveNonce [nonceBytes=(0B E1 31 60 76 1C B6 1A ), timer=155838, valid=true]
2020-05-14 17:06:27.766 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Commands processed 1.
2020-05-14 17:06:27.767 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@39fa7f36.
2020-05-14 17:06:27.768 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@39fa7f36.
2020-05-14 17:06:27.769 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:1415 DONE
2020-05-14 17:06:27.770 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-14 17:06:27.770 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:27.771 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-05-14 17:06:27.771 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.771 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:27.771 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_MESSAGE_ENCAPSULATION version 1
2020-05-14 17:06:27.772 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: SECURITY_TXD 62 01 FF
2020-05-14 17:06:27.773 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1E 00 13 1C 17 98 81 58 EE 04 C6 83 8B 82 7B 5B 32 74 53 0B D4 E1 6D AB 3E E6 81 63 25 2D E1
2020-05-14 17:06:27.774 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 1E 00 13 1C 17 98 81 58 EE 04 C6 83 8B 82 7B 5B 32 74 53 0B D4 E1 6D AB 3E E6 81 63 25 2D E1
2020-05-14 17:06:27.775 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-14 17:06:27.776 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1414: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 45
2020-05-14 17:06:27.777 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-14 17:06:27.777 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:27.778 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:27.779 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1414: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 45
2020-05-14 17:06:27.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-14 17:06:27.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.783 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:27.784 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-05-14 17:06:27.785 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:27.786 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:27.787 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1414: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 45
2020-05-14 17:06:27.788 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:27.789 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1414: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 45
2020-05-14 17:06:27.790 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:27.791 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: sentData successfully placed on stack.
2020-05-14 17:06:27.792 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1414: Advanced to WAIT_REQUEST
2020-05-14 17:06:27.793 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1414: Transaction not completed
2020-05-14 17:06:27.794 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.795 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:27.810 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2D 00 00 03 C5
2020-05-14 17:06:27.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=45, payload=2D 00 00 03
2020-05-14 17:06:27.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=45, payload=2D 00 00 03
2020-05-14 17:06:27.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1414: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 45
2020-05-14 17:06:27.813 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:27.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1414: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 45
2020-05-14 17:06:27.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1414: (Callback 45)
2020-05-14 17:06:27.816 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-14 17:06:27.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 1414: callback 45
2020-05-14 17:06:27.818 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=45, payload=2D 00 00 03
2020-05-14 17:06:27.819 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: SendData Request. CallBack ID = 45, Status = Transmission complete and ACK received(0)
2020-05-14 17:06:27.820 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:27.821 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 1414: Transaction COMPLETED
2020-05-14 17:06:27.822 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Response processed after 46ms
2020-05-14 17:06:27.823 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1414: Transaction completed
2020-05-14 17:06:27.824 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:1414 DONE
2020-05-14 17:06:27.825 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-14 17:06:27.826 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.827 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:27.851 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 1C 02 98 40 35
2020-05-14 17:06:27.852 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 02 98 40
2020-05-14 17:06:27.853 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 02 98 40
2020-05-14 17:06:27.854 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:27.855 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Application Command Request (ALIVE:DONE)
2020-05-14 17:06:27.855 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:27.856 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-05-14 17:06:27.857 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY check internal
2020-05-14 17:06:27.858 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 28: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2020-05-14 17:06:27.859 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1
2020-05-14 17:06:27.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Added to secure queue - size 1
2020-05-14 17:06:27.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Commands processed 1.
2020-05-14 17:06:27.862 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@67b1fd6b.
2020-05-14 17:06:27.863 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-05-14 17:06:27.864 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-05-14 17:06:27.865 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.866 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:27.867 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 1C 0A 98 80 DC 70 D2 D1 0D 04 ED 02 25 2E B1
2020-05-14 17:06:27.868 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 11 00 13 1C 0A 98 80 DC 70 D2 D1 0D 04 ED 02 25 2E B1
2020-05-14 17:06:27.870 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-14 17:06:27.871 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-14 17:06:27.871 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:27.872 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1416: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 46
2020-05-14 17:06:27.873 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:27.874 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1416: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 46
2020-05-14 17:06:27.875 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-14 17:06:27.876 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.877 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:27.879 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-05-14 17:06:27.880 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:27.880 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:27.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1416: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 46
2020-05-14 17:06:27.882 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:27.883 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1416: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 46
2020-05-14 17:06:27.884 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:27.885 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: sentData successfully placed on stack.
2020-05-14 17:06:27.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1416: Advanced to WAIT_REQUEST
2020-05-14 17:06:27.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1416: Transaction not completed
2020-05-14 17:06:27.888 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.889 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:27.901 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2E 00 00 02 C7
2020-05-14 17:06:27.902 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=46, payload=2E 00 00 02
2020-05-14 17:06:27.903 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=46, payload=2E 00 00 02
2020-05-14 17:06:27.904 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1416: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 46
2020-05-14 17:06:27.904 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:27.905 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1416: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 46
2020-05-14 17:06:27.906 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1416: (Callback 46)
2020-05-14 17:06:27.907 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-14 17:06:27.908 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 1416: callback 46
2020-05-14 17:06:27.909 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=46, payload=2E 00 00 02
2020-05-14 17:06:27.910 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: SendData Request. CallBack ID = 46, Status = Transmission complete and ACK received(0)
2020-05-14 17:06:27.911 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:27.912 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 1416: Transaction COMPLETED
2020-05-14 17:06:27.913 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Response processed after 43ms
2020-05-14 17:06:27.914 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1416: Transaction completed
2020-05-14 17:06:27.915 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:1416 DONE
2020-05-14 17:06:27.916 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-14 17:06:27.917 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.918 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:27.925 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 24 00 04 00 1C 1E 98 81 78 B7 79 08 4A C6 78 79 3E 7E 62 04 E8 6C 2B 8D 3F 8A 2F DC 78 AE A0 E4 08 D8 7A B1 3C
2020-05-14 17:06:27.927 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 1E 98 81 78 B7 79 08 4A C6 78 79 3E 7E 62 04 E8 6C 2B 8D 3F 8A 2F DC 78 AE A0 E4 08 D8 7A B1
2020-05-14 17:06:27.928 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 1E 98 81 78 B7 79 08 4A C6 78 79 3E 7E 62 04 E8 6C 2B 8D 3F 8A 2F DC 78 AE A0 E4 08 D8 7A B1
2020-05-14 17:06:27.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:27.930 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Application Command Request (ALIVE:DONE)
2020-05-14 17:06:27.931 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:27.932 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Decapsulating COMMAND_CLASS_SECURITY
2020-05-14 17:06:27.933 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling...
2020-05-14 17:06:27.933 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling zwave:device:fc25cf47:node28:lock_door
2020-05-14 17:06:27.934 [DEBUG] [ng.zwave.internal.converter.ZWaveDoorLockConverter] - NODE 28: Generating poll message for COMMAND_CLASS_DOOR_LOCK endpoint 0
2020-05-14 17:06:27.934 [DEBUG] [al.protocol.commandclass.ZWaveDoorLockCommandClass] - NODE 28: Creating new message for application command DOORLOCK_GET
2020-05-14 17:06:27.934 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY required on COMMAND_CLASS_DOOR_LOCK
2020-05-14 17:06:27.935 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Command Class COMMAND_CLASS_DOOR_LOCK is required to be secured
2020-05-14 17:06:27.936 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: SECURITY_RXD 71 05 18 01 00 FF 06 03 00 00
2020-05-14 17:06:27.937 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2020-05-14 17:06:27.938 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 28: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT
2020-05-14 17:06:27.935 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling zwave:device:fc25cf47:node28:alarm_burglar
2020-05-14 17:06:27.939 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling zwave:device:fc25cf47:node28:alarm_access
2020-05-14 17:06:27.939 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling zwave:device:fc25cf47:node28:alarm_power
2020-05-14 17:06:27.940 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling zwave:device:fc25cf47:node28:battery-level
2020-05-14 17:06:27.947 [DEBUG] [ing.zwave.internal.converter.ZWaveBatteryConverter] - NODE 28: Generating poll message for COMMAND_CLASS_BATTERY endpoint 0
2020-05-14 17:06:27.940 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 28: NOTIFICATION report - 24 = 1, event=3, status=255, plen=0
2020-05-14 17:06:27.947 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY required on COMMAND_CLASS_BATTERY
2020-05-14 17:06:27.948 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Command Class COMMAND_CLASS_BATTERY is required to be secured
2020-05-14 17:06:27.948 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Bump transaction 1417 priority from Get to Immediate
2020-05-14 17:06:27.949 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Adding to device queue
2020-05-14 17:06:27.949 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Added 1417 to queue - size 3
2020-05-14 17:06:27.949 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:27.949 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: isNonceAvailable = null
2020-05-14 17:06:27.950 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1
2020-05-14 17:06:27.949 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 28: Alarm Type = ACCESS_CONTROL (24)
2020-05-14 17:06:27.950 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 1C 02 98 40 25 2F 29
2020-05-14 17:06:27.951 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 09 00 13 1C 02 98 40 25 2F 29
2020-05-14 17:06:27.952 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-14 17:06:27.952 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1418: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 47
2020-05-14 17:06:27.952 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2020-05-14 17:06:27.953 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-14 17:06:27.953 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:27.955 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2020-05-14 17:06:27.956 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 28: Alarm converter processing NOTIFICATION
2020-05-14 17:06:27.957 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 28: Alarm converter NOTIFICATION event is 3, type OnOffType
2020-05-14 17:06:27.958 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 28: Alarm converter processing NOTIFICATION
2020-05-14 17:06:27.953 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Bump transaction 1419 priority from Get to Immediate
2020-05-14 17:06:27.959 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Adding to device queue
2020-05-14 17:06:27.959 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Added 1419 to queue - size 3
2020-05-14 17:06:27.960 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:27.960 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-05-14 17:06:27.961 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:27.962 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 28: Alarm converter NOTIFICATION event is 3, type OnOffType
2020-05-14 17:06:27.963 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 28: Alarm converter NOTIFICATION event is 3, channel alarm_access is not implemented.
2020-05-14 17:06:27.964 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 28: Alarm converter processing NOTIFICATION
2020-05-14 17:06:27.965 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 28: Alarm converter NOTIFICATION event is 3, type OnOffType
2020-05-14 17:06:27.966 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Commands processed 1.
2020-05-14 17:06:27.967 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@c1b7375.
2020-05-14 17:06:27.968 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:27.969 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:27.970 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:27.971 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1418: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 47
2020-05-14 17:06:27.972 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-14 17:06:27.973 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:27.974 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1418: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 47
2020-05-14 17:06:27.975 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:27.976 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1418: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 47
2020-05-14 17:06:27.977 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:27.978 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: sentData successfully placed on stack.
2020-05-14 17:06:27.979 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1418: Advanced to WAIT_REQUEST
2020-05-14 17:06:27.980 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2F 00 00 02 C6
2020-05-14 17:06:27.982 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=47, payload=2F 00 00 02
2020-05-14 17:06:27.983 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1418: Transaction not completed
2020-05-14 17:06:27.984 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=47, payload=2F 00 00 02
2020-05-14 17:06:27.985 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1418: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 47
2020-05-14 17:06:27.986 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:27.987 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1418: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 47
2020-05-14 17:06:27.987 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1418: (Callback 47)
2020-05-14 17:06:27.989 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-14 17:06:27.990 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 1418: callback 47
2020-05-14 17:06:27.991 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=47, payload=2F 00 00 02
2020-05-14 17:06:27.992 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: SendData Request. CallBack ID = 47, Status = Transmission complete and ACK received(0)
2020-05-14 17:06:27.993 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:27.994 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1418: Advanced to WAIT_DATA
2020-05-14 17:06:27.996 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 1C 0A 98 80 2E 22 38 3B A4 C7 A2 7F 54
2020-05-14 17:06:27.997 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1418: Transaction not completed
2020-05-14 17:06:27.997 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:27.997 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 0A 98 80 2E 22 38 3B A4 C7 A2 7F
2020-05-14 17:06:27.997 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:27.998 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 0A 98 80 2E 22 38 3B A4 C7 A2 7F
2020-05-14 17:06:27.999 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:27.999 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Application Command Request (ALIVE:DONE)
2020-05-14 17:06:27.999 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.000 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-05-14 17:06:28.000 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY check internal
2020-05-14 17:06:28.001 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 28: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT
2020-05-14 17:06:28.001 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: NONCE Received start...
2020-05-14 17:06:28.001 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: NONCE Received start null
2020-05-14 17:06:28.002 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: NONCE Received ZWaveNonce [nonceBytes=(2E 22 38 3B A4 C7 A2 7F ), timer=199941, valid=true]
2020-05-14 17:06:28.002 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Commands processed 1.
2020-05-14 17:06:28.002 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1391e14.
2020-05-14 17:06:28.003 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1391e14.
2020-05-14 17:06:28.003 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:1418 DONE
2020-05-14 17:06:28.004 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-14 17:06:28.004 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:28.005 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-05-14 17:06:28.005 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.005 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:28.006 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_MESSAGE_ENCAPSULATION version 1
2020-05-14 17:06:28.007 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: SECURITY_TXD 62 02
2020-05-14 17:06:28.007 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1D 00 13 1C 16 98 81 F5 AF B5 F2 3A 71 FF 05 BD 8A C3 2E 80 09 7B 93 FD 54 0C 59 25 30 1C
2020-05-14 17:06:28.008 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 1D 00 13 1C 16 98 81 F5 AF B5 F2 3A 71 FF 05 BD 8A C3 2E 80 09 7B 93 FD 54 0C 59 25 30 1C
2020-05-14 17:06:28.009 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-14 17:06:28.009 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1417: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 48
2020-05-14 17:06:28.011 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-14 17:06:28.011 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.011 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.012 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1417: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 48
2020-05-14 17:06:28.012 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-14 17:06:28.012 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.013 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.018 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-05-14 17:06:28.019 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.019 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.019 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1417: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 48
2020-05-14 17:06:28.020 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:28.020 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1417: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 48
2020-05-14 17:06:28.021 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.021 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: sentData successfully placed on stack.
2020-05-14 17:06:28.022 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1417: Advanced to WAIT_REQUEST
2020-05-14 17:06:28.022 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1417: Transaction not completed
2020-05-14 17:06:28.022 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.023 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.044 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 30 00 00 03 D8
2020-05-14 17:06:28.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=48, payload=30 00 00 03
2020-05-14 17:06:28.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=48, payload=30 00 00 03
2020-05-14 17:06:28.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1417: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 48
2020-05-14 17:06:28.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:28.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1417: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 48
2020-05-14 17:06:28.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1417: (Callback 48)
2020-05-14 17:06:28.049 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-14 17:06:28.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 1417: callback 48
2020-05-14 17:06:28.049 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=48, payload=30 00 00 03
2020-05-14 17:06:28.050 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: SendData Request. CallBack ID = 48, Status = Transmission complete and ACK received(0)
2020-05-14 17:06:28.050 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.051 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1417: Advanced to WAIT_DATA
2020-05-14 17:06:28.051 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1417: Transaction not completed
2020-05-14 17:06:28.051 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.065 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.080 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 1C 02 98 40 35
2020-05-14 17:06:28.082 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 02 98 40
2020-05-14 17:06:28.083 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 02 98 40
2020-05-14 17:06:28.083 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:28.084 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Application Command Request (ALIVE:DONE)
2020-05-14 17:06:28.084 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.084 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-05-14 17:06:28.084 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY check internal
2020-05-14 17:06:28.085 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 28: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2020-05-14 17:06:28.085 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1
2020-05-14 17:06:28.085 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Added to secure queue - size 1
2020-05-14 17:06:28.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Commands processed 1.
2020-05-14 17:06:28.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6bd0d2ca.
2020-05-14 17:06:28.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6bd0d2ca.
2020-05-14 17:06:28.087 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:28.087 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:28.087 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.088 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 1C 0A 98 80 71 6F DF B1 79 F3 7C F1 25 31 90
2020-05-14 17:06:28.089 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 11 00 13 1C 0A 98 80 71 6F DF B1 79 F3 7C F1 25 31 90
2020-05-14 17:06:28.090 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-14 17:06:28.090 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1420: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 49
2020-05-14 17:06:28.091 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-14 17:06:28.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.095 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.095 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1420: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 49
2020-05-14 17:06:28.096 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-14 17:06:28.097 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.098 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-05-14 17:06:28.099 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 2 out at start. Holdoff false.
2020-05-14 17:06:28.100 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.101 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.102 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1420: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 49
2020-05-14 17:06:28.103 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 2
2020-05-14 17:06:28.104 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1420: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 49
2020-05-14 17:06:28.105 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.106 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: sentData successfully placed on stack.
2020-05-14 17:06:28.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1420: Advanced to WAIT_REQUEST
2020-05-14 17:06:28.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1420: Transaction not completed
2020-05-14 17:06:28.109 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.111 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 2 out at start. Holdoff false.
2020-05-14 17:06:28.120 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 31 00 00 02 D8
2020-05-14 17:06:28.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=49, payload=31 00 00 02
2020-05-14 17:06:28.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=49, payload=31 00 00 02
2020-05-14 17:06:28.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1420: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 49
2020-05-14 17:06:28.125 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 2
2020-05-14 17:06:28.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1420: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 49
2020-05-14 17:06:28.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1417: (Callback 48)
2020-05-14 17:06:28.128 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - NO callback match! (48 <> 49)
2020-05-14 17:06:28.129 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1420: (Callback 49)
2020-05-14 17:06:28.130 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-14 17:06:28.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 1420: callback 49
2020-05-14 17:06:28.132 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=49, payload=31 00 00 02
2020-05-14 17:06:28.133 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: SendData Request. CallBack ID = 49, Status = Transmission complete and ACK received(0)
2020-05-14 17:06:28.134 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.135 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 1420: Transaction COMPLETED
2020-05-14 17:06:28.136 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Response processed after 46ms
2020-05-14 17:06:28.137 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1420: Transaction completed
2020-05-14 17:06:28.138 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:1420 DONE
2020-05-14 17:06:28.139 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-14 17:06:28.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.144 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 21 00 04 00 1C 1B 98 81 EF 84 F1 D6 97 2C 3F C0 D3 78 DA 0C 10 BE 4D DC 71 17 7F AA 63 64 D3 67 72 FC
2020-05-14 17:06:28.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 1B 98 81 EF 84 F1 D6 97 2C 3F C0 D3 78 DA 0C 10 BE 4D DC 71 17 7F AA 63 64 D3 67 72
2020-05-14 17:06:28.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 1B 98 81 EF 84 F1 D6 97 2C 3F C0 D3 78 DA 0C 10 BE 4D DC 71 17 7F AA 63 64 D3 67 72
2020-05-14 17:06:28.148 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:28.148 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Application Command Request (ALIVE:DONE)
2020-05-14 17:06:28.148 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.148 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Decapsulating COMMAND_CLASS_SECURITY
2020-05-14 17:06:28.149 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: SECURITY_RXD 62 03 FF 00 00 FE FE
2020-05-14 17:06:28.149 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Incoming command class COMMAND_CLASS_DOOR_LOCK, endpoint 0
2020-05-14 17:06:28.150 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 28: Received COMMAND_CLASS_DOOR_LOCK V1 DOOR_LOCK_REPORT
2020-05-14 17:06:28.150 [DEBUG] [al.protocol.commandclass.ZWaveDoorLockCommandClass] - NODE 28: Door-Lock state report - lockState=Secured, handlesMode=0, doorCondition=0, timeoutMinutes=254, timeoutSeconds=254
2020-05-14 17:06:28.150 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-05-14 17:06:28.150 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_DOOR_LOCK, value=255
2020-05-14 17:06:28.150 [DEBUG] [ng.zwave.internal.converter.ZWaveDoorLockConverter] - NODE 28: Handle door lock event DOOR_LOCK_STATE
2020-05-14 17:06:28.152 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Updating channel state zwave:device:fc25cf47:node28:lock_door to ON [OnOffType]
2020-05-14 17:06:28.155 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-05-14 17:06:28.156 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_DOOR_LOCK, value=0
2020-05-14 17:06:28.157 [DEBUG] [ng.zwave.internal.converter.ZWaveDoorLockConverter] - NODE 28: Handle door lock event DOOR_CONDITION
2020-05-14 17:06:28.158 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Commands processed 1.
2020-05-14 17:06:28.159 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3e2307e.
2020-05-14 17:06:28.160 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3e2307e.
2020-05-14 17:06:28.161 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:1417 DONE
2020-05-14 17:06:28.162 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-14 17:06:28.162 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:28.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-05-14 17:06:28.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:28.163 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: isNonceAvailable = null
2020-05-14 17:06:28.163 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1
2020-05-14 17:06:28.164 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 1C 02 98 40 25 32 34
2020-05-14 17:06:28.164 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 09 00 13 1C 02 98 40 25 32 34
2020-05-14 17:06:28.165 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-14 17:06:28.166 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-14 17:06:28.166 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.167 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1421: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 50
2020-05-14 17:06:28.168 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.169 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1421: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 50
2020-05-14 17:06:28.170 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-14 17:06:28.171 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.172 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-05-14 17:06:28.173 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.174 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.175 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.176 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1421: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 50
2020-05-14 17:06:28.178 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:28.179 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1421: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 50
2020-05-14 17:06:28.180 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.181 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: sentData successfully placed on stack.
2020-05-14 17:06:28.182 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1421: Advanced to WAIT_REQUEST
2020-05-14 17:06:28.183 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1421: Transaction not completed
2020-05-14 17:06:28.184 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.185 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.194 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 32 00 00 03 DA
2020-05-14 17:06:28.195 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=50, payload=32 00 00 03
2020-05-14 17:06:28.196 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=50, payload=32 00 00 03
2020-05-14 17:06:28.197 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1421: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 50
2020-05-14 17:06:28.198 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:28.199 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1421: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 50
2020-05-14 17:06:28.200 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1421: (Callback 50)
2020-05-14 17:06:28.201 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-14 17:06:28.202 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 1421: callback 50
2020-05-14 17:06:28.203 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=50, payload=32 00 00 03
2020-05-14 17:06:28.204 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: SendData Request. CallBack ID = 50, Status = Transmission complete and ACK received(0)
2020-05-14 17:06:28.205 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1421: Advanced to WAIT_DATA
2020-05-14 17:06:28.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1421: Transaction not completed
2020-05-14 17:06:28.208 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.209 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 1C 0A 98 80 D5 C6 42 46 86 FE CF 82 C7
2020-05-14 17:06:28.211 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 0A 98 80 D5 C6 42 46 86 FE CF 82
2020-05-14 17:06:28.212 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.213 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 0A 98 80 D5 C6 42 46 86 FE CF 82
2020-05-14 17:06:28.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:28.215 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Application Command Request (ALIVE:DONE)
2020-05-14 17:06:28.216 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.217 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-05-14 17:06:28.218 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY check internal
2020-05-14 17:06:28.219 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 28: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT
2020-05-14 17:06:28.220 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: NONCE Received start...
2020-05-14 17:06:28.221 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: NONCE Received start null
2020-05-14 17:06:28.222 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: NONCE Received ZWaveNonce [nonceBytes=(D5 C6 42 46 86 FE CF 82 ), timer=170069, valid=true]
2020-05-14 17:06:28.223 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Commands processed 1.
2020-05-14 17:06:28.224 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@54357bac.
2020-05-14 17:06:28.225 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@54357bac.
2020-05-14 17:06:28.226 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:1421 DONE
2020-05-14 17:06:28.228 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-14 17:06:28.228 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:28.228 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-05-14 17:06:28.228 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.229 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:28.229 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_MESSAGE_ENCAPSULATION version 1
2020-05-14 17:06:28.229 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: SECURITY_TXD 80 02
2020-05-14 17:06:28.230 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1D 00 13 1C 16 98 81 2B 10 04 A1 D4 7D A8 E5 13 2C 70 D5 5A 3B 3C 6F D5 C2 A4 DB 25 33 4E
2020-05-14 17:06:28.231 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 1D 00 13 1C 16 98 81 2B 10 04 A1 D4 7D A8 E5 13 2C 70 D5 5A 3B 3C 6F D5 C2 A4 DB 25 33 4E
2020-05-14 17:06:28.232 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-14 17:06:28.233 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1419: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 51
2020-05-14 17:06:28.234 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-14 17:06:28.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.236 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1419: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 51
2020-05-14 17:06:28.237 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-14 17:06:28.238 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.241 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-05-14 17:06:28.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.243 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.244 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1419: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 51
2020-05-14 17:06:28.245 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:28.246 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1419: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 51
2020-05-14 17:06:28.247 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.248 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: sentData successfully placed on stack.
2020-05-14 17:06:28.249 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1419: Advanced to WAIT_REQUEST
2020-05-14 17:06:28.250 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1419: Transaction not completed
2020-05-14 17:06:28.251 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.265 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 33 00 00 03 DB
2020-05-14 17:06:28.267 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=51, payload=33 00 00 03
2020-05-14 17:06:28.268 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=51, payload=33 00 00 03
2020-05-14 17:06:28.268 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1419: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 51
2020-05-14 17:06:28.269 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-14 17:06:28.270 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1419: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 51
2020-05-14 17:06:28.271 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1419: (Callback 51)
2020-05-14 17:06:28.272 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-14 17:06:28.273 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 1419: callback 51
2020-05-14 17:06:28.274 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=51, payload=33 00 00 03
2020-05-14 17:06:28.275 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: SendData Request. CallBack ID = 51, Status = Transmission complete and ACK received(0)
2020-05-14 17:06:28.276 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.277 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1419: Advanced to WAIT_DATA
2020-05-14 17:06:28.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1419: Transaction not completed
2020-05-14 17:06:28.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.304 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 1C 02 98 40 35
2020-05-14 17:06:28.305 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 02 98 40
2020-05-14 17:06:28.306 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 02 98 40
2020-05-14 17:06:28.306 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:28.307 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Application Command Request (ALIVE:DONE)
2020-05-14 17:06:28.308 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.309 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-05-14 17:06:28.310 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY check internal
2020-05-14 17:06:28.311 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 28: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2020-05-14 17:06:28.312 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1
2020-05-14 17:06:28.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Added to secure queue - size 1
2020-05-14 17:06:28.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Commands processed 1.
2020-05-14 17:06:28.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1d351680.
2020-05-14 17:06:28.316 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1d351680.
2020-05-14 17:06:28.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:28.318 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:28.319 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.320 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.321 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 1C 0A 98 80 04 AF 7B D8 A1 06 CC C0 25 34 41
2020-05-14 17:06:28.322 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 11 00 13 1C 0A 98 80 04 AF 7B D8 A1 06 CC C0 25 34 41
2020-05-14 17:06:28.324 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-14 17:06:28.325 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-14 17:06:28.325 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.326 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1422: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 52
2020-05-14 17:06:28.327 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-14 17:06:28.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1422: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 52
2020-05-14 17:06:28.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-14 17:06:28.329 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.330 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 2 out at start. Holdoff false.
2020-05-14 17:06:28.333 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-05-14 17:06:28.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1422: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 52
2020-05-14 17:06:28.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 2
2020-05-14 17:06:28.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1422: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 52
2020-05-14 17:06:28.338 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-05-14 17:06:28.339 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: sentData successfully placed on stack.
2020-05-14 17:06:28.340 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 1422: Advanced to WAIT_REQUEST
2020-05-14 17:06:28.341 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1422: Transaction not completed
2020-05-14 17:06:28.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.343 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 2 out at start. Holdoff false.
2020-05-14 17:06:28.355 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 34 00 00 03 DC
2020-05-14 17:06:28.356 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=52, payload=34 00 00 03
2020-05-14 17:06:28.356 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=52, payload=34 00 00 03
2020-05-14 17:06:28.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 1422: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 52
2020-05-14 17:06:28.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 2
2020-05-14 17:06:28.359 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 1422: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 52
2020-05-14 17:06:28.360 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1419: (Callback 51)
2020-05-14 17:06:28.361 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - NO callback match! (51 <> 52)
2020-05-14 17:06:28.362 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 1422: (Callback 52)
2020-05-14 17:06:28.363 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-14 17:06:28.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 1422: callback 52
2020-05-14 17:06:28.365 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=52, payload=34 00 00 03
2020-05-14 17:06:28.366 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 28: SendData Request. CallBack ID = 52, Status = Transmission complete and ACK received(0)
2020-05-14 17:06:28.367 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.368 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 1422: Transaction COMPLETED
2020-05-14 17:06:28.369 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Response processed after 45ms
2020-05-14 17:06:28.370 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: TID 1422: Transaction completed
2020-05-14 17:06:28.371 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:1422 DONE
2020-05-14 17:06:28.372 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-14 17:06:28.373 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.374 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-14 17:06:28.376 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 04 00 1C 17 98 81 27 5A EF 85 21 E5 07 83 39 4E D6 43 04 4E 4D 92 C0 AE 5C B6 31 61
2020-05-14 17:06:28.378 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 17 98 81 27 5A EF 85 21 E5 07 83 39 4E D6 43 04 4E 4D 92 C0 AE 5C B6 31
2020-05-14 17:06:28.379 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=28, callback=0, payload=00 1C 17 98 81 27 5A EF 85 21 E5 07 83 39 4E D6 43 04 4E 4D 92 C0 AE 5C B6 31
2020-05-14 17:06:28.379 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-14 17:06:28.379 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Application Command Request (ALIVE:DONE)
2020-05-14 17:06:28.380 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: resetResendCount initComplete=true isDead=false
2020-05-14 17:06:28.380 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Decapsulating COMMAND_CLASS_SECURITY
2020-05-14 17:06:28.382 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 28: SECURITY_RXD 80 03 5A
2020-05-14 17:06:28.382 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 28: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0
2020-05-14 17:06:28.382 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 28: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT
2020-05-14 17:06:28.383 [DEBUG] [nal.protocol.commandclass.ZWaveBatteryCommandClass] - NODE 28: Battery report value = 90
2020-05-14 17:06:28.383 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-05-14 17:06:28.383 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BATTERY, value=90
2020-05-14 17:06:28.384 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Updating channel state zwave:device:fc25cf47:node28:battery-level to 90 [DecimalType]
2020-05-14 17:06:28.384 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Commands processed 1.
2020-05-14 17:06:28.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@62ed7ed3.
2020-05-14 17:06:28.386 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@62ed7ed3.
2020-05-14 17:06:28.386 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:1419 DONE
2020-05-14 17:06:28.387 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-14 17:06:28.387 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-14 17:06:28.387 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-05-14 17:06:28.388 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-14 17:06:28.388 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-14 17:06:38.971 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 0B 0A 32 02 21 74 00 00 00 00 00 00 8F
2020-05-14 17:06:38.973 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=11, callback=0, payload=00 0B 0A 32 02 21 74 00 00 00 00 00 00
2020-05-14 17:06:38.974 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=11, callback=0, payload=00 0B 0A 32 02 21 74 00 00 00 00 00 00
2020-05-14 17:06:38.974 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null