Migrated oh2.5 to oh4.1.2 and the only thing that is offline is the zwave controller

TeKilla · May 14, 2024, 1:27pm

i run the old openhab installation on centos7.9 and a new installation on almalinux 9.
the new installation is looking very good and the only thing that is offline ist the z-wave controller.
BRIDGE_OFFLINE / Controller is offline
when i put the stick in the old server the controller comes online and all is working
when i use the stick in the new server i see the device with lsusb and i can also select the device in the zwave controller thing config BUT the controller allways goes to the OFFLINE State.
i have added the exta_java_opts (EXTRA_JAVA_OPTS=“-Dgnu.io.rxtx.SerialPorts=/dev/ttyACM0”) and the group openhab has all the members (openhab : openhab tty wheel dialout lock audio)
Loglines:

2024-05-14 14:58:45.210 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Creating ZWave discovery service for zwave:serial_zstick:512 with scan time of 60
2024-05-14 14:58:45.211 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Active zwave:serial_zstick:512
2024-05-14 14:58:45.211 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
==> /var/log/openhab/events.log <==
2024-05-14 14:58:45.213 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from UNINITIALIZED (DISABLED) to INITIALIZING
==> /var/log/openhab/openhab.log <==
2024-05-14 14:58:45.214 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Initializing ZWave serial controller.
2024-05-14 14:58:45.214 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Initializing ZWave Controller zwave:serial_zstick:512.
==> /var/log/openhab/events.log <==
2024-05-14 14:58:45.214 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline

rlkoshak · May 14, 2024, 4:45pm

Please use code fences when posting logs to the forum. They are much easier to read that way.

```
code goes here
```

The problem is almost certainly an access problem of some sort.

From the command line run the following:

sudo -u openhab cat /dev/ttyACM0

If you get a “Permission denied” error you know that the openhab user doesn’t have permission on the device. Hit ctrl-c to cancel the cat if you didn’t get the error.

Usually there is more in the logs for both the error case and the success case when you have zwave in debug logging. I’m surprised these logs are so short. I’m not a zwave expert so if there is a problem with device itself someone else will need to help. But I can at least eliminate some of the OS sources of problems.

One other thing is to ensure that you at least restart the zwave binding or openHAB itself every time you remove the dongle and before you plug it back in. OH retains a lock on the device and if you yank it and then plug it back in, the old ttyACM0 file still exists so the dongle will reappear at ttyACM1 which, of course, the binding knows nothing about.

TeKilla · May 14, 2024, 6:15pm

hi rlkoshak, thanks for your fast reply!

sudo -u openhab cat /dev/ttyACM0

i got no permission error, but that command returned some characters like that ‘1"?’

and this are the log lines regarding to the zwave bundle restart

==> /var/log/openhab/openhab.log <==
2024-05-14 20:07:13.476 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(425)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2024-05-14 20:07:13.476 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(425)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2024-05-14 20:07:13.476 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(425)] : invoked activate: activate
2024-05-14 20:07:13.476 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(425)] : Set implementation object for component
2024-05-14 20:07:13.476 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(425)] : Changed state from satisfied to active
2024-05-14 20:07:13.863 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Creating ZWave discovery service for zwave:serial_zstick:512 with scan time of 60
2024-05-14 20:07:13.863 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Active zwave:serial_zstick:512
2024-05-14 20:07:13.863 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2024-05-14 20:07:13.868 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Initializing ZWave serial controller.
2024-05-14 20:07:13.868 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Initializing ZWave Controller zwave:serial_zstick:512.

==> /var/log/openhab/events.log <==
2024-05-14 20:07:13.453 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to UNINITIALIZED
2024-05-14 20:07:13.456 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2024-05-14 20:07:13.477 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to UNINITIALIZED (NOT_YET_READY)
2024-05-14 20:07:13.867 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from UNINITIALIZED (NOT_YET_READY) to INITIALIZING
2024-05-14 20:07:13.868 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline

rlkoshak · May 14, 2024, 6:19pm

