Z-wave things lose their Lifeline Association Group

@dastrix80 and @chris

I can also confirm that when you reboot zwave binding lose Nodes lifeline configurations(Fibaro, Aeotec, Danfoss,etc).

Also one strange thing is that example when I push switch ON / OFF wall button, Fibaro Dimmer 2 doesn’t send state status even if lifeline is configured to Controller. State are sent correctly when I example push ON / OFF button on the dashboard . Here is log for that case (node 83):

openHAB 2.4.0 Build #1389.

2018-10-24 23:05:05.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-24 23:05:05.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-24 23:05:05.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-24 23:05:05.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-24 23:05:05.859 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 53 0A 60 0D 01 01 31 05 04 22 00 90 5D
2018-10-24 23:05:05.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 0A 60 0D 01 01 31 05 04 22 00 90
2018-10-24 23:05:05.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 0A 60 0D 01 01 31 05 04 22 00 90
2018-10-24 23:05:05.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-24 23:05:05.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Application Command Request (ALIVE:DONE)
2018-10-24 23:05:05.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: resetResendCount initComplete=true isDead=false
2018-10-24 23:05:05.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-24 23:05:05.862 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 1
2018-10-24 23:05:05.862 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2018-10-24 23:05:05.863 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 83: Received COMMAND_CLASS_SENSOR_MULTILEVEL V4 SENSOR_MULTILEVEL_REPORT
2018-10-24 23:05:05.863 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 83: Sensor Type = Power(4), Scale = 0
2018-10-24 23:05:05.864 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 83: Sensor Value = 14.4
2018-10-24 23:05:05.864 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2018-10-24 23:05:05.865 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 14.4
2018-10-24 23:05:05.866 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 83: Sensor conversion not performed for POWER.
2018-10-24 23:05:05.867 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Updating channel state zwave:device:12345678-9012-3456-7890-123456789012:node83:sensor_power1 to 14.4 [DecimalType]
2018-10-24 23:05:05.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Commands processed 1.
2018-10-24 23:05:05.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5cb76fa9.
2018-10-24 23:05:05.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-24 23:05:05.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-24 23:05:05.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-24 23:05:05.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-24 23:05:08.262 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 53 07 60 0D 01 01 26 03 1F F5
2018-10-24 23:05:08.262 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 1F
2018-10-24 23:05:08.263 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 1F
2018-10-24 23:05:08.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-24 23:05:08.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Application Command Request (ALIVE:DONE)
2018-10-24 23:05:08.264 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: resetResendCount initComplete=true isDead=false
2018-10-24 23:05:08.265 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-24 23:05:08.265 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2018-10-24 23:05:08.265 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2018-10-24 23:05:08.266 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 83: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2018-10-24 23:05:08.266 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 83: Switch Multi Level report, value = 31
2018-10-24 23:05:08.267 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-10-24 23:05:08.267 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 31
2018-10-24 23:05:08.268 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Updating channel state zwave:device:12345678-9012-3456-7890-123456789012:node83:switch_dimmer1 to 31 [PercentType]
2018-10-24 23:05:08.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Commands processed 1.
2018-10-24 23:05:08.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3cf5d396.
2018-10-24 23:05:08.270 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-24 23:05:08.270 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-24 23:05:08.270 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-24 23:05:08.270 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-24 23:05:10.726 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 1F 0E 60 0D 01 01 32 02 21 44 00 00 0E ED 00 00 25
2018-10-24 23:05:10.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=31, callback=0, payload=00 1F 0E 60 0D 01 01 32 02 21 44 00 00 0E ED 00 00
2018-10-24 23:05:10.729 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=31, callback=0, payload=00 1F 0E 60 0D 01 01 32 02 21 44 00 00 0E ED 00 00
2018-10-24 23:05:10.730 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

Hope this helps to find why Lifeline configuration is not work correctly and why Fibaro Dimmer 2 doesn’t report some mystic reason state when you push wall button to ON / OFF.

ps. I’m not sure is this matter, but I have also noticed that Fibaro Dimmer 2 firmware 3.5 configuration line 21 on Habmin looks different that firmware 3.4. Please see the pictures:

firmware 3.5:

firmware 3.4:

Thank you and best regards,
Jussi

Sorry - I’ve not had the chance to look at this yet but will do so soon.

No worries :slight_smile: Thank you for your help!

I experience the same thing mate with wall switches

No wories Chris Jackson, im sure you’re a busy man!

I had a look through this a bit better, and it actually looks fine. Looking at the log, it shows the group is removed, and then re-added, and confirmed. While this is a little silly to remove and re-add (and is also what confused me when I looked at it quickly last week), it appears to restore the associations to exactly the same as it originally was. It’s used the standard association command class, which should be correct given that this device doesn’t seem to have multiple endpoints.

One question I just noticed - you said -:

However, the image shows that the device is a ZW111 which is a dimmer, not a motion sensor, so I’m not sure quite what you mean with this statement?

This log does seem to send the status -:

yes, but the motion sensor and dimmer work together, when zwave fails its because the association is gone.

Also, when you go into Habmin and look under associations, it doesnt say ‘Controller’ it says Node.

I can tell you it may look right but its definitely not :wink:

thanks @chris ! I know that this is strange because when you look at node83 on habmin switch doesn’t go ON or OFF when you push wall button. Lifeline is set to Controller.

Node83 on habmin:

This morning I made new log when I just pushed wall switch (node83) ON / OFF.
I also checked on https://www.cd-jackson.com/index.php/openhab/zwave-log-viewer that there is dimmer state level logged. But state doesn’t report on HABMIN or Habpanel.

Here is the log:



2018-10-27 09:35:27.219 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:27.219 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:27.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:27.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-27 09:35:27.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2018-10-27 09:35:27.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2018-10-27 09:35:27.221 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 83: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2018-10-27 09:35:27.221 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 83: Switch Multi Level report, value = 1
2018-10-27 09:35:27.221 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-10-27 09:35:27.222 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 1
2018-10-27 09:35:27.222 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Updating channel state zwave:device:12345678-9012-3456-7890-123456789012:node83:switch_dimmer1 to 1 [PercentType]
2018-10-27 09:35:27.222 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Commands processed 1.
2018-10-27 09:35:27.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6f7be5e6.
2018-10-27 09:35:27.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:27.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:27.224 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:27.224 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:30.902 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 1F 0E 60 0D 01 01 32 02 21 44 00 00 0E ED 00 00 25
2018-10-27 09:35:30.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=31, callback=0, payload=00 1F 0E 60 0D 01 01 32 02 21 44 00 00 0E ED 00 00
2018-10-27 09:35:30.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=31, callback=0, payload=00 1F 0E 60 0D 01 01 32 02 21 44 00 00 0E ED 00 00
2018-10-27 09:35:30.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:30.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 31: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:30.903 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 31: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:30.903 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 31: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-27 09:35:30.903 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 31: Incoming command class COMMAND_CLASS_METER, endpoint 1
2018-10-27 09:35:30.904 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 31: SECURITY NOT required on COMMAND_CLASS_METER
2018-10-27 09:35:30.904 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 31: Received COMMAND_CLASS_METER V3 METER_REPORT
2018-10-27 09:35:30.904 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 31: Meter: Type=Electric(1), Scale=kWh(0), Value=38.21
2018-10-27 09:35:30.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 31: Commands processed 1.
2018-10-27 09:35:30.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 31: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@73114b0e.
2018-10-27 09:35:30.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:30.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:30.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:30.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:31.406 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 3D 03 40 03 0B 84
2018-10-27 09:35:31.407 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=61, callback=0, payload=00 3D 03 40 03 0B
2018-10-27 09:35:31.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=61, callback=0, payload=00 3D 03 40 03 0B
2018-10-27 09:35:31.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:31.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 61: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:31.410 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 61: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:31.410 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 61: Incoming command class COMMAND_CLASS_THERMOSTAT_MODE, endpoint 0
2018-10-27 09:35:31.410 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 61: SECURITY not supported
2018-10-27 09:35:31.410 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 61: Received COMMAND_CLASS_THERMOSTAT_MODE V1 THERMOSTAT_MODE_REPORT
2018-10-27 09:35:31.410 [DEBUG] [lass.ZWaveThermostatModeCommandClass] - NODE 61: Thermostat Mode report, value = 11
2018-10-27 09:35:31.411 [DEBUG] [lass.ZWaveThermostatModeCommandClass] - NODE 61: Thermostat Mode Report, value = Heat Econ
2018-10-27 09:35:31.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 61: Commands processed 1.
2018-10-27 09:35:31.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 61: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@18f0b083.
2018-10-27 09:35:31.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:31.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:31.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:31.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:33.036 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 53 0A 60 0D 01 01 31 05 04 22 00 79 B4
2018-10-27 09:35:33.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 0A 60 0D 01 01 31 05 04 22 00 79
2018-10-27 09:35:33.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 0A 60 0D 01 01 31 05 04 22 00 79
2018-10-27 09:35:33.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:33.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:33.037 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:33.037 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-27 09:35:33.037 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 1
2018-10-27 09:35:33.037 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2018-10-27 09:35:33.037 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 83: Received COMMAND_CLASS_SENSOR_MULTILEVEL V4 SENSOR_MULTILEVEL_REPORT
2018-10-27 09:35:33.037 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 83: Sensor Type = Power(4), Scale = 0
2018-10-27 09:35:33.037 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 83: Sensor Value = 12.1
2018-10-27 09:35:33.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2018-10-27 09:35:33.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 12.1
2018-10-27 09:35:33.037 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 83: Sensor conversion not performed for POWER.
2018-10-27 09:35:33.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Updating channel state zwave:device:12345678-9012-3456-7890-123456789012:node83:sensor_power1 to 12.1 [DecimalType]
2018-10-27 09:35:33.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Commands processed 1.
2018-10-27 09:35:33.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@607da4ff.
2018-10-27 09:35:33.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:33.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:33.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:33.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:33.727 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 53 07 60 0D 01 01 26 03 00 EA
2018-10-27 09:35:33.727 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 00
2018-10-27 09:35:33.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 00
2018-10-27 09:35:33.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:33.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:33.728 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:33.729 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-27 09:35:33.729 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2018-10-27 09:35:33.729 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2018-10-27 09:35:33.729 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 83: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2018-10-27 09:35:33.729 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 83: Switch Multi Level report, value = 0
2018-10-27 09:35:33.730 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-10-27 09:35:33.730 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 0
2018-10-27 09:35:33.730 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Updating channel state zwave:device:12345678-9012-3456-7890-123456789012:node83:switch_dimmer1 to 0 [PercentType]
2018-10-27 09:35:33.730 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Commands processed 1.
2018-10-27 09:35:33.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@501929c3.
2018-10-27 09:35:33.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:33.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:33.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:33.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:36.128 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 1B 0E 32 02 21 74 00 08 AC 63 00 00 00 00 00 00 58
2018-10-27 09:35:36.128 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 0E 32 02 21 74 00 08 AC 63 00 00 00 00 00 00
2018-10-27 09:35:36.129 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 0E 32 02 21 74 00 08 AC 63 00 00 00 00 00 00
2018-10-27 09:35:36.129 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:36.130 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:36.130 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:36.130 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_METER, endpoint 0
2018-10-27 09:35:36.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY not supported
2018-10-27 09:35:36.131 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_METER V3 METER_REPORT
2018-10-27 09:35:36.131 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 27: Meter: Type=Electric(1), Scale=W(2), Value=568.419
2018-10-27 09:35:36.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2018-10-27 09:35:36.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@29a951f5.
2018-10-27 09:35:36.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:36.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:36.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:36.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:37.386 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 53 07 60 0D 01 01 26 03 01 EB
2018-10-27 09:35:37.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 01
2018-10-27 09:35:37.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 01
2018-10-27 09:35:37.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:37.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:37.388 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:37.389 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-27 09:35:37.389 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2018-10-27 09:35:37.389 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2018-10-27 09:35:37.390 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 83: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2018-10-27 09:35:37.390 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 83: Switch Multi Level report, value = 1
2018-10-27 09:35:37.390 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-10-27 09:35:37.391 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 1
2018-10-27 09:35:37.391 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Updating channel state zwave:device:12345678-9012-3456-7890-123456789012:node83:switch_dimmer1 to 1 [PercentType]
2018-10-27 09:35:37.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Commands processed 1.
2018-10-27 09:35:37.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@344098eb.
2018-10-27 09:35:37.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:37.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:37.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:37.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:43.065 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 53 0A 60 0D 01 01 31 05 04 22 00 79 B4
2018-10-27 09:35:43.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 0A 60 0D 01 01 31 05 04 22 00 79
2018-10-27 09:35:43.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 0A 60 0D 01 01 31 05 04 22 00 79
2018-10-27 09:35:43.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:43.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:43.066 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:43.066 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-27 09:35:43.066 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 1
2018-10-27 09:35:43.066 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2018-10-27 09:35:43.066 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 83: Received COMMAND_CLASS_SENSOR_MULTILEVEL V4 SENSOR_MULTILEVEL_REPORT
2018-10-27 09:35:43.067 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 83: Sensor Type = Power(4), Scale = 0
2018-10-27 09:35:43.067 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 83: Sensor Value = 12.1
2018-10-27 09:35:43.067 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2018-10-27 09:35:43.067 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 12.1
2018-10-27 09:35:43.067 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 83: Sensor conversion not performed for POWER.
2018-10-27 09:35:43.067 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Updating channel state zwave:device:12345678-9012-3456-7890-123456789012:node83:sensor_power1 to 12.1 [DecimalType]
2018-10-27 09:35:43.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Commands processed 1.
2018-10-27 09:35:43.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@399798c0.
2018-10-27 09:35:43.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:43.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:43.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:43.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:43.288 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 25 0E 60 0D 01 01 32 02 21 44 00 00 0D 1F 00 00 EE
2018-10-27 09:35:43.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=37, callback=0, payload=00 25 0E 60 0D 01 01 32 02 21 44 00 00 0D 1F 00 00
2018-10-27 09:35:43.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=37, callback=0, payload=00 25 0E 60 0D 01 01 32 02 21 44 00 00 0D 1F 00 00
2018-10-27 09:35:43.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:43.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:43.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 37: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:43.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 37: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-27 09:35:43.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 37: Incoming command class COMMAND_CLASS_METER, endpoint 1
2018-10-27 09:35:43.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 37: SECURITY NOT required on COMMAND_CLASS_METER
2018-10-27 09:35:43.290 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 37: Received COMMAND_CLASS_METER V3 METER_REPORT
2018-10-27 09:35:43.290 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 37: Meter: Type=Electric(1), Scale=kWh(0), Value=33.59
2018-10-27 09:35:43.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Commands processed 1.
2018-10-27 09:35:43.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2059e900.
2018-10-27 09:35:43.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:43.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:43.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:43.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:43.835 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 53 07 60 0D 01 01 26 03 00 EA
2018-10-27 09:35:43.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 00
2018-10-27 09:35:43.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 00
2018-10-27 09:35:43.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:43.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:43.836 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:43.836 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2018-10-27 09:35:43.837 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2018-10-27 09:35:43.837 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2018-10-27 09:35:43.837 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 83: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2018-10-27 09:35:43.837 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 83: Switch Multi Level report, value = 0
2018-10-27 09:35:43.837 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-10-27 09:35:43.837 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 0
2018-10-27 09:35:43.837 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 83: Updating channel state zwave:device:12345678-9012-3456-7890-123456789012:node83:switch_dimmer1 to 0 [PercentType]
2018-10-27 09:35:43.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Commands processed 1.
2018-10-27 09:35:43.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@439c3207.
2018-10-27 09:35:43.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:43.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:43.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:43.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:46.130 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 1B 0E 32 02 21 74 00 08 85 D3 00 00 00 00 00 00 C1
2018-10-27 09:35:46.130 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 0E 32 02 21 74 00 08 85 D3 00 00 00 00 00 00
2018-10-27 09:35:46.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 0E 32 02 21 74 00 08 85 D3 00 00 00 00 00 00
2018-10-27 09:35:46.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:46.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:46.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2018-10-27 09:35:46.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_METER, endpoint 0
2018-10-27 09:35:46.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY not supported
2018-10-27 09:35:46.133 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_METER V3 METER_REPORT
2018-10-27 09:35:46.133 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 27: Meter: Type=Electric(1), Scale=W(2), Value=558.547
2018-10-27 09:35:46.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2018-10-27 09:35:46.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@732b6d5f.
2018-10-27 09:35:46.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:46.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-27 09:35:46.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2018-10-27 09:35:46.135 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2018-10-27 09:35:48.337 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 53 07 60 0D 01 01 26 03 01 EB
2018-10-27 09:35:48.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 01
2018-10-27 09:35:48.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=83, callback=0, payload=00 53 07 60 0D 01 01 26 03 01
2018-10-27 09:35:48.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-27 09:35:48.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 83: Application Command Request (ALIVE:DONE)
2018-10-27 09:35:48.340 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 83: resetResendCount initComplete=true isDead=false



I don’t know at this point where I should troubleshoot this more. If you have any tip where to look this trouble, I will check it. Thank you! :slight_smile:

Br, Jussi

Yes, but when it fails, it’s because the association is gone - in the motion sensor - not in the light. You provided me a log for the association configuration in the light which looks fine.

Well, I don’t see how this is an issue. Firstly the associations seem to be set fine - exactly the same as they were. Secondly, associations in the light will have no impact on the motion sensor.

Maybe I’m not understanding something here, so I apologise. Please can you describe why you think there is a problem with the configuration of the light?

There are often multiple channels in these devices - some that are connected to the root endpoint, and some in the other endpoints. Which channels are you using?

This is the type of thing I see in my devices

That’s just the UI - we know there is an issue with that. I need to get a log to show what is happening, and so far I don’t have anything. The issue is not with the dimmer that you keep providing information for - the association that matters is with the motion sensor.

As we saw in the previous log, the dimmer associations are set ok.

This is happening to me too.
Yesterday I changed a FGD212 Dimmer-2 v3.4 with a brand new v3.5 in hope to get rid of some LED flickering via param38. I’m really struggling getting local ON/OFF to trigger rule. I even tried upgrade to 2.4.0 Build#1401 (w/build-in Z-Wave binding).

I changed channel from switch_dimmer to switch_dimmer1 and when I went to bed is was working.
This morning after the network healing, it does not. I have not set any associations.
Communication with the node works fine from the controller. (motion sensor rule turning light ON/OFF, works fine dimming from sitemap). Node has 47 neighbours and is just 2m from the controller.

