2020-03-20 19:06:16.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-03-20 19:06:16.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-03-20 19:06:16.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-03-20 19:06:16.471 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Is awake with 3 messages in the queue
2020-03-20 19:06:16.471 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Start sleep timer at 1000ms
2020-03-20 19:06:16.471 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2020-03-20 19:06:16.473 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 2: Node Status event - Node is AWAKE
2020-03-20 19:06:16.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-03-20 19:06:16.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@190a759c.
2020-03-20 19:06:16.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-03-20 19:06:16.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-03-20 19:06:16.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-03-20 19:06:16.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-03-20 19:06:16.475 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 02 04 31 04 01 00 25 8F 7F
2020-03-20 19:06:16.475 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0B 00 13 02 04 31 04 01 00 25 8F 7F
2020-03-20 19:06:16.476 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-03-20 19:06:16.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 89: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 143
2020-03-20 19:06:16.477 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-03-20 19:06:16.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-03-20 19:06:16.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-03-20 19:06:16.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 89: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 143
2020-03-20 19:06:16.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-03-20 19:06:16.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-03-20 19:06:16.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-03-20 19:06:16.483 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-03-20 19:06:16.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-03-20 19:06:16.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-03-20 19:06:16.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 89: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 143
2020-03-20 19:06:16.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-03-20 19:06:16.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 89: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 143
2020-03-20 19:06:16.485 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-03-20 19:06:16.488 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
2020-03-20 19:06:16.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 89: Advanced to WAIT_REQUEST
2020-03-20 19:06:16.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 89: Transaction not completed
2020-03-20 19:06:16.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-03-20 19:06:16.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-03-20 19:06:16.500 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8F 00 00 02 66
2020-03-20 19:06:16.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=143, payload=8F 00 00 02
2020-03-20 19:06:16.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=143, payload=8F 00 00 02
2020-03-20 19:06:16.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 89: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 143
2020-03-20 19:06:16.501 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-03-20 19:06:16.501 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 89: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 143
2020-03-20 19:06:16.501 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 89: (Callback 143)
2020-03-20 19:06:16.501 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-03-20 19:06:16.501 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 89: callback 143
2020-03-20 19:06:16.502 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=143, payload=8F 00 00 02
2020-03-20 19:06:16.502 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 143, Status = Transmission complete and ACK received(0)
2020-03-20 19:06:16.502 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-03-20 19:06:16.502 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 89: Advanced to WAIT_DATA
2020-03-20 19:06:16.502 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 89: Transaction not completed
2020-03-20 19:06:16.502 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-03-20 19:06:16.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-03-20 19:06:16.516 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 02 06 31 05 01 22 00 CF 2B
2020-03-20 19:06:16.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 06 31 05 01 22 00 CF
2020-03-20 19:06:16.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 06 31 05 01 22 00 CF
2020-03-20 19:06:16.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-03-20 19:06:16.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-03-20 19:06:16.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-03-20 19:06:16.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-03-20 19:06:16.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-03-20 19:06:16.517 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_MULTILEVEL V7 SENSOR_MULTILEVEL_REPORT
2020-03-20 19:06:16.518 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Type = Temperature(1), Scale = 0
2020-03-20 19:06:16.518 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Value = 20.7
2020-03-20 19:06:16.518 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-03-20 19:06:16.518 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=20.7
2020-03-20 19:06:16.518 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:ee55524f:node2:sensor_temperature to 20.7 °C [QuantityType]
2020-03-20 19:06:16.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-03-20 19:06:16.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5a33bcd2.
2020-03-20 19:06:16.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5a33bcd2.
2020-03-20 19:06:16.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:89 DONE
2020-03-20 19:06:16.525 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-03-20 19:06:16.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-03-20 19:06:16.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-03-20 19:06:16.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-03-20 19:06:16.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-03-20 19:06:16.525 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 02 04 31 04 03 00 25 90 62
2020-03-20 19:06:16.525 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0B 00 13 02 04 31 04 03 00 25 90 62
2020-03-20 19:06:16.526 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-03-20 19:06:16.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 90: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 144
2020-03-20 19:06:16.527 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-03-20 19:06:16.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-03-20 19:06:16.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-03-20 19:06:16.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 90: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 144
2020-03-20 19:06:16.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-03-20 19:06:16.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-03-20 19:06:16.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-03-20 19:06:16.533 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-03-20 19:06:16.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-03-20 19:06:16.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-03-20 19:06:16.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 90: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 144
2020-03-20 19:06:16.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-03-20 19:06:16.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 90: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 144
2020-03-20 19:06:16.534 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-03-20 19:06:16.534 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
2020-03-20 19:06:16.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 90: Advanced to WAIT_REQUEST
2020-03-20 19:06:16.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 90: Transaction not completed
2020-03-20 19:06:16.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty