2019-07-30 21:10:10.659 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: ZWaveCommandClassTransactionPayload - send to node
2019-07-30 21:10:10.659 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:10:10.659 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2019-07-30 21:10:10.660 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@5ce5ff2d
2019-07-30 21:10:10.660 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2019-07-30 21:10:10.660 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 87 to queue - size 2
2019-07-30 21:10:10.660 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:10:10.661 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 09 06 60 0D 01 02 25 02 25 46 C4
2019-07-30 21:10:10.662 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0D 00 13 09 06 60 0D 01 02 25 02 25 46 C4
2019-07-30 21:10:10.662 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-07-30 21:10:10.662 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 87: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 70
2019-07-30 21:10:10.663 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-07-30 21:10:10.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:10:10.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:10:10.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 87: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 70
2019-07-30 21:10:10.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-07-30 21:10:10.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:10:10.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:10:10.670 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2019-07-30 21:10:10.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:10:10.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:10:10.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 87: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 70
2019-07-30 21:10:10.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:10:10.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 87: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 70
2019-07-30 21:10:10.671 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:10:10.672 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData successfully placed on stack.
2019-07-30 21:10:10.672 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 87: Advanced to WAIT_REQUEST
2019-07-30 21:10:10.672 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 87: Transaction not completed
2019-07-30 21:10:10.672 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:10:10.672 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:10:10.691 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 46 00 00 03 AE
2019-07-30 21:10:10.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=70, payload=46 00 00 03
2019-07-30 21:10:10.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=70, payload=46 00 00 03
2019-07-30 21:10:10.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 87: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 70
2019-07-30 21:10:10.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:10:10.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 87: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 70
2019-07-30 21:10:10.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 87: (Callback 70)
2019-07-30 21:10:10.694 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-07-30 21:10:10.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 87: callback 70
2019-07-30 21:10:10.694 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=70, payload=46 00 00 03
2019-07-30 21:10:10.694 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 70, Status = Transmission complete and ACK received(0)
2019-07-30 21:10:10.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 87: Advanced to WAIT_DATA
2019-07-30 21:10:10.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 87: Transaction not completed
2019-07-30 21:10:10.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:10:10.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:10:15.694 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 9: TID 87: Timeout at state WAIT_DATA. 3 retries remaining.
2019-07-30 21:10:15.694 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 87: Transaction CANCELLED
2019-07-30 21:10:15.695 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-30 21:10:15.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:87 CANCELLED
2019-07-30 21:10:15.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:10:15.696 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 87: Transaction event listener: DONE: CANCELLED ->
2019-07-30 21:10:15.696 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node Init response (5) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@6ef2d67e
2019-07-30 21:10:15.696 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: No data from device, but it was ACK'd. Possibly not supported? (Try 5)
2019-07-30 21:11:18.536 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: ZWaveCommandClassTransactionPayload - send to node
2019-07-30 21:11:18.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:11:18.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2019-07-30 21:11:18.537 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@5ce5ff2d
2019-07-30 21:11:18.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2019-07-30 21:11:18.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 88 to queue - size 2
2019-07-30 21:11:18.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:11:18.540 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 09 06 60 0D 01 02 25 02 25 47 C5
2019-07-30 21:11:18.540 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0D 00 13 09 06 60 0D 01 02 25 02 25 47 C5
2019-07-30 21:11:18.540 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-07-30 21:11:18.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 88: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 71
2019-07-30 21:11:18.542 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-07-30 21:11:18.542 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:11:18.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:11:18.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 88: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 71
2019-07-30 21:11:18.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-07-30 21:11:18.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:18.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:11:18.548 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2019-07-30 21:11:18.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:11:18.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:11:18.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 88: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 71
2019-07-30 21:11:18.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:11:18.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 88: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 71
2019-07-30 21:11:18.550 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:11:18.551 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData successfully placed on stack.
2019-07-30 21:11:18.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 88: Advanced to WAIT_REQUEST
2019-07-30 21:11:18.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 88: Transaction not completed
2019-07-30 21:11:18.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:18.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:11:18.569 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 47 00 00 02 AE
2019-07-30 21:11:18.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=71, payload=47 00 00 02
2019-07-30 21:11:18.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=71, payload=47 00 00 02
2019-07-30 21:11:18.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 88: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 71
2019-07-30 21:11:18.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:11:18.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 88: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 71
2019-07-30 21:11:18.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 88: (Callback 71)
2019-07-30 21:11:18.572 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-07-30 21:11:18.572 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 88: callback 71
2019-07-30 21:11:18.572 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=71, payload=47 00 00 02
2019-07-30 21:11:18.573 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 71, Status = Transmission complete and ACK received(0)
2019-07-30 21:11:18.573 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 88: Advanced to WAIT_DATA
2019-07-30 21:11:18.573 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 88: Transaction not completed
2019-07-30 21:11:18.573 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:18.573 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:11:23.573 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 9: TID 88: Timeout at state WAIT_DATA. 3 retries remaining.
2019-07-30 21:11:23.574 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 88: Transaction CANCELLED
2019-07-30 21:11:23.574 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-30 21:11:23.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:88 CANCELLED
2019-07-30 21:11:23.575 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:11:23.575 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 88: Transaction event listener: DONE: CANCELLED ->
2019-07-30 21:11:23.576 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node Init response (6) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@610530a7
2019-07-30 21:11:23.576 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: No data from device, but it was ACK'd. Possibly not supported? (Try 6)
2019-07-30 21:11:35.761 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:468c4dcd:node9:blinds_control --> 25 [PercentType]
2019-07-30 21:11:35.762 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 9: Creating new message for command SWITCH_MULTILEVEL_SET
2019-07-30 21:11:35.762 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2019-07-30 21:11:35.762 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-07-30 21:11:35.763 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2019-07-30 21:11:35.763 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 89 to queue - size 2
2019-07-30 21:11:35.763 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:11:35.763 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 09 03 26 01 4B 25 48 ED
2019-07-30 21:11:35.764 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0A 00 13 09 03 26 01 4B 25 48 ED
2019-07-30 21:11:35.765 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-07-30 21:11:35.765 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 89: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 72
2019-07-30 21:11:35.765 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-07-30 21:11:35.765 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:11:35.766 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:11:35.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 89: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 72
2019-07-30 21:11:35.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-07-30 21:11:35.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:35.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:11:35.769 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling intialised at 86400 seconds - start in 1500 milliseconds.
2019-07-30 21:11:35.774 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2019-07-30 21:11:35.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:11:35.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:11:35.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 89: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 72
2019-07-30 21:11:35.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:11:35.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 89: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 72
2019-07-30 21:11:35.776 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:11:35.776 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData successfully placed on stack.
2019-07-30 21:11:35.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 89: Advanced to WAIT_REQUEST
2019-07-30 21:11:35.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 89: Transaction not completed
2019-07-30 21:11:35.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:35.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:11:35.792 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 48 00 00 03 A0
2019-07-30 21:11:35.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=72, payload=48 00 00 03
2019-07-30 21:11:35.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=72, payload=48 00 00 03
2019-07-30 21:11:35.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 89: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 72
2019-07-30 21:11:35.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:11:35.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 89: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 72
2019-07-30 21:11:35.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 89: (Callback 72)
2019-07-30 21:11:35.794 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-07-30 21:11:35.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 89: callback 72
2019-07-30 21:11:35.794 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=72, payload=48 00 00 03
2019-07-30 21:11:35.794 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 72, Status = Transmission complete and ACK received(0)
2019-07-30 21:11:35.794 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 89: Transaction COMPLETED
2019-07-30 21:11:35.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Response processed after 29ms
2019-07-30 21:11:35.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 89: Transaction completed
2019-07-30 21:11:35.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:89 DONE
2019-07-30 21:11:35.795 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-30 21:11:35.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:35.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:11:36.355 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 09 07 60 0D 01 01 20 01 FF 4B
2019-07-30 21:11:36.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=9, callback=0, payload=00 09 07 60 0D 01 01 20 01 FF
2019-07-30 21:11:36.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=9, callback=0, payload=00 09 07 60 0D 01 01 20 01 FF
2019-07-30 21:11:36.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:11:36.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Application Command Request (ALIVE:DYNAMIC_VALUES)
2019-07-30 21:11:36.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:11:36.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Incoming command class COMMAND_CLASS_BASIC, endpoint 1
2019-07-30 21:11:36.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_BASIC
2019-07-30 21:11:36.357 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 9: Received COMMAND_CLASS_BASIC V0 BASIC_SET
2019-07-30 21:11:36.357 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 255
2019-07-30 21:11:36.357 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-07-30 21:11:36.358 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_BASIC, value = 255
2019-07-30 21:11:36.358 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:468c4dcd:node9:blinds_control1 to 100 [PercentType]
2019-07-30 21:11:36.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Commands processed 1.
2019-07-30 21:11:36.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@422d3858.
2019-07-30 21:11:36.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:36.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:36.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:36.359 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:11:37.266 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling...
2019-07-30 21:11:37.266 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling deferred until initialisation complete
2019-07-30 21:11:52.008 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 06 07 60 0D 01 01 25 03 FF 43
2019-07-30 21:11:52.009 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 07 60 0D 01 01 25 03 FF
2019-07-30 21:11:52.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 07 60 0D 01 01 25 03 FF
2019-07-30 21:11:52.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:11:52.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
2019-07-30 21:11:52.011 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2019-07-30 21:11:52.011 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:11:52.011 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 1
2019-07-30 21:11:52.011 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
2019-07-30 21:11:52.012 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
2019-07-30 21:11:52.012 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Switch Binary report, value = 255
2019-07-30 21:11:52.012 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-07-30 21:11:52.012 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SWITCH_BINARY, value = 255
2019-07-30 21:11:52.012 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:468c4dcd:node6:switch_binary1 to ON [OnOffType]
2019-07-30 21:11:52.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
2019-07-30 21:11:52.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4b6a9b5c.
2019-07-30 21:11:52.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:52.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:52.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:52.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:11:52.075 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 08 06 07 60 0D 01 01 20 01 FF 4C
2019-07-30 21:11:52.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=8, payload=08 06 07 60 0D 01 01 20 01 FF
2019-07-30 21:11:52.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=8, payload=08 06 07 60 0D 01 01 20 01 FF
2019-07-30 21:11:52.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:11:52.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
2019-07-30 21:11:52.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2019-07-30 21:11:52.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:11:52.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_BASIC, endpoint 1
2019-07-30 21:11:52.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY NOT required on COMMAND_CLASS_BASIC
2019-07-30 21:11:52.077 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_BASIC V0 BASIC_SET
2019-07-30 21:11:52.078 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 6: Basic report, value = 255
2019-07-30 21:11:52.078 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-07-30 21:11:52.078 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_BASIC, value = 255
2019-07-30 21:11:52.078 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:468c4dcd:node6:switch_binary1 to ON [OnOffType]
2019-07-30 21:11:52.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
2019-07-30 21:11:52.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@59ff8b64.
2019-07-30 21:11:52.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:52.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:52.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:52.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:11:52.090 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 06 07 60 0D 01 01 20 01 FF 44
2019-07-30 21:11:52.091 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 07 60 0D 01 01 20 01 FF
2019-07-30 21:11:52.091 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 07 60 0D 01 01 20 01 FF
2019-07-30 21:11:52.091 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:11:52.091 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
2019-07-30 21:11:52.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2019-07-30 21:11:52.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:11:52.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_BASIC, endpoint 1
2019-07-30 21:11:52.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY NOT required on COMMAND_CLASS_BASIC
2019-07-30 21:11:52.092 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_BASIC V0 BASIC_SET
2019-07-30 21:11:52.093 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 6: Basic report, value = 255
2019-07-30 21:11:52.093 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-07-30 21:11:52.093 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_BASIC, value = 255
2019-07-30 21:11:52.093 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:468c4dcd:node6:switch_binary1 to ON [OnOffType]
2019-07-30 21:11:52.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
2019-07-30 21:11:52.095 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@54e33649.
2019-07-30 21:11:52.095 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:52.095 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:52.095 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:52.095 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:11:54.410 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 06 0C 60 0D 01 01 32 02 21 32 00 73 00 00 DE
2019-07-30 21:11:54.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 0C 60 0D 01 01 32 02 21 32 00 73 00 00
2019-07-30 21:11:54.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 0C 60 0D 01 01 32 02 21 32 00 73 00 00
2019-07-30 21:11:54.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:11:54.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
2019-07-30 21:11:54.413 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2019-07-30 21:11:54.413 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:11:54.413 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_METER, endpoint 1
2019-07-30 21:11:54.413 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY NOT required on COMMAND_CLASS_METER
2019-07-30 21:11:54.414 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_METER V0 METER_REPORT
2019-07-30 21:11:54.414 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Meter: Type=Electric(1), Scale=W(2), Value=11.5
2019-07-30 21:11:54.415 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-07-30 21:11:54.415 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_METER, value = 11.5
2019-07-30 21:11:54.415 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:468c4dcd:node6:meter_watts1 to 11.5 [DecimalType]
2019-07-30 21:11:54.416 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
2019-07-30 21:11:54.417 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7fa72b75.
2019-07-30 21:11:54.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:54.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:11:54.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:11:54.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:12:02.238 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:468c4dcd:node9:blinds_control --> DOWN [UpDownType]
2019-07-30 21:12:02.239 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 9: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-07-30 21:12:02.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2019-07-30 21:12:02.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-07-30 21:12:02.242 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2019-07-30 21:12:02.242 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 90 to queue - size 2
2019-07-30 21:12:02.243 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:12:02.243 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 09 05 26 04 60 00 FF 25 49 3D
2019-07-30 21:12:02.243 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0C 00 13 09 05 26 04 60 00 FF 25 49 3D
2019-07-30 21:12:02.244 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-07-30 21:12:02.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 90: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 73
2019-07-30 21:12:02.245 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-07-30 21:12:02.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:12:02.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:12:02.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 90: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 73
2019-07-30 21:12:02.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-07-30 21:12:02.246 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling intialised at 86400 seconds - start in 1500 milliseconds.
2019-07-30 21:12:02.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:12:02.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:12:02.251 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2019-07-30 21:12:02.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:12:02.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:12:02.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 90: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 73
2019-07-30 21:12:02.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:12:02.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 90: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 73
2019-07-30 21:12:02.253 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:12:02.253 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData successfully placed on stack.
2019-07-30 21:12:02.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 90: Advanced to WAIT_REQUEST
2019-07-30 21:12:02.254 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 90: Transaction not completed
2019-07-30 21:12:02.254 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:12:02.254 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:12:02.273 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 49 00 00 03 A1
2019-07-30 21:12:02.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=73, payload=49 00 00 03
2019-07-30 21:12:02.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=73, payload=49 00 00 03
2019-07-30 21:12:02.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 90: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 73
2019-07-30 21:12:02.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:12:02.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 90: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 73
2019-07-30 21:12:02.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 90: (Callback 73)
2019-07-30 21:12:02.274 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-07-30 21:12:02.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 90: callback 73
2019-07-30 21:12:02.275 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=73, payload=49 00 00 03
2019-07-30 21:12:02.275 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 73, Status = Transmission complete and ACK received(0)
2019-07-30 21:12:02.275 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 90: Transaction COMPLETED
2019-07-30 21:12:02.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Response processed after 31ms
2019-07-30 21:12:02.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 90: Transaction completed
2019-07-30 21:12:02.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:90 DONE
2019-07-30 21:12:02.276 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-30 21:12:02.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:12:02.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:12:02.841 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 09 07 60 0D 01 01 20 01 00 B4
2019-07-30 21:12:02.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=9, callback=0, payload=00 09 07 60 0D 01 01 20 01 00
2019-07-30 21:12:02.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=9, callback=0, payload=00 09 07 60 0D 01 01 20 01 00
2019-07-30 21:12:02.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:12:02.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Application Command Request (ALIVE:DYNAMIC_VALUES)
2019-07-30 21:12:02.843 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:12:02.844 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Incoming command class COMMAND_CLASS_BASIC, endpoint 1
2019-07-30 21:12:02.844 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_BASIC
2019-07-30 21:12:02.844 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 9: Received COMMAND_CLASS_BASIC V0 BASIC_SET
2019-07-30 21:12:02.844 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0
2019-07-30 21:12:02.844 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-07-30 21:12:02.845 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_BASIC, value = 0
2019-07-30 21:12:02.845 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:468c4dcd:node9:blinds_control1 to 0 [PercentType]
2019-07-30 21:12:02.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Commands processed 1.
2019-07-30 21:12:02.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6088ffa6.
2019-07-30 21:12:02.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:12:02.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:12:02.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:12:02.847 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:12:03.746 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling...
2019-07-30 21:12:03.747 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling deferred until initialisation complete
2019-07-30 21:13:07.833 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 04 0A 32 02 21 44 00 00 36 54 00 00 D2
2019-07-30 21:13:07.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 0A 32 02 21 44 00 00 36 54 00 00
2019-07-30 21:13:07.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 0A 32 02 21 44 00 00 36 54 00 00
2019-07-30 21:13:07.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:13:07.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2019-07-30 21:13:07.835 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2019-07-30 21:13:07.836 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-07-30 21:13:07.836 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
2019-07-30 21:13:07.836 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_METER V2 METER_REPORT
2019-07-30 21:13:07.836 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 4: Meter: Type=Electric(1), Scale=kWh(0), Value=139.08
2019-07-30 21:13:07.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2019-07-30 21:13:07.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4589fc78.
2019-07-30 21:13:07.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:13:07.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:13:07.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:13:07.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:13:29.813 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: ZWaveCommandClassTransactionPayload - send to node
2019-07-30 21:13:29.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:13:29.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2019-07-30 21:13:29.814 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@5ce5ff2d
2019-07-30 21:13:29.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2019-07-30 21:13:29.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 91 to queue - size 2
2019-07-30 21:13:29.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:13:29.817 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 09 06 60 0D 01 02 25 02 25 4A C8
2019-07-30 21:13:29.818 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0D 00 13 09 06 60 0D 01 02 25 02 25 4A C8
2019-07-30 21:13:29.818 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-07-30 21:13:29.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 91: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 74
2019-07-30 21:13:29.819 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-07-30 21:13:29.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:13:29.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:13:29.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 91: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 74
2019-07-30 21:13:29.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-07-30 21:13:29.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:13:29.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:13:29.826 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2019-07-30 21:13:29.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:13:29.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:13:29.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 91: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 74
2019-07-30 21:13:29.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:13:29.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 91: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 74
2019-07-30 21:13:29.827 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:13:29.828 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData successfully placed on stack.
2019-07-30 21:13:29.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 91: Advanced to WAIT_REQUEST
2019-07-30 21:13:29.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 91: Transaction not completed
2019-07-30 21:13:29.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:13:29.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:13:29.847 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4A 00 00 02 A3
2019-07-30 21:13:29.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=74, payload=4A 00 00 02
2019-07-30 21:13:29.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=74, payload=4A 00 00 02
2019-07-30 21:13:29.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 91: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 74
2019-07-30 21:13:29.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:13:29.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 91: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 74
2019-07-30 21:13:29.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 91: (Callback 74)
2019-07-30 21:13:29.849 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-07-30 21:13:29.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 91: callback 74
2019-07-30 21:13:29.849 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=74, payload=4A 00 00 02
2019-07-30 21:13:29.849 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 74, Status = Transmission complete and ACK received(0)
2019-07-30 21:13:29.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 91: Advanced to WAIT_DATA
2019-07-30 21:13:29.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 91: Transaction not completed
2019-07-30 21:13:29.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:13:29.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:13:34.850 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 9: TID 91: Timeout at state WAIT_DATA. 3 retries remaining.
2019-07-30 21:13:34.851 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 91: Transaction CANCELLED
2019-07-30 21:13:34.852 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-30 21:13:34.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:91 CANCELLED
2019-07-30 21:13:34.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:13:34.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 91: Transaction event listener: DONE: CANCELLED ->
2019-07-30 21:13:34.853 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node Init response (7) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@71702086
2019-07-30 21:13:34.854 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: No data from device, but it was ACK'd. Possibly not supported? (Try 7)
2019-07-30 21:14:18.205 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 05 06 31 05 01 22 01 51 B3
2019-07-30 21:14:18.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 06 31 05 01 22 01 51
2019-07-30 21:14:18.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 06 31 05 01 22 01 51
2019-07-30 21:14:18.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:14:18.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:REQUEST_NIF)
2019-07-30 21:14:18.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-07-30 21:14:18.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported
2019-07-30 21:14:18.207 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V10 SENSOR_MULTILEVEL_REPORT
2019-07-30 21:14:18.208 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Temperature(1), Scale = 0
2019-07-30 21:14:18.208 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 33.7
2019-07-30 21:14:18.208 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2019-07-30 21:14:18.208 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 33.7
2019-07-30 21:14:18.209 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:468c4dcd:node5:sensor_temperature to 33.7 °C [QuantityType]
2019-07-30 21:14:18.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2019-07-30 21:14:18.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@895cbfe.
2019-07-30 21:14:18.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:14:18.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:14:18.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:14:18.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:14:40.825 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 04 06 31 05 04 22 03 55 B1
2019-07-30 21:14:40.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 06 31 05 04 22 03 55
2019-07-30 21:14:40.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 06 31 05 04 22 03 55
2019-07-30 21:14:40.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:14:40.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2019-07-30 21:14:40.827 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2019-07-30 21:14:40.827 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-07-30 21:14:40.827 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2019-07-30 21:14:40.828 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2019-07-30 21:14:40.828 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Type = Power(4), Scale = 0
2019-07-30 21:14:40.828 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Value = 85.3
2019-07-30 21:14:40.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2019-07-30 21:14:40.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4eafa51a.
2019-07-30 21:14:40.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:14:40.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:14:40.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:14:40.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:14:41.825 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 04 06 31 05 04 22 03 72 96
2019-07-30 21:14:41.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 06 31 05 04 22 03 72
2019-07-30 21:14:41.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 06 31 05 04 22 03 72
2019-07-30 21:14:41.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:14:41.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2019-07-30 21:14:41.827 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2019-07-30 21:14:41.828 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-07-30 21:14:41.828 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2019-07-30 21:14:41.828 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2019-07-30 21:14:41.828 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Type = Power(4), Scale = 0
2019-07-30 21:14:41.829 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Value = 88.2
2019-07-30 21:14:41.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2019-07-30 21:14:41.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@69fd16a6.
2019-07-30 21:14:41.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:14:41.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:14:41.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:14:41.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:17:47.901 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: ZWaveCommandClassTransactionPayload - send to node
2019-07-30 21:17:47.901 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:17:47.902 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2019-07-30 21:17:47.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@5ce5ff2d
2019-07-30 21:17:47.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2019-07-30 21:17:47.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 92 to queue - size 2
2019-07-30 21:17:47.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:17:47.904 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 09 06 60 0D 01 02 25 02 25 4B C9
2019-07-30 21:17:47.904 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0D 00 13 09 06 60 0D 01 02 25 02 25 4B C9
2019-07-30 21:17:47.905 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-07-30 21:17:47.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 92: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 75
2019-07-30 21:17:47.906 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-07-30 21:17:47.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:17:47.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-30 21:17:47.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 92: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 75
2019-07-30 21:17:47.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-07-30 21:17:47.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:17:47.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:17:47.913 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2019-07-30 21:17:47.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:17:47.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:17:47.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 92: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 75
2019-07-30 21:17:47.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:17:47.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 92: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 75
2019-07-30 21:17:47.915 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-30 21:17:47.915 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData successfully placed on stack.
2019-07-30 21:17:47.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 92: Advanced to WAIT_REQUEST
2019-07-30 21:17:47.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 92: Transaction not completed
2019-07-30 21:17:47.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:17:47.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:17:47.935 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4B 00 00 03 A3
2019-07-30 21:17:47.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=75, payload=4B 00 00 03
2019-07-30 21:17:47.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=75, payload=4B 00 00 03
2019-07-30 21:17:47.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 92: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 75
2019-07-30 21:17:47.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-30 21:17:47.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 92: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 75
2019-07-30 21:17:47.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 92: (Callback 75)
2019-07-30 21:17:47.937 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-07-30 21:17:47.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 92: callback 75
2019-07-30 21:17:47.938 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=75, payload=4B 00 00 03
2019-07-30 21:17:47.938 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 75, Status = Transmission complete and ACK received(0)
2019-07-30 21:17:47.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 92: Advanced to WAIT_DATA
2019-07-30 21:17:47.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 92: Transaction not completed
2019-07-30 21:17:47.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:17:47.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:17:49.444 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 06 0E 60 0D 01 01 32 02 21 44 00 00 01 D8 00 00 06
2019-07-30 21:17:49.445 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 0E 60 0D 01 01 32 02 21 44 00 00 01 D8 00 00
2019-07-30 21:17:49.446 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 0E 60 0D 01 01 32 02 21 44 00 00 01 D8 00 00
2019-07-30 21:17:49.446 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:17:49.446 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
2019-07-30 21:17:49.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2019-07-30 21:17:49.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-07-30 21:17:49.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_METER, endpoint 1
2019-07-30 21:17:49.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY NOT required on COMMAND_CLASS_METER
2019-07-30 21:17:49.447 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_METER V0 METER_REPORT
2019-07-30 21:17:49.448 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Meter: Type=Electric(1), Scale=kWh(0), Value=4.72
2019-07-30 21:17:49.448 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-07-30 21:17:49.448 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_METER, value = 4.72
2019-07-30 21:17:49.448 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:468c4dcd:node6:meter_kwh1 to 4.72 [DecimalType]
2019-07-30 21:17:49.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
2019-07-30 21:17:49.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@631d4ddd.
2019-07-30 21:17:49.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@631d4ddd.
2019-07-30 21:17:49.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2019-07-30 21:17:49.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2019-07-30 21:17:49.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:17:49.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-30 21:17:52.939 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 9: TID 92: Timeout at state WAIT_DATA. 3 retries remaining.
2019-07-30 21:17:52.940 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 92: Transaction CANCELLED
2019-07-30 21:17:52.940 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-30 21:17:52.940 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:92 CANCELLED
2019-07-30 21:17:52.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-30 21:17:52.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 92: Transaction event listener: DONE: CANCELLED ->
2019-07-30 21:17:52.942 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node Init response (8) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@5b2f3908
2019-07-30 21:17:52.942 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: No data from device, but it was ACK'd. Possibly not supported? (Try 8)
2019-07-30 21:19:19.684 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 05 06 31 05 01 22 01 51 B3
2019-07-30 21:19:19.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 06 31 05 01 22 01 51
2019-07-30 21:19:19.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 06 31 05 01 22 01 51
2019-07-30 21:19:19.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-30 21:19:19.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:REQUEST_NIF)
2019-07-30 21:19:19.687 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-07-30 21:19:19.687 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported
2019-07-30 21:19:19.687 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V10 SENSOR_MULTILEVEL_REPORT
2019-07-30 21:19:19.687 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Temperature(1), Scale = 0
2019-07-30 21:19:19.687 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 33.7
2019-07-30 21:19:19.688 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2019-07-30 21:19:19.688 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 33.7
2019-07-30 21:19:19.688 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:468c4dcd:node5:sensor_temperature to 33.7 °C [QuantityType]
2019-07-30 21:19:19.689 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2019-07-30 21:19:19.689 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@45f1a07b.
2019-07-30 21:19:19.690 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:19:19.691 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-30 21:19:19.691 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-30 21:19:19.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-31 00:21:10.857 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 04 06 31 05 04 22 00 19 FE
2019-07-31 00:21:10.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 06 31 05 04 22 00 19
2019-07-31 00:21:10.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 06 31 05 04 22 00 19
2019-07-31 00:21:10.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-31 00:21:10.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2019-07-31 00:21:10.859 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2019-07-31 00:21:10.859 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-07-31 00:21:10.859 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2019-07-31 00:21:10.859 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2019-07-31 00:21:10.860 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Type = Power(4), Scale = 0
2019-07-31 00:21:10.860 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Value = 2.5
2019-07-31 00:21:10.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2019-07-31 00:21:10.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4ba1c08f.
2019-07-31 00:21:10.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 00:21:10.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 00:21:10.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 00:21:10.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-31 00:21:12.857 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 04 06 31 05 04 22 00 1A FD
2019-07-31 00:21:12.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 06 31 05 04 22 00 1A
2019-07-31 00:21:12.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 06 31 05 04 22 00 1A
2019-07-31 00:21:12.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-31 00:21:12.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2019-07-31 00:21:12.859 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2019-07-31 00:21:12.859 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-07-31 00:21:12.859 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2019-07-31 00:21:12.859 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2019-07-31 00:21:12.860 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Type = Power(4), Scale = 0
2019-07-31 00:21:12.860 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Value = 2.6
2019-07-31 00:21:12.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2019-07-31 00:21:12.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@28981e72.
2019-07-31 00:21:12.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 00:21:12.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 00:21:12.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 00:21:12.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.