2019-07-31 12:08:57.025 [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 12:08:57.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-31 12:08:57.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2019-07-31 12:08:57.026 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2019-07-31 12:08:57.026 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-07-31 12:08:57.026 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2019-07-31 12:08:57.026 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2019-07-31 12:08:57.027 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Type = Power(4), Scale = 0
2019-07-31 12:08:57.027 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Value = 2.6
2019-07-31 12:08:57.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2019-07-31 12:08:57.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4c26dd90.
2019-07-31 12:08:57.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 12:08:57.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 12:08:57.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:57.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-31 12:08:57.741 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:468c4dcd:node9:blinds_control --> DOWN [UpDownType]
2019-07-31 12:08:57.741 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 9: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-07-31 12:08:57.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2019-07-31 12:08:57.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-07-31 12:08:57.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2019-07-31 12:08:57.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 116 to queue - size 1
2019-07-31 12:08:57.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-31 12:08:57.742 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 09 05 26 04 60 00 FF 25 61 15
2019-07-31 12:08:57.743 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0C 00 13 09 05 26 04 60 00 FF 25 61 15
2019-07-31 12:08:57.746 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-07-31 12:08:57.746 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 116: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 97
2019-07-31 12:08:57.747 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-07-31 12:08:57.747 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling intialised at 86400 seconds - start in 1500 milliseconds.
2019-07-31 12:08:57.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-31 12:08:57.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-31 12:08:57.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 116: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 97
2019-07-31 12:08:57.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-07-31 12:08:57.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:57.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-31 12:08:57.751 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2019-07-31 12:08:57.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-31 12:08:57.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-31 12:08:57.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 116: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 97
2019-07-31 12:08:57.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-31 12:08:57.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 116: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 97
2019-07-31 12:08:57.751 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-31 12:08:57.752 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData successfully placed on stack.
2019-07-31 12:08:57.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 116: Advanced to WAIT_REQUEST
2019-07-31 12:08:57.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 116: Transaction not completed
2019-07-31 12:08:57.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:57.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-31 12:08:57.767 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 61 00 00 02 88
2019-07-31 12:08:57.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=97, payload=61 00 00 02
2019-07-31 12:08:57.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=97, payload=61 00 00 02
2019-07-31 12:08:57.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 116: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 97
2019-07-31 12:08:57.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-31 12:08:57.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 116: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 97
2019-07-31 12:08:57.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 116: (Callback 97)
2019-07-31 12:08:57.768 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-07-31 12:08:57.768 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 116: callback 97
2019-07-31 12:08:57.768 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=97, payload=61 00 00 02
2019-07-31 12:08:57.768 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 97, Status = Transmission complete and ACK received(0)
2019-07-31 12:08:57.768 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: resetResendCount initComplete=true isDead=false
2019-07-31 12:08:57.768 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 116: Transaction COMPLETED
2019-07-31 12:08:57.768 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Response processed after 22ms
2019-07-31 12:08:57.768 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 116: Transaction completed
2019-07-31 12:08:57.768 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:116 DONE
2019-07-31 12:08:57.768 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-31 12:08:57.769 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:57.769 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-31 12:08:58.327 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 09 07 60 0D 01 01 20 01 00 B4
2019-07-31 12:08:58.327 [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-31 12:08:58.327 [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-31 12:08:58.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-31 12:08:58.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Application Command Request (ALIVE:DONE)
2019-07-31 12:08:58.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: resetResendCount initComplete=true isDead=false
2019-07-31 12:08:58.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-07-31 12:08:58.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Incoming command class COMMAND_CLASS_BASIC, endpoint 1
2019-07-31 12:08:58.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_BASIC
2019-07-31 12:08:58.328 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 9: Received COMMAND_CLASS_BASIC V0 BASIC_SET
2019-07-31 12:08:58.329 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0
2019-07-31 12:08:58.329 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-07-31 12:08:58.329 [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-31 12:08:58.329 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:468c4dcd:node9:blinds_control1 to 0 [PercentType]
2019-07-31 12:08:58.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Commands processed 1.
2019-07-31 12:08:58.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@103426cc.
2019-07-31 12:08:58.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 12:08:58.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 12:08:58.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:58.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-31 12:08:59.247 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling...
2019-07-31 12:08:59.247 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling zwave:device:468c4dcd:node9:blinds_control
2019-07-31 12:08:59.247 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 9: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2019-07-31 12:08:59.247 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 9: Creating new message for command SWITCH_MULTILEVEL_GET
2019-07-31 12:08:59.248 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2019-07-31 12:08:59.248 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-07-31 12:08:59.248 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling skipped for zwave:device:468c4dcd:node9:blinds_control on COMMAND_CLASS_BASIC
2019-07-31 12:08:59.248 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling zwave:device:468c4dcd:node9:meter_watts
2019-07-31 12:08:59.248 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 9: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-07-31 12:08:59.248 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 9: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-07-31 12:08:59.249 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 9: Creating new message for application command METER_GET
2019-07-31 12:08:59.249 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_METER
2019-07-31 12:08:59.249 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-07-31 12:08:59.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2019-07-31 12:08:59.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 117 to queue - size 1
2019-07-31 12:08:59.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-31 12:08:59.250 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 09 02 26 02 25 62 8D
2019-07-31 12:08:59.250 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 09 00 13 09 02 26 02 25 62 8D
2019-07-31 12:08:59.250 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-07-31 12:08:59.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 117: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 98
2019-07-31 12:08:59.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue
2019-07-31 12:08:59.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Added 118 to queue - size 1
2019-07-31 12:08:59.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-31 12:08:59.251 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-07-31 12:08:59.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-31 12:08:59.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-31 12:08:59.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 117: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 98
2019-07-31 12:08:59.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-07-31 12:08:59.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:59.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-31 12:08:59.258 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2019-07-31 12:08:59.258 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-31 12:08:59.258 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-31 12:08:59.258 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 117: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 98
2019-07-31 12:08:59.259 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-31 12:08:59.259 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 117: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 98
2019-07-31 12:08:59.259 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-31 12:08:59.259 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData successfully placed on stack.
2019-07-31 12:08:59.259 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 117: Advanced to WAIT_REQUEST
2019-07-31 12:08:59.259 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 117: Transaction not completed
2019-07-31 12:08:59.260 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:59.260 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-31 12:08:59.274 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 62 00 00 02 8B
2019-07-31 12:08:59.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=98, payload=62 00 00 02
2019-07-31 12:08:59.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=98, payload=62 00 00 02
2019-07-31 12:08:59.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 117: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 98
2019-07-31 12:08:59.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-31 12:08:59.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 117: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 98
2019-07-31 12:08:59.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 117: (Callback 98)
2019-07-31 12:08:59.275 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-07-31 12:08:59.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 117: callback 98
2019-07-31 12:08:59.275 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=98, payload=62 00 00 02
2019-07-31 12:08:59.275 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 98, Status = Transmission complete and ACK received(0)
2019-07-31 12:08:59.275 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: resetResendCount initComplete=true isDead=false
2019-07-31 12:08:59.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 117: Advanced to WAIT_DATA
2019-07-31 12:08:59.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 117: Transaction not completed
2019-07-31 12:08:59.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:59.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-31 12:08:59.307 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 26 03 44 99
2019-07-31 12:08:59.307 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=9, callback=0, payload=00 09 03 26 03 44
2019-07-31 12:08:59.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=9, callback=0, payload=00 09 03 26 03 44
2019-07-31 12:08:59.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-31 12:08:59.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Application Command Request (ALIVE:DONE)
2019-07-31 12:08:59.308 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: resetResendCount initComplete=true isDead=false
2019-07-31 12:08:59.308 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2019-07-31 12:08:59.308 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2019-07-31 12:08:59.308 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 9: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2019-07-31 12:08:59.308 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 9: Switch Multi Level report, value = 68
2019-07-31 12:08:59.308 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-07-31 12:08:59.308 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 68
2019-07-31 12:08:59.309 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:468c4dcd:node9:blinds_control to 32 [PercentType]
2019-07-31 12:08:59.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Commands processed 1.
2019-07-31 12:08:59.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@50a08122.
2019-07-31 12:08:59.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@50a08122.
2019-07-31 12:08:59.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:117 DONE
2019-07-31 12:08:59.313 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-31 12:08:59.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2019-07-31 12:08:59.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 12:08:59.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:59.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-07-31 12:08:59.314 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 09 03 32 01 10 25 63 89
2019-07-31 12:08:59.315 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0A 00 13 09 03 32 01 10 25 63 89
2019-07-31 12:08:59.315 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-07-31 12:08:59.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 118: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 99
2019-07-31 12:08:59.316 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-07-31 12:08:59.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-31 12:08:59.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-07-31 12:08:59.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 118: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 99
2019-07-31 12:08:59.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-07-31 12:08:59.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:59.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-31 12:08:59.322 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2019-07-31 12:08:59.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-31 12:08:59.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-31 12:08:59.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 118: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 99
2019-07-31 12:08:59.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-31 12:08:59.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 118: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 99
2019-07-31 12:08:59.323 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2019-07-31 12:08:59.323 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: sentData successfully placed on stack.
2019-07-31 12:08:59.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 118: Advanced to WAIT_REQUEST
2019-07-31 12:08:59.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 118: Transaction not completed
2019-07-31 12:08:59.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:59.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-31 12:08:59.338 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 63 00 00 02 8A
2019-07-31 12:08:59.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=99, payload=63 00 00 02
2019-07-31 12:08:59.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=99, payload=63 00 00 02
2019-07-31 12:08:59.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 118: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 99
2019-07-31 12:08:59.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-07-31 12:08:59.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 118: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 99
2019-07-31 12:08:59.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 118: (Callback 99)
2019-07-31 12:08:59.340 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-07-31 12:08:59.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 118: callback 99
2019-07-31 12:08:59.340 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=99, payload=63 00 00 02
2019-07-31 12:08:59.340 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 99, Status = Transmission complete and ACK received(0)
2019-07-31 12:08:59.340 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: resetResendCount initComplete=true isDead=false
2019-07-31 12:08:59.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 118: Advanced to WAIT_DATA
2019-07-31 12:08:59.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: TID 118: Transaction not completed
2019-07-31 12:08:59.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:59.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-07-31 12:08:59.390 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 09 0A 32 02 21 34 00 00 05 A6 00 00 6E
2019-07-31 12:08:59.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=9, callback=0, payload=00 09 0A 32 02 21 34 00 00 05 A6 00 00
2019-07-31 12:08:59.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=9, callback=0, payload=00 09 0A 32 02 21 34 00 00 05 A6 00 00
2019-07-31 12:08:59.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-07-31 12:08:59.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Application Command Request (ALIVE:DONE)
2019-07-31 12:08:59.391 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: resetResendCount initComplete=true isDead=false
2019-07-31 12:08:59.392 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-07-31 12:08:59.392 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY NOT required on COMMAND_CLASS_METER
2019-07-31 12:08:59.392 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 9: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-07-31 12:08:59.392 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 9: Meter: Type=Electric(1), Scale=W(2), Value=144.6
2019-07-31 12:08:59.392 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-07-31 12:08:59.392 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 144.6
2019-07-31 12:08:59.392 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:468c4dcd:node9:meter_watts to 144.6 [DecimalType]
2019-07-31 12:08:59.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Commands processed 1.
2019-07-31 12:08:59.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@398198ac.
2019-07-31 12:08:59.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@398198ac.
2019-07-31 12:08:59.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:118 DONE
2019-07-31 12:08:59.394 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-07-31 12:08:59.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2019-07-31 12:08:59.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-07-31 12:08:59.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-07-31 12:08:59.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.