Other v3.5 Dimmer-2s in my network are working fine using the switch_dimmer channel. Strange.
Since I was replacing a node, all I did was include it and change the node number in my .items file.
Have tried restarting numerous times.
Did not remove the old node from the network as I intend to use it elsewhere, but right now it is not powered.

Any suggestions how to proceed?

Sorry to keep repeating myself, but I need to get a log to find out why the configuration is being changed. Without this, I’m afraid I have no clue where to look - sorry.

This came in the log 2-3s after the switch (S1) was pushed. Unsure if it is related for my network is only quiet for max 5s with debug on:

2018-10-28 12:10:11.296 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 72: TID 9764: Timeout at state WAIT_DATA. 3 retries remaining.
2018-10-28 12:10:11.297 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 9764: Transaction CANCELLED
2018-10-28 12:10:11.297 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 72: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-10-28 12:10:11.297 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 72: notifyTransactionResponse TID:9764 CANCELLED
2018-10-28 12:10:11.297 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

The node in question is node102, this log was from node72, a neighbour.

This is the end of a transaction - probably from the binding sending some data to a device, but I’m no clue what it is really.

Let’s take a step back again…

The issue that you are reporting is that associations are lost? So, we need to find what is removing them. This will happen some time - I don’t know when as I don’t know what is apparently removing the association. We need to find what is changing the configuration.