That’s fine. You’re basically catting the raw stream from the device. I’m not concerned with what the cat returned and more concerned that it wasn’t blocked. That means the openhab user has the permissions it needs.

There really isn’t anything useful in events.log so you don’t really need to post anything from there.

There is nothing from zwave after the “Initializing ZWave Controller…” log statement?

TeKilla · May 14, 2024, 6:30pm

after a openhab service restart i got more information but the controller is still offline

==> /var/log/openhab/openhab.log <==
2024-05-14 20:20:19.404 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Connecting to serial port '/dev/ttyACM0'
2024-05-14 20:20:19.406 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Starting receive thread
2024-05-14 20:20:19.406 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Serial port is initialized
2024-05-14 20:20:19.406 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Initialising ZWave controller
2024-05-14 20:20:19.406 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Starting ZWave thread: Receive
2024-05-14 20:20:19.408 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 21
2024-05-14 20:20:19.408 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF
2024-05-14 20:20:19.408 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 00 00 0B 02 84 07 79 
2024-05-14 20:20:19.409 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 08 00 00 00 0B 02 84 07 79 
2024-05-14 20:20:19.409 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 125
2024-05-14 20:20:19.410 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum error. Calculated = 7D, Received = 79
2024-05-14 20:20:19.411 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2024-05-14 20:20:19.411 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 21
2024-05-14 20:20:19.411 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF
...
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - Got MessageSerialApiGetInitData response.
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 1: Node found
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 2: Node found
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 3: Node found
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 4: Node found
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 7: Node found
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 8: Node found
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 10: Node found
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 11: Node found
2024-05-14 20:20:22.561 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 12: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 13: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 14: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 15: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 17: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 19: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 20: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 21: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 22: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 24: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 25: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 27: Node found
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - ZWave Controller using Controller API
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - ZWave Controller is Primary Controller
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - ------------Number of Nodes Found Registered to ZWave Controller------------
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - # Nodes = 20
2024-05-14 20:20:22.562 [DEBUG] [age.SerialApiGetInitDataMessageClass] - ----------------------------------------------------------------------------
2024-05-14 20:20:22.562 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 5: Transaction COMPLETED
...
2024-05-14 20:20:22.648 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 14 20:20:24 CEST 2024 - 1999ms
2024-05-14 20:20:22.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_10_init complete
2024-05-14 20:20:22.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_11_init
2024-05-14 20:20:22.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_11_init complete
2024-05-14 20:20:22.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_12_init
2024-05-14 20:20:22.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_12_init complete
2024-05-14 20:20:22.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_13_init
2024-05-14 20:20:22.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_13_init complete
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_14_init
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_14_init complete
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_15_init
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_15_init complete
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_17_init
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_17_init complete
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_19_init
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_19_init complete
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_20_init
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_20_init complete
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_21_init
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_21_init complete
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_22_init
2024-05-14 20:20:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_22_init complete
2024-05-14 20:20:22.650 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_24_init
...
2024-05-14 20:20:22.659 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: ProtocolInfo
2024-05-14 20:20:22.659 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: Listening = true
2024-05-14 20:20:22.659 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: Routing   = true
2024-05-14 20:20:22.659 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: Beaming   = true
2024-05-14 20:20:22.659 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: Version   = 4
2024-05-14 20:20:22.659 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: FLIRS     = false
2024-05-14 20:20:22.659 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: Security  = false
2024-05-14 20:20:22.659 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: Max Baud  = 40000

==> /var/log/openhab/events.log <==
2024-05-14 20:20:22.659 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE

==> /var/log/openhab/openhab.log <==
2024-05-14 20:20:22.660 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: Basic    = BASIC_TYPE_ROUTING_SLAVE
2024-05-14 20:20:22.660 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: Generic  = GENERIC_TYPE_SWITCH_BINARY
2024-05-14 20:20:22.660 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 7: Specific = SPECIFIC_TYPE_POWER_SWITCH_BINARY
2024-05-14 20:20:22.661 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Creating new instance of command class COMMAND_CLASS_NO_OPERATION
2024-05-14 20:20:22.662 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created
2024-05-14 20:20:22.662 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Version = 1, version set. Enabling extra functionality.
2024-05-14 20:20:22.662 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes.
2024-05-14 20:20:22.662 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Creating new instance of command class COMMAND_CLASS_BASIC
2024-05-14 20:20:22.662 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 255: Adding to priority controller queue
2024-05-14 20:20:22.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 22 to queue - size 15
...

