Fibaro Dimmer2 Status Update

Hello together,

I moved my complete z-wave network from ZWAY platform to openhab platform.
Now there is an issue with the Fibaro Dimmer 2. After pressing the wall switch to turn it on or off, this is not reported to the openhab z-wave controller. The new status comes only with the periodical status message, which occurs every 60 seconds.
So it can take up to 60 seconds after pressing the switch until openhab can react to the new state of the dimmer.
I tried to read the Fibaro manual very carefully and tried different setting - but nothing fixed that problem. On z-way platform this was never a issue and worked fine…
Any ideas what I can try?

Thanks in advance
Sebastian

Here is my FGD212 config to compare with, no issues with state updates:


thingTypeUID: zwave:fibaro_fgd212_00_000
configuration:
  config_52_2: 3600
  config_31_1: 1
  config_33_1: 0
  config_54_1: 0
  config_10_2: 0
  config_50_1: 10
  group_5: []
  group_4: []
  group_1:
    - controller
  group_3: []
  group_2: []
  config_16_2: 5
  config_39_2: 250
  config_35_1: 1
  config_14_1: 1
  config_37_1: 1
  config_58_1: 0
  config_20_1: 0
  config_41_1: 2
  config_22_1: 0
  config_43_1: 1
  config_1_1: 22
  config_7_1: 5
  config_28_1: 1
  config_49_1: 1
  config_9_1: 1
  config_3_1: 99
  config_24_1: 0
  config_45_1: 1
  config_5_1: 1
  config_26_1: 0
  config_47_1: 1
  config_30_1: 2
  config_32_1: 2
  config_53_2: 10
  switchall_mode: 0
  action_reinit: false
  config_59_2: 0
  config_11_2: 0
  config_19_1: 99
  config_34_1: 1
  config_13_1: 0
  config_15_1: 30
  config_42_1: 3
  config_21_1: 1
  config_40_1: 3
  config_2_1: 70
  config_27_1: 0
  config_6_2: 1
  config_29_1: 0
  config_8_2: 5
  config_23_1: 0
  config_46_1: 1
  config_44_2: 600
  config_25_1: 0
  config_48_1: 1
  config_4_2: 0
  node_id: 37

I’ve seend the controller is added in the proper assosiacion groups. Sometimes removing it, watining for propagation and readding helped in my case.

Though I would do it from UI not from yml.

Hello together,
sorry to come back with this point again.
I tired to adopt all values as shown and I also removed the lifeline controller and set it up again as well.
But there was no success. After switching the wall switch it takes some time until polling is done. Can be take up to 60 seconds. Is there any idea else what can be checked?
Thanks for help
Sebastian

This is the readout of the device:

UID: zwave:device:49c171dfd7:node3
label: Licht Bad
thingTypeUID: zwave:fibaro_fgd212_03_005
configuration:
  config_52_2: 3600
  config_31_1: 1
  config_33_1: 0
  config_54_1: 0
  config_10_2: 0
  config_50_1: 10
  group_5: []
  group_4: []
  group_1:
    - controller
  group_3: []
  group_2: []
  config_16_2: 0
  config_39_2: 250
  config_35_1: 1
  config_14_1: 0
  config_37_1: 1
  config_58_1: 0
  config_20_1: 0
  config_41_1: 2
  config_22_1: 0
  config_43_1: 1
  config_1_1: 10
  config_7_1: 1
  config_28_1: 1
  config_49_1: 1
  config_9_1: 1
  config_3_1: 1
  config_24_1: 0
  config_45_1: 1
  config_5_1: 1
  config_26_1: 0
  config_47_1: 1
  config_30_1: 2
  config_32_1: 2
  config_53_2: 10
  switchall_mode: 255
  action_reinit: false
  config_38_2: 255
  config_59_2: 0
  config_11_2: 0
  config_19_1: 99
  config_34_1: 2
  config_13_1: 0
  config_15_1: 0
  config_42_1: 3
  config_21_1: 1
  config_40_1: 3
  binding_pollperiod: 60
  config_2_1: 99
  config_27_1: 0
  config_6_2: 1
  config_29_1: 0
  config_8_2: 5
  config_23_1: 1
  config_46_1: 1
  config_44_2: 600
  config_25_1: 0
  config_48_1: 1
  config_4_2: 0
  node_id: 3
bridgeUID: zwave:serial_zstick:49c171dfd7
channels:
  - id: switch_dimmer
    channelTypeUID: zwave:switch_dimmer
    label: Dimmer
    description: The brightness channel allows to control the brightness of a light.
      It is also possible to switch the light on and off.
    configuration:
      config_restorelastvalue: true
  - id: scene_number
    channelTypeUID: zwave:scene_number
    label: Scene Number
    description: Triggers when a scene button is pressed
    configuration: {}
  - id: sensor_power
    channelTypeUID: zwave:sensor_power
    label: Sensor (power) [Deprecated]
    description: Indicates the energy consumption (kWh)
    configuration: {}
  - id: meter_kwh
    channelTypeUID: zwave:meter_kwh
    label: Electric meter (kWh) [Deprecated]
    description: Indicates the energy consumption (kWh)
    configuration: {}
  - id: meter_watts
    channelTypeUID: zwave:meter_watts
    label: Electric meter (watts) [Deprecated]
    description: Indicates the instantaneous power consumption
    configuration: {}
  - id: meter_reset
    channelTypeUID: zwave:meter_reset
    label: Reset the total power consumption
    description: Reset the meter
    configuration: {}
  - id: config_decimal_param19
    channelTypeUID: zwave:config_decimal
    label: Force initial brightness
    description: Generic class for configuration parameter
    configuration: {}
  - id: config_decimal_param26
    channelTypeUID: zwave:fibaro_fgd212_03_005_config_decimal_param26
    label: Enable S2 input [Deprecated]
    description: Key no. 2 controls the Dimmer additionally
    configuration: {}
  - id: alarm_heat
    channelTypeUID: zwave:alarm_heat
    label: Alarm (heat)
    description: Indicates if a heat alarm is triggered
    configuration: {}
  - id: alarm_power
    channelTypeUID: zwave:alarm_power
    label: Alarm (power)
    description: Indicates if a power alarm is triggered
    configuration: {}
  - id: alarm_system
    channelTypeUID: zwave:alarm_system
    label: Alarm (system)
    description: Indicates if a system alarm is triggered
    configuration: {}
  - id: switch_dimmer1
    channelTypeUID: zwave:switch_dimmer
    label: Dimmer 1
    description: The brightness channel allows to control the brightness of a light.
      It is also possible to switch the light on and off.
    configuration:
      config_restorelastvalue: true
  - id: sensor_power1
    channelTypeUID: zwave:sensor_power
    label: Sensor (power) 1
    description: Indicates the energy consumption (kWh)
    configuration: {}
  - id: meter_kwh1
    channelTypeUID: zwave:meter_kwh
    label: Electric meter (kWh) 1
    description: Indicates the energy consumption (kWh)
    configuration: {}
  - id: meter_watts1
    channelTypeUID: zwave:meter_watts
    label: Electric meter (watts) 1
    description: Indicates the instantaneous power consumption
    configuration: {}
  - id: alarm_heat1
    channelTypeUID: zwave:alarm_heat
    label: Alarm (heat) 1
    description: Indicates if a heat alarm is triggered
    configuration: {}
  - id: alarm_power1
    channelTypeUID: zwave:alarm_power
    label: Alarm (power) 1
    description: Indicates if a power alarm is triggered
    configuration: {}
  - id: alarm_system1
    channelTypeUID: zwave:alarm_system
    label: Alarm (system) 1
    description: Indicates if a system alarm is triggered
    configuration: {}
  - id: notification_send1
    channelTypeUID: zwave:notification_send
    label: Send Notification
    description: Sends a notification
    configuration: {}
  - id: switch_dimmer2
    channelTypeUID: zwave:switch_dimmer
    label: Dimmer 2
    description: The brightness channel allows to control the brightness of a light.
      It is also possible to switch the light on and off.
    configuration:
      config_restorelastvalue: true

Any idea which dimmer (1 or 2) is the problem? What dimmer channels of the three (dimmer, dimmer1 dimmer2) do you have linked to items?

I connected dimmer, and have dimmer 1 and dimmer 2 in focus what is going on.
Sounds complete strange to me, why to need three dimmers for only one functionality…
Maybe I have to switch to phillips HUE to get rid of these problems…

But…you will know this…unsolved problems always hurts…

I don’t completely understand. To recap; You have all three dimmer channels linked to three different items and manual operation of either dimmer 1 or dimmer 2 does not show up right away on ANY of the three items?

I will try to be more precise.
Fibaro Dimmer device is connect to wall switch and is used for bathroom lightening. Inside the bathroom there is installed a mirror light. My target is, to switch on mirror light when pressing wall switch for bathroom lightening.

But the trigger command from Fibaro dimmer is not send to openhab when pressing the wall siwtch. The new state is polled every 60 seconds, so it takes maximum 60 seconds to switch on the mirror light.
I connected the dimmer channel to lightening item, dimmer 2 and dimmer 3 are only connected to monitor, what is going on and was used for trouble shooting.
(Former I used zway server, with this setup the Fibaro device worked fine as expected - but I had troubles with zway and openhab to have a stable connection between both - so I migrated all z-wave devices directly to openhab instance)

I don’t understand in detail your setup either, but let’s try:

Your bathroom light is connected to the FGD212, you have a wall switch connected to the FGD212, presumably on terminal S1.

If you press S1, the bathroom light switches ON or OFF.

Your mirror light is also connected to a smart device, but not to the FGD212.

You don’t get the proper switch state from FGD212 to trigger a rule to turn the mirror light on and off.

Possible solution: you need to link your Switch or Dimmer itemtype to Channel switch_dimmer1

Example:
Switch FGD212_Sw "Bathroom Light" { channel="zwave:device:49c171dfd7:node3:switch_dimmer1" }

If you take a look in your events.log, it should show:

2025-02-06 14:10:21.881 [INFO ] [openhab.event.ItemCommandEvent      ] - Item 'FGD212_Sw' received command ON
2025-02-06 14:10:22.935 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'FGD212_Sw' changed from OFF to ON

Now you can trigger on state change events for Item FGD212_Sw

The switch_dimmer2 Channel would show you something similar, if you connect a physical switch to the S2 terminal.

If you are after the scene options (clicking the connected wall switch several times) you need to connect the Channel scene_number to a Number itemtype.
Then you can trigger on state updates to that Number itemtype:

Number FGD212_Num "S2 on FGD212 [%d]" { channel="zwave:device:49c171dfd7:node3:scene_number" }

2025-02-06 14:10:11.593 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'FGD212_Num' changed from 16 to 12 // hold S1
2025-02-06 14:10:12.995 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'FGD212_Num' changed from 12 to 13 // release S1
2025-02-06 14:10:19.393 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'FGD212_Num' changed from 13 to 16 // one click S1
2025-02-06 14:10:21.859 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'FGD212_Num' changed from 16 to 14 // two clicks S1

I see @sihui beat me to it, but a DEBUG log will help at this point.

Does this mean they are linked to items?

Can you set the ZW binding in DEBUG and flip the mirror switch, and wait until OH updates (60 seconds), then go back to INFO and post the log?

The issue is the root endpoint (dimmer) can be a mirror of endpoint 1 (Dimmer 1) and the update from the device may not be going where it needs to. With all three channels linked to items, something should change, but the debug log of ZW will help.

1 Like

Perfect guys :grinning:
You all pointed me into the right direction.
While reading the log, I saw that Dimmer1 channel was updated very quick after hitting the wall switch, and Dimmer (without Number) channel, which was used by me, later as described before. So very easy change and now it works.
Just good to know, for which reason is Dimmer channel available?
Dimmer1 channel is the correct one.

Have a nice evening and thanks very much for your help.
Sebastian