Associations are lists that are configured in a device that configure them to send notifications. If you are seeing information in the log after you press a switch, then there is likely an association configured, or there would be nothing sent from the device. The log excerpt above is too short to tell anything from, but if you are saying that this happens after the device button is pressed, then I suspect that associations are working (or, it’s totally unrelated).

grep’ing for ‘Node 102’ in the log I see this:

2018-10-17 03:32:15.722 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 102: Not initialized (ie node unknown), ignoring message.
2018-10-17 03:32:15.754 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 102: Not initialized (ie node unknown), ignoring message.
2018-10-17 03:32:16.054 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 102: Not initialized (ie node unknown), ignoring message.
2018-10-28 12:14:05.901 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 102: Polling...
2018-10-28 12:14:05.901 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 102: Polling zwave:device:f180343d:node102:switch_dimmer
2018-10-28 12:14:05.901 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 102: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2018-10-28 12:14:05.901 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 102: Creating new message for command SWITCH_MULTILEVEL_GET
2018-10-28 12:14:05.902 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 102: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2018-10-28 12:14:05.902 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 102: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2018-10-28 12:14:05.902 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 102: Polling skipped for zwave:device:f180343d:node102:switch_dimmer on COMMAND_CLASS_BASIC
2018-10-28 12:14:05.902 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 102: Polling zwave:device:f180343d:node102:scene_number
2018-10-28 12:14:05.902 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 102: Polling zwave:device:f180343d:node102:sensor_power

As you can see, nothing from around 12:10:11
My network heals an 03:00

I usually do not touch the associations, but yesterday, in the heat of the battle, I did try setting Lifeline to controller. Today it appears as gone. Will try setting it again.

So we need to get the debug log that shows when it disappeared. If you can provide the logs, I’m very happy to take a look and see what we can find.

I’m not having this particular issue, but I’ve worked though many other issues. One thing I learned early on is that it’s better to provide full debug logs that cover the time period when the problem occurs. Even if it’s 10, 20, 30, or 40 MB of log data. Post it on a shared folder somewhere so that @chris can see exactly what’s happening. Snippets of logs, or logs filtered by “NODE XX”, won’t be sufficient. I can assure you that following this approach will lead to the root cause (whatever it may be) much more quickly. :wink:

1 Like