the i disabled/enabled the thing and got this log lines

2024-05-14 20:33:09.090 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Deactivate zwave:serial_zstick:512
2024-05-14 20:33:19.338 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Creating ZWave discovery service for zwave:serial_zstick:512 with scan time of 60
2024-05-14 20:33:19.338 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Active zwave:serial_zstick:512
2024-05-14 20:33:19.338 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2024-05-14 20:33:19.340 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Initializing ZWave serial controller.
2024-05-14 20:33:19.340 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Initializing ZWave Controller zwave:serial_zstick:512.

==> /var/log/openhab/events.log <==
2024-05-14 20:33:09.086 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to UNINITIALIZED
2024-05-14 20:33:09.091 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from UNINITIALIZED to UNINITIALIZED (DISABLED)
2024-05-14 20:33:19.340 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from UNINITIALIZED (DISABLED) to INITIALIZING
2024-05-14 20:33:19.341 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:512' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline

rlkoshak · May 14, 2024, 6:41pm

We are now getting past where I can help. It’s able to connect to the controller and it’s set up communication between OH and the controller as expected. That checksum error is a little concerning but it’s a trace level log so probably something that happens all the time.

Is there anything from any other binding or part of OH that is relevant perhaps? I know that all the bindings use a common serial communications library, maybe there’s an error from that in the logs?

As far as these logs indicate it’s humming along happy as a clam and then it just silently quits. Based on that first set of logs it never event logs about going offline, unless there’s something you’ve missed and not posted. At 20:20:22 the Thing changes to ONLINE so it had to change to OFFLINE sometime after that.

It’s weird that when you just disable and reenable the Thing that it just stops at initializing the controller also. The Thing won’t go ONLINE until long after that point so it’s getting stuck somehow.

The only other thing I have to recommend is trying a different USB port to plug it into. Maybe there’s something iffy going on there. Beyond that I’m out of ideas.

TeKilla · May 14, 2024, 6:52pm

I tried a new and a different usb controller (it is a vmware vm) but that did not solve the problem
thanks anyway for taking the time, and at least I have more information now and have gotten further than yesterday

apella12 · May 15, 2024, 12:37am

Not an expert on the startup process, but the suggestion to try another port was a good one. What about putting the Zstick in a USB 2.0 hub? By chance is this an Aeotec Gen 5?

From the log snippets the issue is between the controller startup (as 255) and the binding. The check sum error and this message Message is invalid, discarding is not right. Also the messages

2024-05-14 20:20:22.662 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 255: Adding to priority controller queue
2024-05-14 20:20:22.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 22 to queue - size 15

show a big queue of high priority controller message. Once the Node255 messages are cleared the controller will normally be online.

Edit: For comparison you could use Debug (not Trace) startup on the working system.

TeKilla · May 17, 2024, 6:04am

hello, i tried different things without any solution and now i have resettet the controller and this has nothing changed.
Bridge Item State: BRIDGE_OFFLINE Controller is offline
reset procedure: press the reset button 22 seconds until the blue light was solid for 2 seconds

