2018-11-24 19:35:05.005 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Command received zwave:device:15a7a49f3a6:node4:blinds_control --> UP [UpDownType]
2018-11-24 19:35:05.007 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 4: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2018-11-24 19:35:05.008 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2018-11-24 19:35:05.009 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2018-11-24 19:35:05.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
2018-11-24 19:35:05.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Added 1561 to queue - size 61
2018-11-24 19:35:05.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:05.017 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 04 05 26 04 20 00 FF 25 42 7B 
2018-11-24 19:35:05.020 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 0C 00 13 04 05 26 04 20 00 FF 25 42 7B 
2018-11-24 19:35:05.021 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2018-11-24 19:35:05.023 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1561: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 66
2018-11-24 19:35:05.023 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2018-11-24 19:35:05.024 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling intialised at 86400 seconds - start in 15000 milliseconds.
2018-11-24 19:35:05.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2018-11-24 19:35:05.025 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2018-11-24 19:35:05.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1561: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 66
2018-11-24 19:35:05.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2018-11-24 19:35:05.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:05.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2018-11-24 19:35:05.030 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-11-24 19:35:05.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2018-11-24 19:35:05.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2018-11-24 19:35:05.033 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1561: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 66
2018-11-24 19:35:05.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2018-11-24 19:35:05.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1561: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 66
2018-11-24 19:35:05.035 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2018-11-24 19:35:05.036 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
2018-11-24 19:35:05.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1561: Advanced to WAIT_REQUEST
2018-11-24 19:35:05.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: TID 1561: Transaction not completed
2018-11-24 19:35:05.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:05.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2018-11-24 19:35:05.047 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 42 00 00 02 AB 
2018-11-24 19:35:05.050 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=66, payload=42 00 00 02 
2018-11-24 19:35:05.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=66, payload=42 00 00 02 
2018-11-24 19:35:05.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1561: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 66
2018-11-24 19:35:05.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2018-11-24 19:35:05.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1561: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 66
2018-11-24 19:35:05.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1561: (Callback 66)
2018-11-24 19:35:05.055 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2018-11-24 19:35:05.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1561: callback 66
2018-11-24 19:35:05.057 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=66, payload=42 00 00 02 
2018-11-24 19:35:05.058 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 66, Status = Transmission complete and ACK received(0)
2018-11-24 19:35:05.059 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2018-11-24 19:35:05.060 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1561: Transaction COMPLETED
2018-11-24 19:35:05.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Response processed after 38ms
2018-11-24 19:35:05.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: TID 1561: Transaction completed
2018-11-24 19:35:05.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:1561 DONE
2018-11-24 19:35:05.064 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-11-24 19:35:05.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:05.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:06.055 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@4fc0d2
2018-11-24 19:35:06.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Adding to device queue
2018-11-24 19:35:06.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Added 313 to queue - size 41
2018-11-24 19:35:06.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:06.061 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 33 A8 
2018-11-24 19:35:06.063 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 04 00 60 33 A8 
2018-11-24 19:35:06.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 313: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2018-11-24 19:35:06.170 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 04 06 31 05 04 22 04 83 60 
2018-11-24 19:35:06.173 [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 04 83 
2018-11-24 19:35:06.174 [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 04 83 
2018-11-24 19:35:06.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-11-24 19:35:06.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2018-11-24 19:35:06.177 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2018-11-24 19:35:06.177 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2018-11-24 19:35:06.178 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2018-11-24 19:35:06.179 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_MULTILEVEL V2 SENSOR_MULTILEVEL_REPORT
2018-11-24 19:35:06.180 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Type = Power(4), Scale = 0
2018-11-24 19:35:06.180 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Value = 115.5
2018-11-24 19:35:06.181 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2018-11-24 19:35:06.182 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 115.5
2018-11-24 19:35:06.183 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 4: Sensor conversion not performed for POWER.
2018-11-24 19:35:06.184 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:15a7a49f3a6:node4:sensor_power to 115.5 [DecimalType]
2018-11-24 19:35:06.185 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2018-11-24 19:35:06.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@18e7688.
2018-11-24 19:35:06.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-11-24 19:35:06.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-11-24 19:35:06.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:06.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:08.066 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 51: TID 313: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2018-11-24 19:35:08.069 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 313: Transaction is current transaction, so clearing!!!!!
2018-11-24 19:35:08.071 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 313: Transaction CANCELLED
2018-11-24 19:35:08.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: notifyTransactionResponse TID:313 CANCELLED
2018-11-24 19:35:08.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:08.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 313: Transaction event listener: DONE: CANCELLED -> 
2018-11-24 19:35:08.080 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 51: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1aa2e7a
2018-11-24 19:35:12.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 56: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@f0e4d4
2018-11-24 19:35:12.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 56: Adding to device queue
2018-11-24 19:35:12.833 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 56: Added 307 to queue - size 41
2018-11-24 19:35:12.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:12.836 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 38 A3 
2018-11-24 19:35:12.838 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 04 00 60 38 A3 
2018-11-24 19:35:12.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 307: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2018-11-24 19:35:14.842 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 56: TID 307: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2018-11-24 19:35:14.844 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 307: Transaction is current transaction, so clearing!!!!!
2018-11-24 19:35:14.846 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 307: Transaction CANCELLED
2018-11-24 19:35:14.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 56: notifyTransactionResponse TID:307 CANCELLED
2018-11-24 19:35:14.851 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:14.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 307: Transaction event listener: DONE: CANCELLED -> 
2018-11-24 19:35:14.856 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 56: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@15805b1
2018-11-24 19:35:20.024 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling...
2018-11-24 19:35:20.026 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:15a7a49f3a6:node4:blinds_control
2018-11-24 19:35:20.027 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 4: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2018-11-24 19:35:20.028 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 4: Creating new message for command SWITCH_MULTILEVEL_GET
2018-11-24 19:35:20.030 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2018-11-24 19:35:20.031 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2018-11-24 19:35:20.033 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
2018-11-24 19:35:20.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Added 1562 to queue - size 61
2018-11-24 19:35:20.035 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:20.038 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 04 02 26 02 25 43 A1 
2018-11-24 19:35:20.040 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 09 00 13 04 02 26 02 25 43 A1 
2018-11-24 19:35:20.041 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2018-11-24 19:35:20.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1562: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 67
2018-11-24 19:35:20.042 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2018-11-24 19:35:20.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2018-11-24 19:35:20.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2018-11-24 19:35:20.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1562: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 67
2018-11-24 19:35:20.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2018-11-24 19:35:20.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:20.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2018-11-24 19:35:20.049 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-11-24 19:35:20.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2018-11-24 19:35:20.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2018-11-24 19:35:20.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1562: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 67
2018-11-24 19:35:20.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2018-11-24 19:35:20.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1562: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 67
2018-11-24 19:35:20.055 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2018-11-24 19:35:20.056 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
2018-11-24 19:35:20.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1562: Advanced to WAIT_REQUEST
2018-11-24 19:35:20.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: TID 1562: Transaction not completed
2018-11-24 19:35:20.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:20.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2018-11-24 19:35:20.066 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 43 00 00 02 AA 
2018-11-24 19:35:20.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=67, payload=43 00 00 02 
2018-11-24 19:35:20.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=67, payload=43 00 00 02 
2018-11-24 19:35:20.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1562: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 67
2018-11-24 19:35:20.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2018-11-24 19:35:20.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1562: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 67
2018-11-24 19:35:20.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1562: (Callback 67)
2018-11-24 19:35:20.072 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2018-11-24 19:35:20.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1562: callback 67
2018-11-24 19:35:20.074 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=67, payload=43 00 00 02 
2018-11-24 19:35:20.074 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 67, Status = Transmission complete and ACK received(0)
2018-11-24 19:35:20.075 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2018-11-24 19:35:20.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1562: Advanced to WAIT_DATA
2018-11-24 19:35:20.076 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 04 03 26 03 3D ED 
2018-11-24 19:35:20.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: TID 1562: Transaction not completed
2018-11-24 19:35:20.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:20.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2018-11-24 19:35:20.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 26 03 3D 
2018-11-24 19:35:20.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 26 03 3D 
2018-11-24 19:35:20.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-11-24 19:35:20.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2018-11-24 19:35:20.082 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2018-11-24 19:35:20.083 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2018-11-24 19:35:20.083 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2018-11-24 19:35:20.084 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2018-11-24 19:35:20.085 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 4: Switch Multi Level report, value = 61
2018-11-24 19:35:20.086 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-11-24 19:35:20.086 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 61
2018-11-24 19:35:20.088 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:15a7a49f3a6:node4:blinds_control to 61 [PercentType]
2018-11-24 19:35:20.089 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2018-11-24 19:35:20.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@135b59b.
2018-11-24 19:35:20.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@135b59b.
2018-11-24 19:35:20.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:1562 DONE
2018-11-24 19:35:20.095 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-11-24 19:35:20.096 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2018-11-24 19:35:20.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-11-24 19:35:20.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:20.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:28.644 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 04 06 31 05 04 22 00 96 71 
2018-11-24 19:35:28.649 [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 96 
2018-11-24 19:35:28.651 [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 96 
2018-11-24 19:35:28.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-11-24 19:35:28.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2018-11-24 19:35:28.655 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2018-11-24 19:35:28.656 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2018-11-24 19:35:28.658 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2018-11-24 19:35:28.659 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_MULTILEVEL V2 SENSOR_MULTILEVEL_REPORT
2018-11-24 19:35:28.661 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Type = Power(4), Scale = 0
2018-11-24 19:35:28.662 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Value = 15
2018-11-24 19:35:28.664 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2018-11-24 19:35:28.666 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 15
2018-11-24 19:35:28.668 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 4: Sensor conversion not performed for POWER.
2018-11-24 19:35:28.669 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:15a7a49f3a6:node4:sensor_power to 15 [DecimalType]
2018-11-24 19:35:28.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2018-11-24 19:35:28.673 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1d87afe.
2018-11-24 19:35:28.673 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-11-24 19:35:28.674 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-11-24 19:35:28.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:28.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:30.262 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 04 03 26 03 63 B3 
2018-11-24 19:35:30.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 26 03 63 
2018-11-24 19:35:30.266 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 26 03 63 
2018-11-24 19:35:30.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-11-24 19:35:30.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2018-11-24 19:35:30.268 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2018-11-24 19:35:30.269 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2018-11-24 19:35:30.270 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2018-11-24 19:35:30.271 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2018-11-24 19:35:30.272 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 4: Switch Multi Level report, value = 99
2018-11-24 19:35:30.273 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-11-24 19:35:30.274 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 99
2018-11-24 19:35:30.275 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:15a7a49f3a6:node4:blinds_control to 100 [PercentType]
2018-11-24 19:35:30.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2018-11-24 19:35:30.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@b026.
2018-11-24 19:35:30.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-11-24 19:35:30.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-11-24 19:35:30.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:30.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-11-24 19:35:30.282 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 04 06 31 05 04 22 00 00 E7 
2018-11-24 19:35:30.285 [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 00 
2018-11-24 19:35:30.287 [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 00 
2018-11-24 19:35:30.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-11-24 19:35:30.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2018-11-24 19:35:30.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2018-11-24 19:35:30.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2018-11-24 19:35:30.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2018-11-24 19:35:30.291 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_MULTILEVEL V2 SENSOR_MULTILEVEL_REPORT
2018-11-24 19:35:30.292 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Type = Power(4), Scale = 0
2018-11-24 19:35:30.293 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Value = 0E+1
2018-11-24 19:35:30.294 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2018-11-24 19:35:30.295 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 0E+1
2018-11-24 19:35:30.296 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 4: Sensor conversion not performed for POWER.
2018-11-24 19:35:30.296 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:15a7a49f3a6:node4:sensor_power to 0 [DecimalType]
2018-11-24 19:35:30.297 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2018-11-24 19:35:30.298 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f2aa58.
2018-11-24 19:35:30.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-11-24 19:35:30.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-11-24 19:35:30.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-11-24 19:35:30.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.