2025-02-06 20:34:15.113 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 03 08 56 01 2B 01 10 00 7B 08 C3 00 3D 
2025-02-06 20:34:15.123 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 08 56 01 2B 01 10 00 7B 08 C3 00 
2025-02-06 20:34:15.125 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 08 56 01 2B 01 10 00 7B 08 C3 00 
2025-02-06 20:34:15.125 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:15.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
2025-02-06 20:34:15.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:15.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_CRC_16_ENCAP
2025-02-06 20:34:15.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SCENE_ACTIVATION, endpoint 0
2025-02-06 20:34:15.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SCENE_ACTIVATION
2025-02-06 20:34:15.128 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SCENE_ACTIVATION V1 SCENEACTIVATION_SET
2025-02-06 20:34:15.129 [DEBUG] [ass.ZWaveSceneActivationCommandClass] - NODE 3: Scene activation: Scene 16, Time 0
2025-02-06 20:34:15.129 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2025-02-06 20:34:15.129 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SCENE_ACTIVATION, value=16
2025-02-06 20:34:15.130 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:49c171dfd7:node3:scene_number to 16 [DecimalType]
2025-02-06 20:34:15.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2025-02-06 20:34:15.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5b448f42.
2025-02-06 20:34:15.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:15.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:15.133 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:15.133 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-06 20:34:15.455 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 04 00 03 0E 56 01 60 0D 01 01 31 05 04 22 00 14 43 02 C3 00 5E 
2025-02-06 20:34:15.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0E 56 01 60 0D 01 01 31 05 04 22 00 14 43 02 C3 00 
2025-02-06 20:34:15.469 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0E 56 01 60 0D 01 01 31 05 04 22 00 14 43 02 C3 00 
2025-02-06 20:34:15.469 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:15.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
2025-02-06 20:34:15.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:15.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_CRC_16_ENCAP
2025-02-06 20:34:15.471 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2025-02-06 20:34:15.471 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 1
2025-02-06 20:34:15.472 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2025-02-06 20:34:15.472 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SENSOR_MULTILEVEL V4 SENSOR_MULTILEVEL_REPORT
2025-02-06 20:34:15.473 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Type = Power(4), Scale = 0
2025-02-06 20:34:15.473 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Value = 2
2025-02-06 20:34:15.474 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2025-02-06 20:34:15.474 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=2
2025-02-06 20:34:15.475 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 3: Sensor conversion not performed for POWER.
2025-02-06 20:34:15.476 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:49c171dfd7:node3:sensor_power1 to 2 [DecimalType]
2025-02-06 20:34:15.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2025-02-06 20:34:15.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1fbc8d56.
2025-02-06 20:34:15.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:15.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:15.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:15.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-06 20:34:16.091 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 13 00 04 00 03 0B 56 01 60 0D 01 01 26 03 63 D9 3F C1 00 BB 
2025-02-06 20:34:16.103 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0B 56 01 60 0D 01 01 26 03 63 D9 3F C1 00 
2025-02-06 20:34:16.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0B 56 01 60 0D 01 01 26 03 63 D9 3F C1 00 
2025-02-06 20:34:16.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:16.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
2025-02-06 20:34:16.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:16.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_CRC_16_ENCAP
2025-02-06 20:34:16.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2025-02-06 20:34:16.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2025-02-06 20:34:16.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2025-02-06 20:34:16.109 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2025-02-06 20:34:16.109 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Switch Multi Level report, value = 99
2025-02-06 20:34:16.109 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2025-02-06 20:34:16.110 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=99
2025-02-06 20:34:16.111 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:49c171dfd7:node3:switch_dimmer1 to 100 [PercentType]
2025-02-06 20:34:16.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2025-02-06 20:34:16.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@bd58326.
2025-02-06 20:34:16.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:16.114 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:16.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:16.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
==> /var/log/openhab/events.log <==
2025-02-06 20:34:16.114 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Licht_Bad_Dimmer_1' changed from 0 to 100
==> /var/log/openhab/openhab.log <==
2025-02-06 20:34:25.440 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 04 00 03 0E 56 01 60 0D 01 01 31 05 04 22 01 E1 CF 89 C1 00 AF 
2025-02-06 20:34:25.452 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0E 56 01 60 0D 01 01 31 05 04 22 01 E1 CF 89 C1 00 
2025-02-06 20:34:25.453 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0E 56 01 60 0D 01 01 31 05 04 22 01 E1 CF 89 C1 00 
2025-02-06 20:34:25.453 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:25.454 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
2025-02-06 20:34:25.454 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:25.455 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_CRC_16_ENCAP
2025-02-06 20:34:25.455 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2025-02-06 20:34:25.456 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 1
2025-02-06 20:34:25.456 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2025-02-06 20:34:25.457 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SENSOR_MULTILEVEL V4 SENSOR_MULTILEVEL_REPORT
2025-02-06 20:34:25.457 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Type = Power(4), Scale = 0
2025-02-06 20:34:25.458 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Value = 48.1
2025-02-06 20:34:25.458 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2025-02-06 20:34:25.459 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=48.1
2025-02-06 20:34:25.460 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 3: Sensor conversion not performed for POWER.
2025-02-06 20:34:25.460 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:49c171dfd7:node3:sensor_power1 to 48.1 [DecimalType]
2025-02-06 20:34:25.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2025-02-06 20:34:25.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@350d3acf.
2025-02-06 20:34:25.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:25.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:25.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:25.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-06 20:34:45.153 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...
2025-02-06 20:34:45.154 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:49c171dfd7:node3:switch_dimmer
2025-02-06 20:34:45.155 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2025-02-06 20:34:45.155 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Creating new message for command SWITCH_MULTILEVEL_GET
2025-02-06 20:34:45.156 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2025-02-06 20:34:45.156 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2025-02-06 20:34:45.157 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:49c171dfd7:node3:switch_dimmer on COMMAND_CLASS_BASIC
2025-02-06 20:34:45.157 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:49c171dfd7:node3:meter_kwh
2025-02-06 20:34:45.158 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 3: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2025-02-06 20:34:45.158 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 3: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2025-02-06 20:34:45.159 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 3: Creating new message for application command METER_GET
2025-02-06 20:34:45.159 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_METER
2025-02-06 20:34:45.160 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_METER is NOT required to be secured
2025-02-06 20:34:45.160 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:49c171dfd7:node3:meter_watts
2025-02-06 20:34:45.160 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 3: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2025-02-06 20:34:45.161 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 3: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2025-02-06 20:34:45.161 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 3: Creating new message for application command METER_GET
2025-02-06 20:34:45.162 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_METER
2025-02-06 20:34:45.162 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_METER is NOT required to be secured
2025-02-06 20:34:45.163 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:49c171dfd7:node3:switch_dimmer1
2025-02-06 20:34:45.163 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2025-02-06 20:34:45.164 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Creating new message for command SWITCH_MULTILEVEL_GET
2025-02-06 20:34:45.164 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Encapsulating message, instance / endpoint 1
2025-02-06 20:34:45.165 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 3: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2025-02-06 20:34:45.165 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2025-02-06 20:34:45.165 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2025-02-06 20:34:45.166 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:49c171dfd7:node3:switch_dimmer1 on COMMAND_CLASS_BASIC
2025-02-06 20:34:45.166 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:49c171dfd7:node3:switch_dimmer2
2025-02-06 20:34:45.167 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 2
2025-02-06 20:34:45.167 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Creating new message for command SWITCH_MULTILEVEL_GET
2025-02-06 20:34:45.167 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Encapsulating message, instance / endpoint 2
2025-02-06 20:34:45.168 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 3: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2
2025-02-06 20:34:45.168 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2025-02-06 20:34:45.169 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2025-02-06 20:34:45.169 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:49c171dfd7:node3:switch_dimmer2 on COMMAND_CLASS_BASIC
2025-02-06 20:34:45.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
2025-02-06 20:34:45.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 33571 to queue - size 1
2025-02-06 20:34:45.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-06 20:34:45.171 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 26 02 25 14 F1 
2025-02-06 20:34:45.172 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 26 02 25 14 F1 
2025-02-06 20:34:45.174 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-06 20:34:45.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:45.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:45.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:45.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-06 20:34:45.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.178 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2025-02-06 20:34:45.183 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-06 20:34:45.184 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 33571: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 20
2025-02-06 20:34:45.185 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
2025-02-06 20:34:45.185 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 33572 to queue - size 1
2025-02-06 20:34:45.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
2025-02-06 20:34:45.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 33573 to queue - size 2
2025-02-06 20:34:45.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
2025-02-06 20:34:45.189 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 33574 to queue - size 3
2025-02-06 20:34:45.189 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.189 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
2025-02-06 20:34:45.190 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 33575 to queue - size 4
2025-02-06 20:34:45.190 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33571: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 20
2025-02-06 20:34:45.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:45.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33571: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 20
2025-02-06 20:34:45.198 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.199 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
2025-02-06 20:34:45.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33571: Advanced to WAIT_REQUEST
2025-02-06 20:34:45.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33571: Transaction not completed
2025-02-06 20:34:45.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.336 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 14 00 00 10 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 03 00 00 22 
2025-02-06 20:34:45.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=20, payload=14 00 00 10 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 03 00 00 
2025-02-06 20:34:45.349 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=20, payload=14 00 00 10 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 03 00 00 
2025-02-06 20:34:45.349 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33571: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 20
2025-02-06 20:34:45.350 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:45.350 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33571: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 20
2025-02-06 20:34:45.351 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 33571: (Callback 20)
2025-02-06 20:34:45.351 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2025-02-06 20:34:45.351 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 33571: callback 20
2025-02-06 20:34:45.352 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=20, payload=14 00 00 10 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 03 00 00 
2025-02-06 20:34:45.353 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 20, Status = Transmission complete and ACK received(0)
2025-02-06 20:34:45.353 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:45.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33571: Advanced to WAIT_DATA
2025-02-06 20:34:45.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33571: Transaction not completed
2025-02-06 20:34:45.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.404 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 03 03 26 03 63 C1 00 77 
2025-02-06 20:34:45.416 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 26 03 63 C1 00 
2025-02-06 20:34:45.417 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 26 03 63 C1 00 
2025-02-06 20:34:45.417 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:45.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
2025-02-06 20:34:45.418 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:45.418 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2025-02-06 20:34:45.419 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2025-02-06 20:34:45.419 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2025-02-06 20:34:45.420 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Switch Multi Level report, value = 99
2025-02-06 20:34:45.420 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2025-02-06 20:34:45.421 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=99
2025-02-06 20:34:45.421 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:49c171dfd7:node3:switch_dimmer to 100 [PercentType]
2025-02-06 20:34:45.422 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2025-02-06 20:34:45.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4acc8db3.
2025-02-06 20:34:45.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4acc8db3.
2025-02-06 20:34:45.424 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:33571 DONE
2025-02-06 20:34:45.428 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2025-02-06 20:34:45.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2025-02-06 20:34:45.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:45.430 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.431 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-06 20:34:45.432 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 03 03 32 01 00 25 15 E5 
2025-02-06 20:34:45.433 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0A 00 13 03 03 32 01 00 25 15 E5 
2025-02-06 20:34:45.435 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-06 20:34:45.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:45.439 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2025-02-06 20:34:45.447 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-06 20:34:45.448 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 33572: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 21
2025-02-06 20:34:45.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:45.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33572: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 21
2025-02-06 20:34:45.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-06 20:34:45.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.459 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.460 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33572: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 21
2025-02-06 20:34:45.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:45.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33572: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 21
2025-02-06 20:34:45.462 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.462 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
2025-02-06 20:34:45.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33572: Advanced to WAIT_REQUEST
2025-02-06 20:34:45.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33572: Transaction not completed
2025-02-06 20:34:45.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.522 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 15 00 00 09 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 38 
2025-02-06 20:34:45.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=21, payload=15 00 00 09 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:45.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=21, payload=15 00 00 09 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:45.537 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33572: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 21
2025-02-06 20:34:45.537 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:45.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33572: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 21
2025-02-06 20:34:45.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 33572: (Callback 21)
2025-02-06 20:34:45.538 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2025-02-06 20:34:45.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 33572: callback 21
2025-02-06 20:34:45.539 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=21, payload=15 00 00 09 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:45.540 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 21, Status = Transmission complete and ACK received(0)
2025-02-06 20:34:45.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:45.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33572: Advanced to WAIT_DATA
2025-02-06 20:34:45.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33572: Transaction not completed
2025-02-06 20:34:45.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.597 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 03 0A 32 02 21 44 00 00 1B 6F 00 00 C1 00 00 
2025-02-06 20:34:45.632 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0A 32 02 21 44 00 00 1B 6F 00 00 C1 00 
2025-02-06 20:34:45.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0A 32 02 21 44 00 00 1B 6F 00 00 C1 00 
2025-02-06 20:34:45.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:45.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
2025-02-06 20:34:45.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:45.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_METER, endpoint 0
2025-02-06 20:34:45.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_METER
2025-02-06 20:34:45.635 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_METER V3 METER_REPORT
2025-02-06 20:34:45.636 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 3: Meter: Type=Electric(1), Scale=kWh(0), Value=70.23
2025-02-06 20:34:45.636 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveMeterValueEvent
2025-02-06 20:34:45.637 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=70.23
2025-02-06 20:34:45.637 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:49c171dfd7:node3:meter_kwh to 70.23 [DecimalType]
2025-02-06 20:34:45.638 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2025-02-06 20:34:45.638 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@31616f81.
2025-02-06 20:34:45.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@31616f81.
2025-02-06 20:34:45.640 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:33572 DONE
2025-02-06 20:34:45.641 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2025-02-06 20:34:45.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2025-02-06 20:34:45.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:45.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-06 20:34:45.644 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 03 03 32 01 10 25 16 F6 
2025-02-06 20:34:45.644 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0A 00 13 03 03 32 01 10 25 16 F6 
2025-02-06 20:34:45.647 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-06 20:34:45.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:45.651 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2025-02-06 20:34:45.655 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-06 20:34:45.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 33573: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 22
2025-02-06 20:34:45.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:45.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33573: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 22
2025-02-06 20:34:45.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-06 20:34:45.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.667 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33573: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 22
2025-02-06 20:34:45.669 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:45.669 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33573: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 22
2025-02-06 20:34:45.669 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.670 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
2025-02-06 20:34:45.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33573: Advanced to WAIT_REQUEST
2025-02-06 20:34:45.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33573: Transaction not completed
2025-02-06 20:34:45.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.712 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 16 00 00 07 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 35 
2025-02-06 20:34:45.724 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=22, payload=16 00 00 07 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:45.724 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=22, payload=16 00 00 07 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:45.725 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33573: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 22
2025-02-06 20:34:45.725 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:45.725 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33573: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 22
2025-02-06 20:34:45.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 33573: (Callback 22)
2025-02-06 20:34:45.726 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2025-02-06 20:34:45.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 33573: callback 22
2025-02-06 20:34:45.727 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=22, payload=16 00 00 07 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:45.727 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 22, Status = Transmission complete and ACK received(0)
2025-02-06 20:34:45.728 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:45.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33573: Advanced to WAIT_DATA
2025-02-06 20:34:45.729 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33573: Transaction not completed
2025-02-06 20:34:45.729 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.729 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.786 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 03 08 32 02 21 32 01 E1 00 00 C1 00 E2 
2025-02-06 20:34:45.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 08 32 02 21 32 01 E1 00 00 C1 00 
2025-02-06 20:34:45.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 08 32 02 21 32 01 E1 00 00 C1 00 
2025-02-06 20:34:45.797 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:45.797 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
2025-02-06 20:34:45.797 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:45.798 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_METER, endpoint 0
2025-02-06 20:34:45.798 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_METER
2025-02-06 20:34:45.798 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_METER V3 METER_REPORT
2025-02-06 20:34:45.799 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 3: Meter: Type=Electric(1), Scale=W(2), Value=48.1
2025-02-06 20:34:45.799 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveMeterValueEvent
2025-02-06 20:34:45.800 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=48.1
2025-02-06 20:34:45.800 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:49c171dfd7:node3:meter_watts to 48.1 [DecimalType]
2025-02-06 20:34:45.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2025-02-06 20:34:45.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@160a1638.
2025-02-06 20:34:45.803 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@160a1638.
2025-02-06 20:34:45.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:33573 DONE
2025-02-06 20:34:45.806 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2025-02-06 20:34:45.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2025-02-06 20:34:45.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:45.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-06 20:34:45.810 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 03 06 60 0D 01 01 26 02 25 17 9F 
2025-02-06 20:34:45.811 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0D 00 13 03 06 60 0D 01 01 26 02 25 17 9F 
2025-02-06 20:34:45.813 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-06 20:34:45.814 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:45.817 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2025-02-06 20:34:45.823 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-06 20:34:45.824 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 33574: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 23
2025-02-06 20:34:45.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:45.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33574: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 23
2025-02-06 20:34:45.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-06 20:34:45.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33574: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 23
2025-02-06 20:34:45.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:45.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33574: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 23
2025-02-06 20:34:45.838 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:45.838 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
2025-02-06 20:34:45.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33574: Advanced to WAIT_REQUEST
2025-02-06 20:34:45.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33574: Transaction not completed
2025-02-06 20:34:45.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.840 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.903 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 17 00 00 09 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 3A 
2025-02-06 20:34:45.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=23, payload=17 00 00 09 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:45.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=23, payload=17 00 00 09 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:45.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33574: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 23
2025-02-06 20:34:45.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:45.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33574: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 23
2025-02-06 20:34:45.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 33574: (Callback 23)
2025-02-06 20:34:45.918 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2025-02-06 20:34:45.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 33574: callback 23
2025-02-06 20:34:45.919 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=23, payload=17 00 00 09 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:45.919 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 23, Status = Transmission complete and ACK received(0)
2025-02-06 20:34:45.920 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:45.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33574: Advanced to WAIT_DATA
2025-02-06 20:34:45.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33574: Transaction not completed
2025-02-06 20:34:45.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:45.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:45.975 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 03 07 60 0D 01 01 26 03 63 C1 00 1A 
==> /var/log/openhab/events.log <==
2025-02-06 20:34:45.426 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Licht_Bad_Dimmer' changed from 0 to 100
2025-02-06 20:34:45.804 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Licht_Bad_Electric_meter_watts_Deprecated' changed from 0 to 48.1
==> /var/log/openhab/openhab.log <==
2025-02-06 20:34:45.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 01 01 26 03 63 C1 00 
2025-02-06 20:34:45.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 01 01 26 03 63 C1 00 
2025-02-06 20:34:45.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:45.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
2025-02-06 20:34:45.994 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:45.995 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2025-02-06 20:34:45.995 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2025-02-06 20:34:45.996 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2025-02-06 20:34:45.997 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2025-02-06 20:34:45.997 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Switch Multi Level report, value = 99
2025-02-06 20:34:45.998 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2025-02-06 20:34:45.999 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=99
2025-02-06 20:34:45.999 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:49c171dfd7:node3:switch_dimmer1 to 100 [PercentType]
2025-02-06 20:34:46.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2025-02-06 20:34:46.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4a0d9d74.
2025-02-06 20:34:46.003 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4a0d9d74.
2025-02-06 20:34:46.004 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:33574 DONE
2025-02-06 20:34:46.006 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2025-02-06 20:34:46.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2025-02-06 20:34:46.008 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:46.008 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:46.009 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-06 20:34:46.010 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 03 06 60 0D 01 02 26 02 25 18 93 
2025-02-06 20:34:46.012 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0D 00 13 03 06 60 0D 01 02 26 02 25 18 93 
2025-02-06 20:34:46.015 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-06 20:34:46.016 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:46.019 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2025-02-06 20:34:46.027 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-06 20:34:46.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 33575: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 24
2025-02-06 20:34:46.029 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-06 20:34:46.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33575: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 24
2025-02-06 20:34:46.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-06 20:34:46.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:46.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:46.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:46.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:46.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33575: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 24
2025-02-06 20:34:46.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:46.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33575: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 24
2025-02-06 20:34:46.043 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2025-02-06 20:34:46.044 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
2025-02-06 20:34:46.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33575: Advanced to WAIT_REQUEST
2025-02-06 20:34:46.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33575: Transaction not completed
2025-02-06 20:34:46.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:46.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:46.093 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 18 00 00 08 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 34 
2025-02-06 20:34:46.104 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=24, payload=18 00 00 08 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:46.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=24, payload=18 00 00 08 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:46.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 33575: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 24
2025-02-06 20:34:46.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-06 20:34:46.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 33575: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 24
2025-02-06 20:34:46.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 33575: (Callback 24)
2025-02-06 20:34:46.106 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2025-02-06 20:34:46.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 33575: callback 24
2025-02-06 20:34:46.107 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=24, payload=18 00 00 08 01 C1 7F 7F 7F 7F 00 00 03 11 00 00 00 03 01 00 00 
2025-02-06 20:34:46.107 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 24, Status = Transmission complete and ACK received(0)
2025-02-06 20:34:46.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:46.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 33575: Advanced to WAIT_DATA
2025-02-06 20:34:46.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 33575: Transaction not completed
2025-02-06 20:34:46.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:46.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-06 20:34:46.165 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 03 07 60 0D 02 01 26 03 00 C1 00 7A 
2025-02-06 20:34:46.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 02 01 26 03 00 C1 00 
2025-02-06 20:34:46.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 02 01 26 03 00 C1 00 
2025-02-06 20:34:46.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-06 20:34:46.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
2025-02-06 20:34:46.177 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
2025-02-06 20:34:46.177 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2025-02-06 20:34:46.177 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 2
2025-02-06 20:34:46.177 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2025-02-06 20:34:46.178 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2025-02-06 20:34:46.178 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Switch Multi Level report, value = 0
2025-02-06 20:34:46.178 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2025-02-06 20:34:46.178 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=0
2025-02-06 20:34:46.179 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:49c171dfd7:node3:switch_dimmer2 to 0 [PercentType]
2025-02-06 20:34:46.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2025-02-06 20:34:46.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@64822093.
2025-02-06 20:34:46.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@64822093.
2025-02-06 20:34:46.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:33575 DONE
2025-02-06 20:34:46.182 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2025-02-06 20:34:46.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2025-02-06 20:34:46.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2025-02-06 20:34:46.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-06 20:34:46.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2 Likes

My only other unsolicited piece of advice (now that you are getting the update from the switch) is to increase the poll from 60 seconds to something a lot higher like 86400 (or at least 3600) to reduce ZW network traffic

Maybe I can help here.

I had a similar problem with my rollershutter from fibaro. If I manually move the rollershutter via button the position value is transmitted to OH right after the movement. If I move the rollershutter via OH/ZWave the position value is not transmitted, I need to grab it via refrech.

But this behaviour is not the same on all my rollershutters. It depends on the software version. Older version transmit the new value right after the movement.

Maybe the firmware version is also the case here.

This was/is likely a different problem. Manual operation of a switch generally results in a BASIC command class sent back to the controller. Besides the firmware possibility, the ZW DB needs to have this flag sent to True and not everyone does that (since there are a lot of first-time authors). I had checked that flag out for the FGD-212 prior to my post above to eliminate that possibility and all was correct).
EDIT CC 2025-02-07 100856