/var/log/openhab/openhab.log:2024-05-17 07:57:11.750 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : BundleComponentActivator : ComponentHolder created.
/var/log/openhab/openhab.log:2024-05-17 07:57:11.751 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : BundleComponentActivator : ComponentHolder created.
/var/log/openhab/openhab.log:2024-05-17 07:57:11.752 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created ConfigDescriptionRegistryinterface=org.openhab.core.config.core.ConfigDescriptionRegistry, filter=null, policy=static, cardinality=1..1, bind=setConfigDescriptionRegistry, unbind=unsetConfigDescriptionRegistry, updated=null, field=null, field-option=null, collection-type=null, parameter=null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.752 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created ThingRegistryinterface=org.openhab.core.thing.ThingRegistry, filter=null, policy=static, cardinality=1..1, bind=setThingRegistry, unbind=unsetThingRegistry, updated=null, field=null, field-option=null, collection-type=null, parameter=null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.753 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created ThingTypeRegistryinterface=org.openhab.core.thing.type.ThingTypeRegistry, filter=null, policy=static, cardinality=1..1, bind=setThingTypeRegistry, unbind=unsetThingTypeRegistry, updated=null, field=null, field-option=null, collection-type=null, parameter=null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.753 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.753 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component created: DS=DS13, implementation=org.openhab.binding.zwave.internal.ZWaveConfigProvider, immediate=true, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.zwave.internal.ZWaveConfigProvider]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.754 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component Services: scope=singleton, services=[org.openhab.core.config.core.ConfigDescriptionProvider, org.openhab.core.config.core.ConfigOptionProvider]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.754 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
/var/log/openhab/openhab.log:2024-05-17 07:57:11.754 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Querying state disabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.755 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Querying state disabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.755 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component can not be activated since it is in state disabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.755 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Querying state disabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.755 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Updating target filters
/var/log/openhab/openhab.log:2024-05-17 07:57:11.756 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : No change in target property for dependency ConfigDescriptionRegistry: currently registered: false
/var/log/openhab/openhab.log:2024-05-17 07:57:11.759 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] :  No existing service listener to unregister for dependency ConfigDescriptionRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.759 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Setting target property for dependency ConfigDescriptionRegistry to null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.759 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : New service tracker for ConfigDescriptionRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.config.core.ConfigDescriptionRegistry), initialReferenceFilter (objectClass=org.openhab.core.config.core.ConfigDescriptionRegistry)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.760 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ConfigDescriptionRegistry tracker reset (closed)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.760 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ConfigDescriptionRegistry tracking 1 SingleStatic added {org.openhab.core.config.core.ConfigDescriptionRegistry}={service.id=245, service.bundleid=166, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.config.core.ConfigDescriptionRegistry, component.id=114} (enter)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.760 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ConfigDescriptionRegistry tracking 1 SingleStatic active: false trackerOpened: false optional: false
/var/log/openhab/openhab.log:2024-05-17 07:57:11.761 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ConfigDescriptionRegistry tracking 1 SingleStatic added {org.openhab.core.config.core.ConfigDescriptionRegistry}={service.id=245, service.bundleid=166, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.config.core.ConfigDescriptionRegistry, component.id=114} (exit)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.761 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ConfigDescriptionRegistry tracker opened
/var/log/openhab/openhab.log:2024-05-17 07:57:11.761 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : registering service listener for dependency ConfigDescriptionRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.761 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : No change in target property for dependency ThingRegistry: currently registered: false
/var/log/openhab/openhab.log:2024-05-17 07:57:11.762 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] :  No existing service listener to unregister for dependency ThingRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.762 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Setting target property for dependency ThingRegistry to null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.762 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : New service tracker for ThingRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.ThingRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.ThingRegistry)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.763 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingRegistry tracker reset (closed)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.763 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingRegistry tracking 2 SingleStatic added {org.openhab.core.thing.ThingRegistry}={service.id=391, service.bundleid=219, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.internal.ThingRegistryImpl, component.id=265} (enter)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.763 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingRegistry tracking 2 SingleStatic active: false trackerOpened: false optional: false
/var/log/openhab/openhab.log:2024-05-17 07:57:11.764 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingRegistry tracking 2 SingleStatic added {org.openhab.core.thing.ThingRegistry}={service.id=391, service.bundleid=219, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.internal.ThingRegistryImpl, component.id=265} (exit)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.764 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingRegistry tracker opened
/var/log/openhab/openhab.log:2024-05-17 07:57:11.764 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : registering service listener for dependency ThingRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.764 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : No change in target property for dependency ThingTypeRegistry: currently registered: false
/var/log/openhab/openhab.log:2024-05-17 07:57:11.765 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] :  No existing service listener to unregister for dependency ThingTypeRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.765 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Setting target property for dependency ThingTypeRegistry to null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.765 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : New service tracker for ThingTypeRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.type.ThingTypeRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.type.ThingTypeRegistry)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.765 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingTypeRegistry tracker reset (closed)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.766 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingTypeRegistry tracking 3 SingleStatic added {org.openhab.core.thing.type.ThingTypeRegistry}={service.id=424, service.bundleid=219, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=281} (enter)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.766 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingTypeRegistry tracking 3 SingleStatic active: false trackerOpened: false optional: false
/var/log/openhab/openhab.log:2024-05-17 07:57:11.766 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingTypeRegistry tracking 3 SingleStatic added {org.openhab.core.thing.type.ThingTypeRegistry}={service.id=424, service.bundleid=219, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=281} (exit)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.767 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm ThingTypeRegistry tracker opened
/var/log/openhab/openhab.log:2024-05-17 07:57:11.767 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : registering service listener for dependency ThingTypeRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.767 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
/var/log/openhab/openhab.log:2024-05-17 07:57:11.767 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.768 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
/var/log/openhab/openhab.log:2024-05-17 07:57:11.768 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm osgi.ds.satisfying.condition tracker reset (closed)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.768 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm osgi.ds.satisfying.condition tracking 4 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.769 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm osgi.ds.satisfying.condition tracking 4 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.769 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : dm osgi.ds.satisfying.condition tracker opened
/var/log/openhab/openhab.log:2024-05-17 07:57:11.769 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : registering service listener for dependency osgi.ds.satisfying.condition
/var/log/openhab/openhab.log:2024-05-17 07:57:11.769 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Changed state from disabled to unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.770 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Component enabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.770 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : ActivateInternal
/var/log/openhab/openhab.log:2024-05-17 07:57:11.770 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.770 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.771 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Activating component from state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.771 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.771 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.771 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Changed state from unsatisfiedReference to satisfied
/var/log/openhab/openhab.log:2024-05-17 07:57:11.772 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : registration change queue [registered]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.774 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Checking constructor public org.openhab.binding.zwave.internal.ZWaveConfigProvider()
/var/log/openhab/openhab.log:2024-05-17 07:57:11.774 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Found constructor with 0 arguments : public org.openhab.binding.zwave.internal.ZWaveConfigProvider()
/var/log/openhab/openhab.log:2024-05-17 07:57:11.774 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getting bind: setConfigDescriptionRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.774 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Locating method setConfigDescriptionRegistry in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
/var/log/openhab/openhab.log:2024-05-17 07:57:11.775 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.776 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry([interface org.osgi.framework.ServiceReference]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.777 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry([interface org.osgi.service.component.ComponentServiceObjects]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.777 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.config.core.ConfigDescriptionRegistry through loader of org.openhab.binding.zwave.internal.ZWaveConfigProvider
/var/log/openhab/openhab.log:2024-05-17 07:57:11.777 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getClassFromComponentClassLoader: Found class org.openhab.core.config.core.ConfigDescriptionRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.778 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.config.core.ConfigDescriptionRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.778 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry(org.openhab.core.config.core.ConfigDescriptionRegistry)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.778 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry(org.openhab.core.config.core.ConfigDescriptionRegistry)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.779 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getting bind: setThingRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.779 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Locating method setThingRegistry in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
/var/log/openhab/openhab.log:2024-05-17 07:57:11.779 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.780 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry([interface org.osgi.framework.ServiceReference]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.780 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry([interface org.osgi.service.component.ComponentServiceObjects]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.780 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.thing.ThingRegistry through loader of org.openhab.binding.zwave.internal.ZWaveConfigProvider
/var/log/openhab/openhab.log:2024-05-17 07:57:11.780 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getClassFromComponentClassLoader: Found class org.openhab.core.thing.ThingRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.781 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.thing.ThingRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.781 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry(org.openhab.core.thing.ThingRegistry)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.781 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry(org.openhab.core.thing.ThingRegistry)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.781 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getting bind: setThingTypeRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.782 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Locating method setThingTypeRegistry in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
/var/log/openhab/openhab.log:2024-05-17 07:57:11.782 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.782 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry([interface org.osgi.framework.ServiceReference]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.782 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry([interface org.osgi.service.component.ComponentServiceObjects]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.783 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.thing.type.ThingTypeRegistry through loader of org.openhab.binding.zwave.internal.ZWaveConfigProvider
/var/log/openhab/openhab.log:2024-05-17 07:57:11.783 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getClassFromComponentClassLoader: Found class org.openhab.core.thing.type.ThingTypeRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.783 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.thing.type.ThingTypeRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.783 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry(org.openhab.core.thing.type.ThingTypeRegistry)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.784 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry(org.openhab.core.thing.type.ThingTypeRegistry)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.784 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : This thread collected dependencies
/var/log/openhab/openhab.log:2024-05-17 07:57:11.784 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getService (single component manager) dependencies collected.
/var/log/openhab/openhab.log:2024-05-17 07:57:11.785 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Querying state satisfied
/var/log/openhab/openhab.log:2024-05-17 07:57:11.785 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Querying state satisfied
/var/log/openhab/openhab.log:2024-05-17 07:57:11.785 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : For dependency ConfigDescriptionRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.config.core.ConfigDescriptionRegistry}={service.id=245, service.bundleid=166, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.config.core.ConfigDescriptionRegistry, component.id=114}] service: [org.openhab.core.config.core.ConfigDescriptionRegistry@551f9bd8]]]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.785 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : For dependency ThingRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.ThingRegistry}={service.id=391, service.bundleid=219, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.internal.ThingRegistryImpl, component.id=265}] service: [org.openhab.core.thing.internal.ThingRegistryImpl@51561f6b]]]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.786 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : For dependency ThingTypeRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.type.ThingTypeRegistry}={service.id=424, service.bundleid=219, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=281}] service: [org.openhab.core.thing.type.ThingTypeRegistry@2965a561]]]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.786 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.801 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : invoking bind: setConfigDescriptionRegistry: parameters [org.openhab.core.config.core.ConfigDescriptionRegistry]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.802 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : invoked bind: setConfigDescriptionRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.802 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : invoking bind: setThingRegistry: parameters [org.openhab.core.thing.internal.ThingRegistryImpl]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.802 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : invoked bind: setThingRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.803 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : invoking bind: setThingTypeRegistry: parameters [org.openhab.core.thing.type.ThingTypeRegistry]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.803 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : invoked bind: setThingTypeRegistry
/var/log/openhab/openhab.log:2024-05-17 07:57:11.803 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : getting activate: activate
/var/log/openhab/openhab.log:2024-05-17 07:57:11.803 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Locating method activate in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
/var/log/openhab/openhab.log:2024-05-17 07:57:11.804 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.activate([interface org.osgi.service.component.ComponentContext]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.804 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Locating method activate in class java.lang.Object
/var/log/openhab/openhab.log:2024-05-17 07:57:11.804 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.804 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : activate method [activate] not found, ignoring
/var/log/openhab/openhab.log:2024-05-17 07:57:11.805 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Set implementation object for component
/var/log/openhab/openhab.log:2024-05-17 07:57:11.805 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(369)] : Changed state from satisfied to active
/var/log/openhab/openhab.log:2024-05-17 07:57:11.806 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Dependency Manager created SerialPortManagerinterface=org.openhab.core.io.transport.serial.SerialPortManager, filter=null, policy=static, cardinality=1..1, bind=setSerialPortManager, unbind=unsetSerialPortManager, updated=null, field=null, field-option=null, collection-type=null, parameter=null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.806 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.806 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Component created: DS=DS13, implementation=org.openhab.binding.zwave.internal.ZWaveHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.zwave.internal.ZWaveHandlerFactory]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.806 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.807 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
/var/log/openhab/openhab.log:2024-05-17 07:57:11.807 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Querying state disabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.807 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Querying state disabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.807 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Component can not be activated since it is in state disabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.807 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Querying state disabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.808 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Updating target filters
/var/log/openhab/openhab.log:2024-05-17 07:57:11.808 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : No change in target property for dependency SerialPortManager: currently registered: false
/var/log/openhab/openhab.log:2024-05-17 07:57:11.808 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] :  No existing service listener to unregister for dependency SerialPortManager
/var/log/openhab/openhab.log:2024-05-17 07:57:11.808 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Setting target property for dependency SerialPortManager to null
/var/log/openhab/openhab.log:2024-05-17 07:57:11.809 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : New service tracker for SerialPortManager, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.transport.serial.SerialPortManager), initialReferenceFilter (objectClass=org.openhab.core.io.transport.serial.SerialPortManager)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.809 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : dm SerialPortManager tracker reset (closed)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.809 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : dm SerialPortManager tracker opened
/var/log/openhab/openhab.log:2024-05-17 07:57:11.809 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : registering service listener for dependency SerialPortManager
/var/log/openhab/openhab.log:2024-05-17 07:57:11.810 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
/var/log/openhab/openhab.log:2024-05-17 07:57:11.810 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.810 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
/var/log/openhab/openhab.log:2024-05-17 07:57:11.810 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : dm osgi.ds.satisfying.condition tracker reset (closed)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.811 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.811 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.811 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : dm osgi.ds.satisfying.condition tracker opened
/var/log/openhab/openhab.log:2024-05-17 07:57:11.811 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : registering service listener for dependency osgi.ds.satisfying.condition
/var/log/openhab/openhab.log:2024-05-17 07:57:11.812 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Changed state from disabled to unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.812 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Component enabled
/var/log/openhab/openhab.log:2024-05-17 07:57:11.812 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : ActivateInternal
/var/log/openhab/openhab.log:2024-05-17 07:57:11.812 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.812 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.813 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Activating component from state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.813 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.813 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.813 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Dependency not satisfied: SerialPortManager
/var/log/openhab/openhab.log:2024-05-17 07:57:11.814 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Not all dependencies satisfied, cannot activate
/var/log/openhab/openhab.log:2024-05-17 07:57:11.900 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : dm SerialPortManager tracking 2 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=574, service.bundleid=299, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=377} (enter)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.900 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : ActivateInternal
/var/log/openhab/openhab.log:2024-05-17 07:57:11.901 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.901 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.904 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Activating component from state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.904 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.905 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state unsatisfiedReference
/var/log/openhab/openhab.log:2024-05-17 07:57:11.905 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Changed state from unsatisfiedReference to satisfied
/var/log/openhab/openhab.log:2024-05-17 07:57:11.906 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : registration change queue [registered]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.917 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Checking constructor public org.openhab.binding.zwave.internal.ZWaveHandlerFactory()
/var/log/openhab/openhab.log:2024-05-17 07:57:11.918 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Found constructor with 0 arguments : public org.openhab.binding.zwave.internal.ZWaveHandlerFactory()
/var/log/openhab/openhab.log:2024-05-17 07:57:11.918 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : getting bind: setSerialPortManager
/var/log/openhab/openhab.log:2024-05-17 07:57:11.918 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Locating method setSerialPortManager in class org.openhab.binding.zwave.internal.ZWaveHandlerFactory
/var/log/openhab/openhab.log:2024-05-17 07:57:11.919 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager
/var/log/openhab/openhab.log:2024-05-17 07:57:11.919 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Declared Method org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager([interface org.osgi.framework.ServiceReference]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.919 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Declared Method org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager([interface org.osgi.service.component.ComponentServiceObjects]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.920 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.io.transport.serial.SerialPortManager through loader of org.openhab.binding.zwave.internal.ZWaveHandlerFactory
/var/log/openhab/openhab.log:2024-05-17 07:57:11.920 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : getClassFromComponentClassLoader: Found class org.openhab.core.io.transport.serial.SerialPortManager
/var/log/openhab/openhab.log:2024-05-17 07:57:11.920 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.io.transport.serial.SerialPortManager
/var/log/openhab/openhab.log:2024-05-17 07:57:11.920 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager(org.openhab.core.io.transport.serial.SerialPortManager)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.925 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager(org.openhab.core.io.transport.serial.SerialPortManager)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.929 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : This thread collected dependencies
/var/log/openhab/openhab.log:2024-05-17 07:57:11.929 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : getService (single component manager) dependencies collected.
/var/log/openhab/openhab.log:2024-05-17 07:57:11.929 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state satisfied
/var/log/openhab/openhab.log:2024-05-17 07:57:11.929 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Querying state satisfied
/var/log/openhab/openhab.log:2024-05-17 07:57:11.930 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : For dependency SerialPortManager, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.transport.serial.SerialPortManager}={service.id=574, service.bundleid=299, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=377}] service: [org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl@1e4848c3]]]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.930 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.930 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : invoking bind: setSerialPortManager: parameters [org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.930 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : invoked bind: setSerialPortManager
/var/log/openhab/openhab.log:2024-05-17 07:57:11.931 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : getting activate: activate
/var/log/openhab/openhab.log:2024-05-17 07:57:11.931 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Locating method activate in class org.openhab.binding.zwave.internal.ZWaveHandlerFactory
/var/log/openhab/openhab.log:2024-05-17 07:57:11.931 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Declared Method org.openhab.binding.zwave.internal.ZWaveHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
/var/log/openhab/openhab.log:2024-05-17 07:57:11.932 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
/var/log/openhab/openhab.log:2024-05-17 07:57:11.932 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
/var/log/openhab/openhab.log:2024-05-17 07:57:11.937 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
/var/log/openhab/openhab.log:2024-05-17 07:57:11.938 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : invoked activate: activate
/var/log/openhab/openhab.log:2024-05-17 07:57:11.938 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Set implementation object for component
/var/log/openhab/openhab.log:2024-05-17 07:57:11.938 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : Changed state from satisfied to active
/var/log/openhab/openhab.log:2024-05-17 07:57:11.940 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:4.1.2 (292)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(370)] : dm SerialPortManager tracking 2 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=574, service.bundleid=299, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=377} (exit)
/var/log/openhab/openhab.log:2024-05-17 07:57:14.913 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Creating ZWave discovery service for zwave:serial_zstick:512 with scan time of 60
/var/log/openhab/openhab.log:2024-05-17 07:57:14.914 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Active zwave:serial_zstick:512
/var/log/openhab/openhab.log:2024-05-17 07:57:14.914 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
/var/log/openhab/openhab.log:2024-05-17 07:57:14.923 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Initializing ZWave serial controller.
/var/log/openhab/openhab.log:2024-05-17 07:57:14.923 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Initializing ZWave Controller zwave:serial_zstick:512.
/var/log/openhab/openhab.log:2024-05-17 07:57:28.261 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - No bridgeUID found in getConfigDescription thing:zwave:serial_zstick:512
/var/log/openhab/openhab.log:2024-05-17 07:57:37.962 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - No bridgeUID found in getConfigDescription thing:zwave:serial_zstick:512
/var/log/openhab/openhab.log:2024-05-17 07:57:46.542 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - No bridgeUID found in getConfigDescription thing:zwave:serial_zstick:512

apella12 · May 17, 2024, 11:44am

I think that means you have an Aeotec Zstick 5 (I know of no other device that has a reset button and a colored light. There is a hardware problem with some versions of that device at least with Rpi4 regarding USB. I don’t know about almalinux 9 (also this:controller (it is a vmware vm)-is this using ser2net?, but with the behavior (logs seem to stop at a different time each try), it seems to me to be the most likely issue. As noted above, workaround is the intermediate USB 2.0 hub. You can google about the problem and other solutions. One recent OH post has a reference
Aeotec Zwave bridge can not be found - scanned, added manually - Setup, Configuration and Use / Beginners - openHAB Community

TeKilla · May 25, 2024, 9:41pm

i found no solution, anyway meanwhile i replaced the zwave stuff with hue motion and contact sensors and nous a1t plugs.

system · July 6, 2024, 1:42pm

This topic was automatically closed 41 days after the last reply. New replies are no longer allowed.