2021-06-11 00:14:45.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2021-06-11 00:14:45.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6dbd500f.
2021-06-11 00:14:45.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:45.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:45.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:45.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:48.185 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 05 02 98 40 2C 
2021-06-11 00:14:48.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 98 40 
2021-06-11 00:14:48.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 98 40 
2021-06-11 00:14:48.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-06-11 00:14:48.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:48.187 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:48.188 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2021-06-11 00:14:48.188 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY check internal
2021-06-11 00:14:48.188 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2021-06-11 00:14:48.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Added to secure queue - size 1
2021-06-11 00:14:48.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2021-06-11 00:14:48.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@51748e81.
2021-06-11 00:14:48.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:48.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:48.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:48.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:48.189 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 11 00 13 05 0A 98 80 80 DD 52 2B 1F 3F 06 17 25 BC 66 
2021-06-11 00:14:48.189 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-06-11 00:14:48.189 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 30960: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:48.190 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-06-11 00:14:48.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:48.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:48.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30960: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:48.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-06-11 00:14:48.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:48.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:48.204 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-06-11 00:14:48.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:48.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:48.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30960: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:48.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:48.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30960: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:48.206 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:48.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 30960: Advanced to WAIT_REQUEST
2021-06-11 00:14:48.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 30960: Transaction not completed
2021-06-11 00:14:48.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:48.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:48.471 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 06 00 04 00 05 00 F8 
2021-06-11 00:14:48.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 00 
2021-06-11 00:14:48.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 00 
2021-06-11 00:14:48.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30960: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:48.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:48.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:48.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:48.664 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 06 00 04 00 05 00 F8 
2021-06-11 00:14:48.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 00 
2021-06-11 00:14:48.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 00 
2021-06-11 00:14:48.666 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30960: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:48.666 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:48.666 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:48.666 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:48.813 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 21 00 04 00 05 1B 98 81 B3 97 37 DD 9E 3B 70 5E 36 AC BC 55 6F D1 9B BB 80 FE 00 A7 E0 9F D6 A1 CB 6F 
2021-06-11 00:14:48.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 1B 98 81 B3 97 37 DD 9E 3B 70 5E 36 AC BC 55 6F D1 9B BB 80 FE 00 A7 E0 9F D6 A1 CB 
2021-06-11 00:14:48.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 1B 98 81 B3 97 37 DD 9E 3B 70 5E 36 AC BC 55 6F D1 9B BB 80 FE 00 A7 E0 9F D6 A1 CB 
2021-06-11 00:14:48.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30960: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:48.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:48.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:48.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Decapsulating COMMAND_CLASS_SECURITY
2021-06-11 00:14:48.816 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 5: SECURITY_RXD 60 0D 00 01 20 01 FF 
2021-06-11 00:14:48.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2021-06-11 00:14:48.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2021-06-11 00:14:48.816 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2021-06-11 00:14:48.816 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic report, value = 255
2021-06-11 00:14:48.816 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2021-06-11 00:14:48.816 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=255
2021-06-11 00:14:48.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2021-06-11 00:14:48.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@664ac7be.
2021-06-11 00:14:48.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-06-11 00:14:48.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-06-11 00:14:48.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:48.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:49.067 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 05 02 98 40 2C 
2021-06-11 00:14:49.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 98 40 
2021-06-11 00:14:49.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 98 40 
2021-06-11 00:14:49.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30960: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:49.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:49.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:49.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2021-06-11 00:14:49.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY check internal
2021-06-11 00:14:49.070 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2021-06-11 00:14:49.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Added to secure queue - size 1
2021-06-11 00:14:49.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2021-06-11 00:14:49.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@56b7ab09.
2021-06-11 00:14:49.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-06-11 00:14:49.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-06-11 00:14:49.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:49.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:49.091 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BC 00 00 5A 0D 
2021-06-11 00:14:49.092 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=188, payload=BC 00 00 5A 
2021-06-11 00:14:49.092 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=188, payload=BC 00 00 5A 
2021-06-11 00:14:49.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30960: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:49.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:49.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30960: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 188
2021-06-11 00:14:49.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 30960: (Callback 188)
2021-06-11 00:14:49.093 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-06-11 00:14:49.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 30960: callback 188
2021-06-11 00:14:49.093 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=188, payload=BC 00 00 5A 
2021-06-11 00:14:49.093 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:49.094 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 30960: Transaction COMPLETED
2021-06-11 00:14:49.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Response processed after 905ms
2021-06-11 00:14:49.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 30960: Transaction completed
2021-06-11 00:14:49.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:30960 DONE
2021-06-11 00:14:49.094 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-06-11 00:14:49.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:49.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:49.094 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 11 00 13 05 0A 98 80 2B F5 BB CB CF 14 4E B4 25 BD FD 
2021-06-11 00:14:49.096 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-06-11 00:14:49.096 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 30961: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 189
2021-06-11 00:14:49.097 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-06-11 00:14:49.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:49.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:49.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30961: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 189
2021-06-11 00:14:49.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-06-11 00:14:49.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:49.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:49.108 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-06-11 00:14:49.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:49.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:49.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30961: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 189
2021-06-11 00:14:49.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:49.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30961: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 189
2021-06-11 00:14:49.112 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:49.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 30961: Advanced to WAIT_REQUEST
2021-06-11 00:14:49.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 30961: Transaction not completed
2021-06-11 00:14:49.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:49.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:49.285 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BD 00 00 12 44 
2021-06-11 00:14:49.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=189, payload=BD 00 00 12 
2021-06-11 00:14:49.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=189, payload=BD 00 00 12 
2021-06-11 00:14:49.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30961: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 189
2021-06-11 00:14:49.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:49.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30961: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 189
2021-06-11 00:14:49.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 30961: (Callback 189)
2021-06-11 00:14:49.287 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-06-11 00:14:49.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 30961: callback 189
2021-06-11 00:14:49.287 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=189, payload=BD 00 00 12 
2021-06-11 00:14:49.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:49.287 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 30961: Transaction COMPLETED
2021-06-11 00:14:49.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Response processed after 191ms
2021-06-11 00:14:49.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 30961: Transaction completed
2021-06-11 00:14:49.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:30961 DONE
2021-06-11 00:14:49.288 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-06-11 00:14:49.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:49.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:49.521 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 06 00 04 00 05 00 F8 
2021-06-11 00:14:49.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 00 
2021-06-11 00:14:49.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 00 
2021-06-11 00:14:49.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-06-11 00:14:49.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:49.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:49.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:51.639 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 05 02 98 40 2C 
2021-06-11 00:14:51.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 98 40 
2021-06-11 00:14:51.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 98 40 
2021-06-11 00:14:51.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-06-11 00:14:51.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:51.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:51.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2021-06-11 00:14:51.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY check internal
2021-06-11 00:14:51.642 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2021-06-11 00:14:51.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Added to secure queue - size 1
2021-06-11 00:14:51.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2021-06-11 00:14:51.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@510da4f8.
2021-06-11 00:14:51.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:51.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:51.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:51.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:51.643 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 11 00 13 05 0A 98 80 B5 54 7D 9E CC 4F 2F 2C 25 BE F3 
2021-06-11 00:14:51.645 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-06-11 00:14:51.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 30962: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 190
2021-06-11 00:14:51.646 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-06-11 00:14:51.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:51.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:51.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30962: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 190
2021-06-11 00:14:51.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-06-11 00:14:51.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:51.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:51.656 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-06-11 00:14:51.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:51.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:51.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30962: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 190
2021-06-11 00:14:51.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:51.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30962: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 190
2021-06-11 00:14:51.658 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:51.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 30962: Advanced to WAIT_REQUEST
2021-06-11 00:14:51.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 30962: Transaction not completed
2021-06-11 00:14:51.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:51.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:51.688 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 05 02 98 40 2C 
2021-06-11 00:14:51.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 98 40 
2021-06-11 00:14:51.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 98 40 
2021-06-11 00:14:51.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30962: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 190
2021-06-11 00:14:51.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:51.693 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:51.693 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2021-06-11 00:14:51.693 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY check internal
2021-06-11 00:14:51.693 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2021-06-11 00:14:51.693 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 5: Ignoring NONCE Request received after 51ms
2021-06-11 00:14:51.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2021-06-11 00:14:51.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@24f828de.
2021-06-11 00:14:51.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-06-11 00:14:51.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-06-11 00:14:51.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:51.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:51.991 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BE 00 00 21 74 
2021-06-11 00:14:51.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=190, payload=BE 00 00 21 
2021-06-11 00:14:51.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=190, payload=BE 00 00 21 
2021-06-11 00:14:51.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30962: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 190
2021-06-11 00:14:51.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:51.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30962: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 190
2021-06-11 00:14:51.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 30962: (Callback 190)
2021-06-11 00:14:51.994 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-06-11 00:14:51.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 30962: callback 190
2021-06-11 00:14:51.994 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=190, payload=BE 00 00 21 
2021-06-11 00:14:51.994 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:51.994 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 30962: Transaction COMPLETED
2021-06-11 00:14:51.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Response processed after 349ms
2021-06-11 00:14:51.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 30962: Transaction completed
2021-06-11 00:14:51.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:30962 DONE
2021-06-11 00:14:51.995 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-06-11 00:14:51.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:51.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:52.072 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 28 00 04 00 05 22 98 81 44 0A 9A E5 93 BC 3C 47 7F 7F 42 3C CD 7F 92 75 33 58 D2 42 71 85 D4 B5 AF 5A C3 64 81 06 9C 0B 8F 
2021-06-11 00:14:52.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 22 98 81 44 0A 9A E5 93 BC 3C 47 7F 7F 42 3C CD 7F 92 75 33 58 D2 42 71 85 D4 B5 AF 5A C3 64 81 06 9C 0B 
2021-06-11 00:14:52.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 22 98 81 44 0A 9A E5 93 BC 3C 47 7F 7F 42 3C CD 7F 92 75 33 58 D2 42 71 85 D4 B5 AF 5A C3 64 81 06 9C 0B 
2021-06-11 00:14:52.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-06-11 00:14:52.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:52.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:52.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Decapsulating COMMAND_CLASS_SECURITY
2021-06-11 00:14:52.077 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 5: SECURITY_RXD 60 0D 01 01 32 02 21 32 00 00 00 00 00 00 
2021-06-11 00:14:52.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2021-06-11 00:14:52.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_METER, endpoint 1
2021-06-11 00:14:52.077 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_METER V0 METER_REPORT
2021-06-11 00:14:52.077 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2021-06-11 00:14:52.077 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMeterValueEvent
2021-06-11 00:14:52.077 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0E+1
2021-06-11 00:14:52.077 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:8f57dbad:node5:meter_watts1 to 0 [DecimalType]
2021-06-11 00:14:52.078 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:8f57dbad:node5:meter_kwh1 to 0 [DecimalType]
2021-06-11 00:14:52.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2021-06-11 00:14:52.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@143f7cc8.
2021-06-11 00:14:52.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:52.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:52.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:52.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:57.687 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 02 02 98 40 2B 
2021-06-11 00:14:57.689 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 02 98 40 
2021-06-11 00:14:57.689 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 02 98 40 
2021-06-11 00:14:57.689 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-06-11 00:14:57.689 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:57.689 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:57.689 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2021-06-11 00:14:57.690 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY check internal
2021-06-11 00:14:57.690 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2021-06-11 00:14:57.690 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added to secure queue - size 1
2021-06-11 00:14:57.690 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2021-06-11 00:14:57.690 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7fbb72a5.
2021-06-11 00:14:57.690 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:57.690 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:57.690 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:57.690 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:57.691 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 11 00 13 02 0A 98 80 A5 12 E3 B9 9F 52 53 54 25 BF 50 
2021-06-11 00:14:57.692 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-06-11 00:14:57.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 30963: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 191
2021-06-11 00:14:57.693 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-06-11 00:14:57.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:57.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:57.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30963: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 191
2021-06-11 00:14:57.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-06-11 00:14:57.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:57.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:57.705 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-06-11 00:14:57.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:57.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:57.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30963: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 191
2021-06-11 00:14:57.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:57.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30963: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 191
2021-06-11 00:14:57.708 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:57.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 30963: Advanced to WAIT_REQUEST
2021-06-11 00:14:57.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 30963: Transaction not completed
2021-06-11 00:14:57.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:57.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:57.825 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BF 00 00 0D 59 
2021-06-11 00:14:57.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=191, payload=BF 00 00 0D 
2021-06-11 00:14:57.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=191, payload=BF 00 00 0D 
2021-06-11 00:14:57.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30963: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 191
2021-06-11 00:14:57.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:57.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30963: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 191
2021-06-11 00:14:57.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 30963: (Callback 191)
2021-06-11 00:14:57.828 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-06-11 00:14:57.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 30963: callback 191
2021-06-11 00:14:57.829 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=191, payload=BF 00 00 0D 
2021-06-11 00:14:57.829 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:57.829 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 30963: Transaction COMPLETED
2021-06-11 00:14:57.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Response processed after 137ms
2021-06-11 00:14:57.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 30963: Transaction completed
2021-06-11 00:14:57.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:30963 DONE
2021-06-11 00:14:57.830 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-06-11 00:14:57.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:57.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:57.927 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 2C 00 04 00 02 26 98 81 BD 27 B2 5D E2 2E 2C A1 95 23 27 D6 CD F0 56 9E B7 B7 9E 66 11 FA 29 B2 C5 71 4B A5 F3 A3 08 AE 02 08 B3 DB 2A 
2021-06-11 00:14:57.929 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 26 98 81 BD 27 B2 5D E2 2E 2C A1 95 23 27 D6 CD F0 56 9E B7 B7 9E 66 11 FA 29 B2 C5 71 4B A5 F3 A3 08 AE 02 08 B3 DB 
2021-06-11 00:14:57.929 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 26 98 81 BD 27 B2 5D E2 2E 2C A1 95 23 27 D6 CD F0 56 9E B7 B7 9E 66 11 FA 29 B2 C5 71 4B A5 F3 A3 08 AE 02 08 B3 DB 
2021-06-11 00:14:57.929 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-06-11 00:14:57.929 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:57.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:57.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Decapsulating COMMAND_CLASS_SECURITY
2021-06-11 00:14:57.930 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 2: SECURITY_RXD 60 0D 04 01 32 02 21 24 00 00 1C 32 00 00 00 00 00 00 
2021-06-11 00:14:57.930 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2021-06-11 00:14:57.930 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_METER, endpoint 4
2021-06-11 00:14:57.930 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_METER V0 METER_REPORT
2021-06-11 00:14:57.930 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 2: Meter: Type=Electric(1), Scale=kWh(0), Value=721.8
2021-06-11 00:14:57.930 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveMeterValueEvent
2021-06-11 00:14:57.930 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=4, command class=COMMAND_CLASS_METER, value=721.8
2021-06-11 00:14:57.930 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:8f57dbad:node2:meter_kwh4 to 721.8 [DecimalType]
2021-06-11 00:14:57.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2021-06-11 00:14:57.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@43ad33ce.
2021-06-11 00:14:57.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:57.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:57.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:57.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:57.991 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 02 02 98 40 2B 
2021-06-11 00:14:57.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 02 98 40 
2021-06-11 00:14:57.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 02 98 40 
2021-06-11 00:14:57.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-06-11 00:14:57.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:57.993 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:57.993 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2021-06-11 00:14:57.993 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY check internal
2021-06-11 00:14:57.994 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2021-06-11 00:14:57.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added to secure queue - size 1
2021-06-11 00:14:57.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2021-06-11 00:14:57.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@26765dbd.
2021-06-11 00:14:57.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:57.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:57.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:57.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:57.995 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 11 00 13 02 0A 98 80 F9 C7 98 F6 88 53 1A DB 25 C0 42 
2021-06-11 00:14:57.996 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-06-11 00:14:57.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 30964: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 192
2021-06-11 00:14:57.997 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-06-11 00:14:57.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:57.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:57.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30964: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 192
2021-06-11 00:14:57.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-06-11 00:14:57.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:57.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:58.008 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-06-11 00:14:58.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:58.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:58.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30964: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 192
2021-06-11 00:14:58.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:58.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30964: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 192
2021-06-11 00:14:58.012 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:58.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 30964: Advanced to WAIT_REQUEST
2021-06-11 00:14:58.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 30964: Transaction not completed
2021-06-11 00:14:58.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:58.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:58.143 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 C0 00 00 0E 25 
2021-06-11 00:14:58.146 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=192, payload=C0 00 00 0E 
2021-06-11 00:14:58.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=192, payload=C0 00 00 0E 
2021-06-11 00:14:58.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30964: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 192
2021-06-11 00:14:58.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:58.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30964: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 192
2021-06-11 00:14:58.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 30964: (Callback 192)
2021-06-11 00:14:58.147 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-06-11 00:14:58.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 30964: callback 192
2021-06-11 00:14:58.147 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=192, payload=C0 00 00 0E 
2021-06-11 00:14:58.148 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:58.148 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 30964: Transaction COMPLETED
2021-06-11 00:14:58.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Response processed after 152ms
2021-06-11 00:14:58.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 30964: Transaction completed
2021-06-11 00:14:58.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:30964 DONE
2021-06-11 00:14:58.148 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-06-11 00:14:58.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:58.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:58.249 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 2C 00 04 00 02 26 98 81 2C 26 5D 28 A4 9D 4F 96 D4 CE 62 6E F9 81 CC 43 53 48 A4 B3 D6 7E E7 A5 38 F5 06 F9 A2 C6 6B 4C 9C A1 31 DA D5 
2021-06-11 00:14:58.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 26 98 81 2C 26 5D 28 A4 9D 4F 96 D4 CE 62 6E F9 81 CC 43 53 48 A4 B3 D6 7E E7 A5 38 F5 06 F9 A2 C6 6B 4C 9C A1 31 DA 
2021-06-11 00:14:58.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 26 98 81 2C 26 5D 28 A4 9D 4F 96 D4 CE 62 6E F9 81 CC 43 53 48 A4 B3 D6 7E E7 A5 38 F5 06 F9 A2 C6 6B 4C 9C A1 31 DA 
2021-06-11 00:14:58.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-06-11 00:14:58.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:58.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:58.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Decapsulating COMMAND_CLASS_SECURITY
2021-06-11 00:14:58.253 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 2: SECURITY_RXD 60 0D 04 01 32 02 21 54 00 00 00 00 00 00 00 00 00 00 
2021-06-11 00:14:58.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2021-06-11 00:14:58.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_METER, endpoint 4
2021-06-11 00:14:58.253 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_METER V0 METER_REPORT
2021-06-11 00:14:58.253 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 2: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2021-06-11 00:14:58.253 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveMeterValueEvent
2021-06-11 00:14:58.253 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=4, command class=COMMAND_CLASS_METER, value=0E+1
2021-06-11 00:14:58.253 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:8f57dbad:node2:meter_watts4 to 0 [DecimalType]
2021-06-11 00:14:58.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2021-06-11 00:14:58.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4940bc87.
2021-06-11 00:14:58.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:58.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:58.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:58.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:58.309 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 02 02 98 40 2B 
2021-06-11 00:14:58.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 02 98 40 
2021-06-11 00:14:58.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 02 98 40 
2021-06-11 00:14:58.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-06-11 00:14:58.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2021-06-11 00:14:58.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2021-06-11 00:14:58.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2021-06-11 00:14:58.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY check internal
2021-06-11 00:14:58.313 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2021-06-11 00:14:58.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added to secure queue - size 1
2021-06-11 00:14:58.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2021-06-11 00:14:58.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6dcad150.
2021-06-11 00:14:58.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:58.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-06-11 00:14:58.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:58.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-06-11 00:14:58.314 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 11 00 13 02 0A 98 80 02 42 02 C9 CA BC F3 1F 25 C1 18 
2021-06-11 00:14:58.316 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-06-11 00:14:58.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 30965: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 193
2021-06-11 00:14:58.317 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-06-11 00:14:58.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:58.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-06-11 00:14:58.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30965: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 193
2021-06-11 00:14:58.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-06-11 00:14:58.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:58.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-06-11 00:14:58.329 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-06-11 00:14:58.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:58.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:58.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30965: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 193
2021-06-11 00:14:58.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-06-11 00:14:58.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30965: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 193
2021-06-11 00:14:58.332 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-06-11 00:14:58.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 30965: Advanced to WAIT_REQUEST
2021-06-11 00:14:58.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 30965: Transaction not completed
2021-06-11 00:14:58.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-06-11 00:14:58.33