Okay so i managed to get the switch to act up by activating it via a rule.
I have the below rule which turns all the lights in my master bathroom on when a particular switch is turned on. The only members of MasterBathAuto are the other 2 lights in the bathroom. One of those lights is the switch that keeps acting up. i have a second identical rule for turning the master bath lights off.
Node 25: ZWave025VanityLightsDimmerSwitchSwitchDimmer
Node 51: Switch in master bathroom. This switch is in MasterBathAuto group.
Node 30: other switch in master bathroom. This switch is in MasterBathAuto group.
rule "Master Bathroom Lights ON"
when
Item ZWave025VanityLightsDimmerSwitchSwitchDimmer changed from 0
then
MasterBathAuto.sendCommand(ON)
end
Admittedly i was activating the rule about every 10 seconds (On wait 10 seconds, Off, Repeat) to get it to mess up and it did (I was not spamming it though). In the end, when the switch messed up, i activated the rule to turn all master bath lights off and the light attached to node 51 stayed on but the little blue light on node 51 turned on.
Logs:
2020-01-18 11:44:07.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Application Command Request (ALIVE:SUC_ROUTE)
2020-01-18 11:44:07.319 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2020-01-18 11:44:07.319 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported
2020-01-18 11:44:07.319 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 25: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2020-01-18 11:44:07.319 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 25: Basic report, value = 0
2020-01-18 11:44:07.319 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-01-18 11:44:07.319 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=0
2020-01-18 11:44:07.319 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Updating channel state zwave:device:28e4dafb:node25:switch_dimmer to 0 [PercentType]
2020-01-18 11:44:07.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Commands processed 1.
2020-01-18 11:44:07.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@143695e.
2020-01-18 11:44:07.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-01-18 11:44:07.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-01-18 11:44:07.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:07.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:07.327 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Command received zwave:device:28e4dafb:node51:switch_dimmer --> OFF [OnOffType]
2020-01-18 11:44:07.328 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 51: Creating new message for command SWITCH_MULTILEVEL_SET
2020-01-18 11:44:07.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 51: SECURITY not supported
2020-01-18 11:44:07.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 51: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2020-01-18 11:44:07.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Adding to device queue
2020-01-18 11:44:07.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Added 13716 to queue - size 8
2020-01-18 11:44:07.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:07.328 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Polling initialised at 86400 seconds - start in 1500 milliseconds.
2020-01-18 11:44:07.330 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 30: Command received zwave:device:28e4dafb:node30:switch_dimmer --> OFF [OnOffType]
2020-01-18 11:44:07.330 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 30: Creating new message for command SWITCH_MULTILEVEL_SET
2020-01-18 11:44:07.330 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported
2020-01-18 11:44:07.330 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 30: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2020-01-18 11:44:07.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Adding to device queue
2020-01-18 11:44:07.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Added 13717 to queue - size 9
2020-01-18 11:44:07.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:07.331 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 30: Polling initialised at 86400 seconds - start in 1500 milliseconds.
2020-01-18 11:44:07.490 [INFO ] [e.smarthome.model.script.Temperature] - InsideTemp received update: 23.000000000000004 in Celsius = 73.4000000000000072 in Fahrenheit.
2020-01-18 11:44:07.494 [INFO ] [clipse.smarthome.model.script.Length] - RainRate received update: 0.0 in milimeters = 0.0000000000 in inches.
2020-01-18 11:44:07.492 [INFO ] [e.smarthome.model.script.Temperature] - OutsideTemp received update: 22.38888888888889 in Celsius = 72.300000000000002 in Fahrenheit.
2020-01-18 11:44:08.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Polling...
2020-01-18 11:44:08.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Polling deferred until initialisation complete
2020-01-18 11:44:08.831 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 30: Polling...
2020-01-18 11:44:08.831 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 30: Polling deferred until initialisation complete
2020-01-18 11:44:09.607 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 51: TID 13715: Timeout at state WAIT_REQUEST. 3 retries remaining.
2020-01-18 11:44:09.607 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 13715: Transaction is current transaction, so clearing!!!!!
2020-01-18 11:44:09.608 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13715: Transaction CANCELLED
2020-01-18 11:44:09.608 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-01-18 11:44:09.608 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: notifyTransactionResponse TID:13715 CANCELLED
2020-01-18 11:44:09.608 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-01-18 11:44:09.608 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 33 03 26 01 00 25 02 D6
2020-01-18 11:44:09.609 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 51: Sending REQUEST Message = 01 0A 00 13 33 03 26 01 00 25 02 D6
2020-01-18 11:44:09.609 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 13715: Transaction event listener: DONE: CANCELLED ->
2020-01-18 11:44:09.609 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 51: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@4478b9
2020-01-18 11:44:09.610 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-01-18 11:44:09.610 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 2
2020-01-18 11:44:11.584 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-01-18 11:44:11.584 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:11.585 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:11.585 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 2
2020-01-18 11:44:11.585 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-01-18 11:44:11.585 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:11.585 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:11.586 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9
2020-01-18 11:44:11.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-18 11:44:11.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-18 11:44:11.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 2
2020-01-18 11:44:11.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-01-18 11:44:11.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 2
2020-01-18 11:44:11.589 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-18 11:44:11.589 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 51: sentData was not placed on stack.
2020-01-18 11:44:11.589 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13716: Transaction CANCELLED
2020-01-18 11:44:11.589 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2020-01-18 11:44:11.589 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-01-18 11:44:11.589 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: CANCEL while sending message. Requeueing - 2 attempts left!
2020-01-18 11:44:11.589 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13716: Transaction RESET with 2 retries remaining.
2020-01-18 11:44:11.589 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Adding to device queue
2020-01-18 11:44:11.589 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Added 13716 to queue - size 9
2020-01-18 11:44:11.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-18 11:44:11.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: TID 13716: Transaction not completed
2020-01-18 11:44:11.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:11.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-18 11:44:11.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-01-18 11:44:11.839 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 33 03 26 01 00 25 03 D7
2020-01-18 11:44:11.839 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 51: Sending REQUEST Message = 01 0A 00 13 33 03 26 01 00 25 03 D7
2020-01-18 11:44:11.841 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-01-18 11:44:11.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 3
2020-01-18 11:44:13.841 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 51: TID 13716: Timeout at state WAIT_RESPONSE. 2 retries remaining.
2020-01-18 11:44:13.841 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2020-01-18 11:44:13.842 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13716: Transaction ABORTED
2020-01-18 11:44:13.842 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA
2020-01-18 11:44:13.842 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA
2020-01-18 11:44:13.844 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-01-18 11:44:13.844 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:15.696 [INFO ] [clipse.smarthome.model.script.Length] - Rainlast1h received update: 0.0 in milimeters = 0.0000000000 in inches.
2020-01-18 11:44:15.698 [INFO ] [clipse.smarthome.model.script.Length] - Rain15min received update: 0.0 in milimeters = 0.0000000000 in inches.
2020-01-18 11:44:15.696 [INFO ] [clipse.smarthome.model.script.Length] - Rainlast24h received update: 0.0 in milimeters = 0.0000000000 in inches.
2020-01-18 11:44:15.721 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-01-18 11:44:15.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:15.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:15.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [ABORTED] priority=Set, requiresResponse=true, callback: 3
2020-01-18 11:44:15.722 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-01-18 11:44:15.722 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:15.722 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:15.723 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9
2020-01-18 11:44:15.725 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-18 11:44:15.725 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-18 11:44:15.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [ABORTED] priority=Set, requiresResponse=true, callback: 3
2020-01-18 11:44:15.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-01-18 11:44:15.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 13716: [ABORTED] priority=Set, requiresResponse=true, callback: 3
2020-01-18 11:44:15.726 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-18 11:44:15.725 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2020-01-18 11:44:15.727 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2020-01-18 11:44:15.727 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 51: sentData was not placed on stack.
2020-01-18 11:44:15.727 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13716: Transaction CANCELLED
2020-01-18 11:44:15.728 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-01-18 11:44:15.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: CANCEL while sending message. Requeueing - 1 attempts left!
2020-01-18 11:44:15.728 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13716: Transaction RESET with 1 retries remaining.
2020-01-18 11:44:15.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Adding to device queue
2020-01-18 11:44:15.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Added 13716 to queue - size 9
2020-01-18 11:44:15.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-01-18 11:44:15.728 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 33 03 26 01 00 25 04 D0
2020-01-18 11:44:15.729 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 51: Sending REQUEST Message = 01 0A 00 13 33 03 26 01 00 25 04 D0
2020-01-18 11:44:15.730 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-01-18 11:44:15.730 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:15.732 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-01-18 11:44:15.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:15.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: TID 13716: Transaction not completed
2020-01-18 11:44:15.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2020-01-18 11:44:15.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:15.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2020-01-18 11:44:15.733 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9
2020-01-18 11:44:15.734 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 13716: Resetting transaction
2020-01-18 11:44:15.734 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: notifyTransactionResponse TID:13716 WAIT_RESPONSE
2020-01-18 11:44:15.734 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Adding to device queue
2020-01-18 11:44:15.734 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Added 13716 to queue - size 9
2020-01-18 11:44:15.734 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-18 11:44:15.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-18 11:44:15.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:15.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-01-18 11:44:15.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-01-18 11:44:15.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00
2020-01-18 11:44:15.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-01-18 11:44:15.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2020-01-18 11:44:15.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2020-01-18 11:44:15.736 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:15.736 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-18 11:44:15.826 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 10 19 03 20 01 00 D9
2020-01-18 11:44:15.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=16, payload=10 19 03 20 01 00
2020-01-18 11:44:15.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=16, payload=10 19 03 20 01 00
2020-01-18 11:44:15.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-01-18 11:44:15.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Application Command Request (ALIVE:SUC_ROUTE)
2020-01-18 11:44:15.828 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2020-01-18 11:44:15.829 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported
2020-01-18 11:44:15.829 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 25: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2020-01-18 11:44:15.829 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 25: Basic report, value = 0
2020-01-18 11:44:15.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-01-18 11:44:15.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=0
2020-01-18 11:44:15.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Updating channel state zwave:device:28e4dafb:node25:switch_dimmer to 0 [PercentType]
2020-01-18 11:44:15.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Commands processed 1.
2020-01-18 11:44:15.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@149e843.
2020-01-18 11:44:15.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-18 11:44:15.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-18 11:44:15.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:15.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-18 11:44:15.892 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 51 01 00 AA
2020-01-18 11:44:15.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=1, payload=01 00
2020-01-18 11:44:15.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=1, payload=01 00
2020-01-18 11:44:15.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-01-18 11:44:15.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2020-01-18 11:44:15.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2020-01-18 11:44:15.895 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=1, payload=01 00
2020-01-18 11:44:15.895 [DEBUG] [age.AssignSucReturnRouteMessageClass] - NODE {}: transaction not correlated for AssignSucReturnRouteMessageClass
2020-01-18 11:44:15.895 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:15.895 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-18 11:44:15.906 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 04 10 2A 12 56 01 32 02 21 74 00 17 B0 9E 00 05 00 18 8C 2E 81 3C C2
2020-01-18 11:44:15.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=42, callback=16, payload=10 2A 12 56 01 32 02 21 74 00 17 B0 9E 00 05 00 18 8C 2E 81 3C
2020-01-18 11:44:15.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=42, callback=16, payload=10 2A 12 56 01 32 02 21 74 00 17 B0 9E 00 05 00 18 8C 2E 81 3C
2020-01-18 11:44:15.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-01-18 11:44:15.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 42: Application Command Request (ALIVE:SUC_ROUTE)
2020-01-18 11:44:15.908 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 42: Decapsulating COMMAND_CLASS_CRC_16_ENCAP
2020-01-18 11:44:15.909 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 42: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-01-18 11:44:15.909 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 42: SECURITY NOT required on COMMAND_CLASS_METER
2020-01-18 11:44:15.909 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 42: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-01-18 11:44:15.909 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 42: Meter: Type=Electric(1), Scale=W(2), Value=1552.542
2020-01-18 11:44:15.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 42: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-01-18 11:44:15.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 42: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=1552.542
2020-01-18 11:44:15.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 42: Updating channel state zwave:device:28e4dafb:node42:meter_watts to 1552.542 [DecimalType]
2020-01-18 11:44:15.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 42: Commands processed 1.
2020-01-18 11:44:15.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 42: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@620df3.
2020-01-18 11:44:15.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-18 11:44:15.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-18 11:44:15.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:15.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-18 11:44:15.966 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 04 10 2A 12 56 01 32 02 21 74 00 17 B0 9E 00 05 00 18 8C 2E 81 3C C2
2020-01-18 11:44:15.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=42, callback=16, payload=10 2A 12 56 01 32 02 21 74 00 17 B0 9E 00 05 00 18 8C 2E 81 3C
2020-01-18 11:44:15.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=42, callback=16, payload=10 2A 12 56 01 32 02 21 74 00 17 B0 9E 00 05 00 18 8C 2E 81 3C
2020-01-18 11:44:15.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-01-18 11:44:15.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 42: Application Command Request (ALIVE:SUC_ROUTE)
2020-01-18 11:44:15.969 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 42: Meter: Type=Electric(1), Scale=W(2), Value=1552.542
2020-01-18 11:44:15.970 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 42: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-01-18 11:44:15.970 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 42: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=1552.542
2020-01-18 11:44:15.970 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 42: Updating channel state zwave:device:28e4dafb:node42:meter_watts to 1552.542 [DecimalType]
2020-01-18 11:44:15.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 42: Commands processed 1.
2020-01-18 11:44:15.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 42: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1d6bf5c.
2020-01-18 11:44:15.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-18 11:44:15.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-18 11:44:15.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:15.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2020-01-18 11:44:15.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-01-18 11:44:15.982 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 33 03 26 01 00 25 04 D0
2020-01-18 11:44:15.982 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 51: Sending REQUEST Message = 01 0A 00 13 33 03 26 01 00 25 04 D0
2020-01-18 11:44:15.985 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-01-18 11:44:15.985 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-01-18 11:44:15.985 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:15.985 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:15.985 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:15.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:15.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-01-18 11:44:15.987 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:15.987 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:15.994 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-01-18 11:44:15.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-01-18 11:44:15.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-01-18 11:44:15.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:15.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-01-18 11:44:15.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 13716: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:15.997 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-01-18 11:44:15.997 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 51: sentData successfully placed on stack.
2020-01-18 11:44:15.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 13716: Advanced to WAIT_REQUEST
2020-01-18 11:44:15.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: TID 13716: Transaction not completed
2020-01-18 11:44:15.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:15.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:16.052 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 04 10 2A 12 56 01 32 02 21 74 00 17 B0 9E 00 05 00 18 8C 2E 81 3C C2
2020-01-18 11:44:16.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=42, callback=16, payload=10 2A 12 56 01 32 02 21 74 00 17 B0 9E 00 05 00 18 8C 2E 81 3C
2020-01-18 11:44:16.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=42, callback=16, payload=10 2A 12 56 01 32 02 21 74 00 17 B0 9E 00 05 00 18 8C 2E 81 3C
2020-01-18 11:44:16.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:16.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-01-18 11:44:16.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-01-18 11:44:16.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:16.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:16.330 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 10 19 03 20 01 00 D9
2020-01-18 11:44:16.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=16, payload=10 19 03 20 01 00
2020-01-18 11:44:16.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=16, payload=10 19 03 20 01 00
2020-01-18 11:44:16.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:16.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Application Command Request (ALIVE:SUC_ROUTE)
2020-01-18 11:44:16.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2020-01-18 11:44:16.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported
2020-01-18 11:44:16.333 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 25: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2020-01-18 11:44:16.333 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 25: Basic report, value = 0
2020-01-18 11:44:16.334 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-01-18 11:44:16.334 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=0
2020-01-18 11:44:16.334 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Updating channel state zwave:device:28e4dafb:node25:switch_dimmer to 0 [PercentType]
2020-01-18 11:44:16.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Commands processed 1.
2020-01-18 11:44:16.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1b4caf7.
2020-01-18 11:44:16.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-01-18 11:44:16.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-01-18 11:44:16.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:16.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:17.100 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 04 00 00 6F 80
2020-01-18 11:44:17.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=4, payload=04 00 00 6F
2020-01-18 11:44:17.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=4, payload=04 00 00 6F
2020-01-18 11:44:17.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13716: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:17.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-01-18 11:44:17.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 13716: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 4
2020-01-18 11:44:17.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 13716: (Callback 4)
2020-01-18 11:44:17.102 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-01-18 11:44:17.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 13716: callback 4
2020-01-18 11:44:17.103 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=4, payload=04 00 00 6F
2020-01-18 11:44:17.103 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 51: SendData Request. CallBack ID = 4, Status = Transmission complete and ACK received(0)
2020-01-18 11:44:17.103 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13716: Transaction COMPLETED
2020-01-18 11:44:17.103 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: Response processed after 1118ms
2020-01-18 11:44:17.103 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: TID 13716: Transaction completed
2020-01-18 11:44:17.103 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 51: notifyTransactionResponse TID:13716 DONE
2020-01-18 11:44:17.103 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-01-18 11:44:17.104 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:17.104 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-01-18 11:44:17.104 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 1E 03 26 01 00 25 05 FC
2020-01-18 11:44:17.104 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 30: Sending REQUEST Message = 01 0A 00 13 1E 03 26 01 00 25 05 FC
2020-01-18 11:44:17.107 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-01-18 11:44:17.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:17.108 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-01-18 11:44:17.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 13717: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 5
2020-01-18 11:44:17.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-01-18 11:44:17.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13717: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 5
2020-01-18 11:44:17.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-01-18 11:44:17.109 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:17.109 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:17.116 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-01-18 11:44:17.118 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-01-18 11:44:17.118 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-01-18 11:44:17.118 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13717: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 5
2020-01-18 11:44:17.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-01-18 11:44:17.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 13717: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 5
2020-01-18 11:44:17.119 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-01-18 11:44:17.119 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 30: sentData successfully placed on stack.
2020-01-18 11:44:17.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 13717: Advanced to WAIT_REQUEST
2020-01-18 11:44:17.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: TID 13717: Transaction not completed
2020-01-18 11:44:17.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:17.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-01-18 11:44:17.245 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 05 00 00 0D E3
2020-01-18 11:44:17.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=5, payload=05 00 00 0D
2020-01-18 11:44:17.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=5, payload=05 00 00 0D
2020-01-18 11:44:17.248 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13717: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 5
2020-01-18 11:44:17.248 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-01-18 11:44:17.248 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 13717: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 5
2020-01-18 11:44:17.248 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 13717: (Callback 5)
2020-01-18 11:44:17.248 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-01-18 11:44:17.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 13717: callback 5
2020-01-18 11:44:17.249 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=5, payload=05 00 00 0D
2020-01-18 11:44:17.249 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 30: SendData Request. CallBack ID = 5, Status = Transmission complete and ACK received(0)
2020-01-18 11:44:17.249 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13717: Transaction COMPLETED
2020-01-18 11:44:17.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Response processed after 142ms
2020-01-18 11:44:17.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: TID 13717: Transaction completed
2020-01-18 11:44:17.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: notifyTransactionResponse TID:13717 DONE
2020-01-18 11:44:17.250 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-01-18 11:44:17.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:17.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-01-18 11:44:17.632 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 19 03 26 03 00 CD
2020-01-18 11:44:17.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=0, payload=00 19 03 26 03 00
2020-01-18 11:44:17.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=0, payload=00 19 03 26 03 00
2020-01-18 11:44:17.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-01-18 11:44:17.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Application Command Request (ALIVE:SUC_ROUTE)
2020-01-18 11:44:17.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2020-01-18 11:44:17.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported
2020-01-18 11:44:17.635 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 25: Received COMMAND_CLASS_SWITCH_MULTILEVEL V2 SWITCH_MULTILEVEL_REPORT
2020-01-18 11:44:17.635 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 25: Switch Multi Level report, value = 0
2020-01-18 11:44:17.635 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-01-18 11:44:17.635 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=0
2020-01-18 11:44:17.635 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Updating channel state zwave:device:28e4dafb:node25:switch_dimmer to 0 [PercentType]
2020-01-18 11:44:17.635 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Commands processed 1.
2020-01-18 11:44:17.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1f54ca7.
2020-01-18 11:44:17.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-18 11:44:17.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-01-18 11:44:17.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-01-18 11:44:17.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.