openHAB 3.4 Milestone discussion

laursen · November 8, 2022, 7:42pm

Not sure if related, but after the upgrade I noticed this in my logs:

2022-11-06 20:45:50.413 [ERROR] [Events.Framework                    ] - FrameworkEvent ERROR
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.samsungtv [26]
  Unresolved requirement: Import-Package: org.openhab.core.config.discovery.upnp

        at org.eclipse.osgi.container.Module.start(Module.java:463) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel$2.run(ModuleContainer.java:1847) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor$1$1.execute(EquinoxContainerAdaptor.java:136) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1840) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1783) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1745) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1667) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:234) [org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:345) [org.eclipse.osgi-3.17.200.jar:?]
2022-11-06 20:45:50.616 [ERROR] [Events.Framework                    ] - FrameworkEvent ERROR
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.lgwebos [29]
  Unresolved requirement: Import-Package: org.openhab.core.config.discovery.upnp

        at org.eclipse.osgi.container.Module.start(Module.java:463) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel$2.run(ModuleContainer.java:1847) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor$1$1.execute(EquinoxContainerAdaptor.java:136) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1840) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1783) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1745) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1667) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1) ~[org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:234) [org.eclipse.osgi-3.17.200.jar:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:345) [org.eclipse.osgi-3.17.200.jar:?]

The two mentioned bindings are working, but I just now discovered that I have problems with the Harmony Hub binding being uninitialized. Debug logs shows it might have some relation to discovery:

2022-11-08 20:35:21.395 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Start Harmony Hub background discovery
2022-11-08 20:35:21.397 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Start Scan
2022-11-08 20:35:21.399 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2022-11-08 20:35:21.408 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 39271
2022-11-08 20:35:21.415 [TRACE] [discovery.HarmonyHubDiscoveryService] - Request packet sent to: 224.0.0.1 Interface: eth0
2022-11-08 20:35:21.417 [DEBUG] [discovery.HarmonyHubDiscoveryService] - bundle org.openhab.binding.harmonyhub:3.4.0.M4 (314)[org.openhab.binding.harmonyhub.internal.discovery.HarmonyHubDiscoveryService(487)] : invoked activate: activate
2022-11-08 20:35:21.419 [TRACE] [discovery.HarmonyHubDiscoveryService] - Request packet sent to: 255.255.255.255 Interface: eth0
2022-11-08 20:35:21.421 [DEBUG] [discovery.HarmonyHubDiscoveryService] - bundle org.openhab.binding.harmonyhub:3.4.0.M4 (314)[org.openhab.binding.harmonyhub.internal.discovery.HarmonyHubDiscoveryService(487)] : Set implementation object for comp>
2022-11-08 20:35:21.422 [TRACE] [discovery.HarmonyHubDiscoveryService] - Request packet sent to: 224.0.0.1 Interface: eth0
2022-11-08 20:35:21.424 [DEBUG] [discovery.HarmonyHubDiscoveryService] - bundle org.openhab.binding.harmonyhub:3.4.0.M4 (314)[org.openhab.binding.harmonyhub.internal.discovery.HarmonyHubDiscoveryService(487)] : Changed state from satisfied to ac>
2022-11-08 20:35:21.425 [TRACE] [discovery.HarmonyHubDiscoveryService] - Request packet sent to: 255.255.255.255 Interface: eth0
2022-11-08 20:35:21.434 [TRACE] [discovery.HarmonyHubDiscoveryService] - Request packet sent to: 192.168.0.255 Interface: eth0
2022-11-08 20:35:23.439 [TRACE] [discovery.HarmonyHubDiscoveryService] - Request packet sent to: 224.0.0.1 Interface: eth0

Just sharing in case this is a piece of some puzzle.

konfetti · November 8, 2022, 8:23pm

I give M4 yesterday a try and for me not a single ECMA-11 rule works. It looks like the injection does not work. Same with the current snapshots. Going back to OH 3.3 release all works fine.

2022-11-07 09:32:15.784 [ERROR] [.internal.OpenhabGraalJSScriptEngine] - Could not inject global script
javax.script.ScriptException: org.graalvm.polyglot.PolyglotException: SyntaxError: arraycopy: last destination index 45 out of bounds for byte[44]
 at com.oracle.truffle.js.scriptengine.GraalJSScriptEngine.toScriptException(GraalJSScriptEngine.java:503) ~[?:?]
 at com.oracle.truffle.js.scriptengine.GraalJSScriptEngine.eval(GraalJSScriptEngine.java:480) ~[?:?]
 at com.oracle.truffle.js.scriptengine.GraalJSScriptEngine.eval(GraalJSScriptEngine.java:446) ~[?:?]
 at javax.script.AbstractScriptEngine.eval(AbstractScriptEngine.java:264) ~[java.scripting:?]
 at org.openhab.automation.jsscripting.internal.scriptengine.DelegatingScriptEngineWithInvocableAndAutocloseable.eval(DelegatingScriptEngineWithInvocableAndAutocloseable.java:54) ~[?:?]
 at org.openhab.automation.jsscripting.internal.scriptengine.InvocationInterceptingScriptEngineWithInvocableAndAutoCloseable.eval(InvocationInterceptingScriptEngineWithInvocableAndAutoCloseable.java:69) ~[?:?]
 at org.openhab.automation.jsscripting.internal.OpenhabGraalJSScriptEngine.beforeInvocation(OpenhabGraalJSScriptEngine.java:218) ~[?:?]
 at org.openhab.automation.jsscripting.internal.scriptengine.InvocationInterceptingScriptEngineWithInvocableAndAutoCloseable.eval(InvocationInterceptingScriptEngineWithInvocableAndAutoCloseable.java:68) ~[?:?]
 at org.openhab.automation.jsscripting.internal.scriptengine.DelegatingScriptEngineWithInvocableAndAutocloseable.eval(DelegatingScriptEngineWithInvocableAndAutocloseable.java:54) ~[?:?]
 at org.openhab.automation.jsscripting.internal.scriptengine.InvocationInterceptingScriptEngineWithInvocableAndAutoCloseable.eval(InvocationInterceptingScriptEngineWithInvocableAndAutoCloseable.java:69) ~[?:?]
 at org.openhab.core.automation.module.script.internal.handler.ScriptActionHandler.lambda$0(ScriptActionHandler.java:58) ~[?:?]
 at java.util.Optional.ifPresent(Optional.java:183) [?:?]
 at org.openhab.core.automation.module.script.internal.handler.ScriptActionHandler.execute(ScriptActionHandler.java:55) [bundleFile:?]
 at org.openhab.core.automation.internal.RuleEngineImpl.executeActions(RuleEngineImpl.java:1181) [bundleFile:?]
 at org.openhab.core.automation.internal.RuleEngineImpl.runRule(RuleEngineImpl.java:989) [bundleFile:?]
 at org.openhab.core.automation.internal.TriggerHandlerCallbackImpl$TriggerData.run(TriggerHandlerCallbackImpl.java:89) [bundleFile:?]
 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
 at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
 at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
 at java.lang.Thread.run(Thread.java:829) [?:?]
Caused by: org.graalvm.polyglot.PolyglotException: SyntaxError: arraycopy: last destination index 45 out of bounds for byte[44]
 at com.oracle.truffle.polyglot.PolyglotMapAndFunction.apply(PolyglotMapAndFunction.java:46) ~[?:?]
 at org.openhab.automation.jsscripting.internal.OpenhabGraalJSScriptEngine.lambda$7(OpenhabGraalJSScriptEngine.java:208) ~[?:?]
 at java.util.Optional.orElseGet(Optional.java:369) ~[?:?]
 at org.openhab.automation.jsscripting.internal.OpenhabGraalJSScriptEngine.lambda$5(OpenhabGraalJSScriptEngine.java:208) ~[?:?]
 at <js>.:program(<eval>:1) ~[?:?]
 at org.graalvm.polyglot.Context.eval(Context.java:399) ~[?:?]
 at com.oracle.truffle.js.scriptengine.GraalJSScriptEngine.eval(GraalJSScriptEngine.java:478) ~[?:?]
 ... 20 more

Br,
konfetti

tnemrap · November 8, 2022, 8:42pm

I had to downgrade to M3 to get the ECMA 11 rules to work.

JustinG · November 8, 2022, 9:01pm

All of my ECMA 11 scripts and rules work just fine in M4 including those with events.itemName.

Did the rule actually run in response to an event or did you test the rule by running it manually? Manual runs will not get the event object.

I doubt this is an M4 specific issue. Maybe you could start a new thread with the full code of the rule that’s giving the error so the JSscripting guru’s can help you troubleshoot.

konfetti · November 8, 2022, 9:12pm

Yes I want to investigate a little bit when I’m back from business trip. But for me it sounds similar to the issue from tnemrap. The rules can be very simplified. Try to use items.getItem("myScenes").state;, event.itemName or just the line var logger = log(ruleName); leads for the first time to the injection error and then later to just something like “log” unknown …
Br,
konfetti

tnemrap · November 8, 2022, 9:20pm

Yes it was run in response of an event

When i downgraded to M3 everything work fine.

mark_leonard_tuil · November 8, 2022, 9:46pm

Facing a similar issue here. Almost no ecma scripts work after upgrading to M4. Works fine after downgrading. Working with fils here

Lolodomo · November 9, 2022, 11:51am

For JSscripting, there was a breaking change:

github.com/openhab/openhab-addons

[jsscripting] Reimplement timer polyfills to conform standard JS

openhab:main ← florian-h05:jsscripting-reimplement-timers

opened 02:50PM - 30 Oct 22 UTC

florian-h05

+240 -122

Fixes #13594. ## Description This PR reimplements the polyfills for the st…andard JS timers (`setTimeout` and `setInterval`) in the Java layer of the binding to conform standard JS. This brings several improvements: - Usage of `TimerImpl` from core is no longer required; instead the scheduler is accessed directly - Mapping of ScheduledFutures to unique Integers to conform standard JS that returns a `timeoutId` or `intervalId` when the according funtion is run. FYI: Standard JS shares the same pool of integers for both, the binding does therefore the same. - Scheduled jobs created by the polyfills are named by using the loggername, the type (`timeout` or `interval`) and the id (an Integer). - All scheduled jobs created by the polyfills are automatically cancelled when the rule is unloaded to avoid having unmanagable scheduled jobs running endless (until restart of openHAB). ## Breaking Changes - The polyfills do not return an openHAB Timer anymore, instead they return an interger id as standard JS does. - Other ways of scheduling timers (`createTimer`, `createTimerWithArgument`) are not recommended to be used anymore as they are not threadsafe. But it is still possible to access them via the `ScriptExecution` actions from core. - I propose to log a warning when someone tries to access those timer creation methods via the `ScriptExecution` action from the openhab-js library. Therefore, the polyfills for the standard JS timer methods should be the only way taken for creating timers in JS Scripting! ## Testing ```javascript const { rules, triggers } = require('openhab'); const Thread = Java.type('java.lang.Thread'); rules.JSRule({ name: 'Test Rule', triggers: triggers.ItemCommandTrigger('test_switch'), execute: (event) => { console.info('Rule 2 ran'); // console.loggerName = 'org.openhab.automation.js.1123'; let myVar = 'Hello world'; // Create timers for the same timeout to ensure that timers/scheduled jobs are threadsafe for (let i = 0; i < 15; i++) { setTimeout(() => { console.info('setTimeout ' + i + ' completed successfully with message: ' + myVar); Thread.sleep(500); }, 2000, myVar); } let id; if (true) { // Create interval to verify functionality of setInterval id = setInterval(() => { console.info('setInterval completed successfully'); }, 100, myVar, myVar); } if (true) { // Make a scheduled job fail to check whether naming jobs works setTimeout(() => { console.info(uninizializedVar); }, 1000); } if (true) { // Cancel interval with a timeout by using the intervalId setTimeout(() => { console.info('Attempting to clear setInterval...'); clearTimeout(id); }, 5000, myVar, myVar); } myVar = 'Hello mutation'; } }); ```

A note was added in future 3.4 release notes.

florian-h05 · November 9, 2022, 12:32pm

The breaking changes should not lead to the error messages that some experience (and some not).
The breaking change is, that setTimeout and setInterval now return an integer id instead of an openHAB Timer before.

I am not sure what the problem is, it has to do something with the globals script that is injected into the runtime. I‘ve changed that script in my PRs, but I have never experienced any issue during development.
The huge problem I see with this issue is, that for me everything is working fine, I cannot reproduce.

I have only experienced that error when I upgraded, but reinstalling the addon did the trick.

My platform information (Debian 64-bit Virtual Machine):

Linux smarthomevm 5.10.0-18-amd64 #1 SMP Debian 5.10.140-1 (2022-09-02) x86_64 GNU/Linux

openjdk 11.0.16 2022-07-19
OpenJDK Runtime Environment (build 11.0.16+8-post-Debian-1deb11u1)
OpenJDK 64-Bit Server VM (build 11.0.16+8-post-Debian-1deb11u1, mixed mode, sharing)

tnemrap · November 9, 2022, 3:18pm

Did a new upgrade to M4, uninstalled/installed JSScripting, still the same problem.
Downgraded to M3.

konfetti · November 9, 2022, 7:38pm

It looks like it works now for me on current snapshot (milestone not tested, but before it was on both M4 and snapshot). Maybe it is worth to mention that I’m on an raspi 4b with openhabian and default openJDK11 seems to be installed.
Steps I have done now:

Stop OH
Clean cache & tmp
Install Zulu 11 32 Bit
Reboot
Wait 10 Minutes

Now it works. I reverse also for double check to openJDK 11 and the problems start again …

florian-h05 · November 9, 2022, 9:58pm

@konfetti @tnemrap @JustinG @mark_leonard_tuil

Can we please continue the JS Scripting discussion at this thread: [JS Scripting] All scripts stop working when upgrading to 3.4.0.M4?

usambara · November 12, 2022, 2:26pm

Since my upgrade from M3 to M4 the systeminfo binding does not work anymore.
The thing is uninitialized with a HANDLER_MISSING_ERROR.

With M3 the binding reports:

2022-11-07 22:10:30.286 [DEBUG] [o.internal.handler.SysteminfoHandler] - Systeminfo implementation is instantiated!
2022-11-07 22:10:30.294 [DEBUG] [o.internal.handler.SysteminfoHandler] - Start reading Thing configuration.
2022-11-07 22:10:30.295 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for medium priority channels set to 60 s
2022-11-07 22:10:30.296 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for high priority channels set to 10 s
2022-11-07 22:10:30.477 [DEBUG] [o.internal.handler.SysteminfoHandler] - Properties updated!
2022-11-07 22:10:30.479 [DEBUG] [o.internal.handler.SysteminfoHandler] - Schedule high priority tasks at fixed rate 10 s.
2022-11-07 22:10:30.491 [DEBUG] [o.internal.handler.SysteminfoHandler] - Schedule medium priority tasks at fixed rate 60 s.
2022-11-07 22:10:30.493 [DEBUG] [o.internal.handler.SysteminfoHandler] - Schedule one time update for low priority tasks.

With M4 it looks different:

2022-11-12 14:39:09.523 [DEBUG] [o.internal.handler.SysteminfoHandler] - Systeminfo implementation is instantiated!
2022-11-12 14:39:09.524 [DEBUG] [o.internal.handler.SysteminfoHandler] - Start reading Thing configuration.
2022-11-12 14:39:09.525 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for medium priority channels set to 60 s
2022-11-12 14:39:09.526 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for high priority channels set to 10 s
2022-11-12 14:39:10.093 [DEBUG] [o.internal.handler.SysteminfoHandler] - Properties updated!
2022-11-12 14:39:10.097 [DEBUG] [o.internal.handler.SysteminfoHandler] - Creating additional channel groups

2022-11-12 14:39:10.741 [WARN ] [mon.registry.AbstractManagedProvider] - Could not update element with key systeminfo:computer:kopf in ManagedThingProvider, because it does not exists.`

These messages are new, not sure if related and where to look for the reason.

2022-11-12 14:39:00.490 [WARN ] [penhab.core.config.core.ConfigParser] - Conversion of value '0' with type 'class java.math.BigDecimal' to 'class java.lang.String' failed. Returning null
2022-11-12 14:39:00.569 [WARN ] [penhab.core.config.core.ConfigParser] - Conversion of value '0' with type 'class java.math.BigDecimal' to 'class java.lang.String' failed. Returning null
2022-11-12 14:39:00.577 [WARN ] [penhab.core.config.core.ConfigParser] - Conversion of value '0' with type 'class java.math.BigDecimal' to 'class java.lang.String' failed. Returning null
2022-11-12 14:39:00.584 [WARN ] [penhab.core.config.core.ConfigParser] - Conversion of value '0' with type 'class java.math.BigDecimal' to 'class java.lang.String' failed. Returning null
2022-11-12 14:39:00.587 [WARN ] [penhab.core.config.core.ConfigParser] - Conversion of value '0' with type 'class java.math.BigDecimal' to 'class java.lang.String' failed. Returning null
2022-11-12 14:39:00.591 [WARN ] [penhab.core.config.core.ConfigParser] - Conversion of value '0' with type 'class java.math.BigDecimal' to 'class java.lang.String' failed. Returning null
2022-11-12 14:39:00.594 [WARN ] [penhab.core.config.core.ConfigParser] - Conversion of value '0' with type 'class java.math.BigDecimal' to 'class java.lang.String' failed. Returning null
2022-11-12 14:39:00.664 [WARN ] [penhab.core.config.core.ConfigParser] - Conversion of value '0' with type 'class java.math.BigDecimal' to 'class java.lang.String' failed. Returning null
2022-11-12 14:39:00.668 [WARN ] [penhab.core.config.core.ConfigParser] - Conversion of value '0' with type 'class java.math.BigDecimal' to 'class java.lang.String' failed. Returning null

Lolodomo · November 12, 2022, 4:30pm

The only change was I believe this one:

github.com/openhab/openhab-addons

[systeminfo] dynamic channels

openhab:main ← mherwege:systeminfo_channels

opened 08:11PM - 17 Oct 22 UTC

mherwege

+975 -160

This PR enhances the systeminfo binding to expose all devices on the system when… multiple devices of the same type exist. So far, the binding only exposed the first of such devices. - These extra devices are exposed through dynamically created channels in dynamically created device groups. This will happen - for storage, drive, display, battery and network device groups. The new device groups will have an index appended (starting from 0). If no extra devices are detected, only the channel group without index will exist. - Extra fanspeed channels in the sensors group may also get created if the system has multiple fans. - Addition of a process channel group for the current process that is running OH. This makes monitoring the OH process easier, as the PID would change with a restart.

Mherwege · November 12, 2022, 4:55pm

The last Systeminfo DEBUG message is expected and part of extra initialization steps with the enhanced version. But there should be more lines following this as you have debug enabled. Could you provide a longer log?

usambara · November 12, 2022, 5:07pm

The log ends with the lines provided.

Here I provide a snippet which shows this. Below is a full log of the start.

2022-11-12 14:09:52.274 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SystemInfo tracking 2 SingleStatic added {org.openhab.binding.systeminfo.internal.model.SysteminfoInterface}={service.id=540, service.bundleid=286, service.scope=bundle, component.name=org.openhab.binding.systeminfo.internal.model.OSHISysteminfo, component.id=340} (exit)
2022-11-12 14:09:56.404 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - OSHISysteminfo service starts initializing
2022-11-12 14:09:57.846 [DEBUG] [o.internal.handler.SysteminfoHandler] - Systeminfo implementation is instantiated!
2022-11-12 14:09:57.857 [DEBUG] [o.internal.handler.SysteminfoHandler] - Start reading Thing configuration.
2022-11-12 14:09:57.858 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for medium priority channels set to 60 s
2022-11-12 14:09:57.859 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for high priority channels set to 10 s
2022-11-12 14:09:58.164 [DEBUG] [o.internal.handler.SysteminfoHandler] - Properties updated!
2022-11-12 14:09:58.196 [DEBUG] [o.internal.handler.SysteminfoHandler] - Creating additional channel groups
2022-11-12 14:15:35.699 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state active
2022-11-12 14:15:35.700 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Deactivating component
2022-11-12 14:15:35.700 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : registration change queue [unregistered]
2022-11-12 14:15:35.702 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state active
2022-11-12 14:15:35.702 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : getting deactivate: deactivate

Full log of start:

2022-11-12 14:38:58.681 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2022-11-12 14:38:58.685 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : BundleComponentActivator : ComponentHolder created.
2022-11-12 14:38:58.719 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService] : BundleComponentActivator : ComponentHolder created.
2022-11-12 14:38:58.722 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo] : BundleComponentActivator : ComponentHolder created.
2022-11-12 14:38:58.726 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : Dependency Manager created SystemInfointerface=org.openhab.binding.systeminfo.internal.model.SysteminfoInterface, filter=null, policy=static, cardinality=1..1, bind=bindSystemInfo, unbind=unbindSystemInfo, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2022-11-12 14:38:58.737 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : Dependency Manager created SysteminfoThingTypeProviderinterface=org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider, filter=null, policy=static, cardinality=1..1, bind=setSysteminfoThingTypeProvider, unbind=unsetSysteminfoThingTypeProvider, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2022-11-12 14:38:58.738 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : Component created: DS=DS13, implementation=org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.systeminfo]
2022-11-12 14:38:58.739 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2022-11-12 14:38:58.740 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : Component Properties: {}
2022-11-12 14:38:58.741 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : Querying state disabled
2022-11-12 14:38:58.741 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : Querying state disabled
2022-11-12 14:38:58.742 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : Component can not be activated since it is in state disabled
2022-11-12 14:38:58.743 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory] : Querying state disabled
2022-11-12 14:38:58.744 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Updating target filters
2022-11-12 14:38:58.744 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : No change in target property for dependency SystemInfo: currently registered: false
2022-11-12 14:38:58.745 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] :  No existing service listener to unregister for dependency SystemInfo
2022-11-12 14:38:58.746 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Setting target property for dependency SystemInfo to null
2022-11-12 14:38:58.797 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : New service tracker for SystemInfo, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.systeminfo.internal.model.SysteminfoInterface), initialReferenceFilter (objectClass=org.openhab.binding.systeminfo.internal.model.SysteminfoInterface)
2022-11-12 14:38:58.798 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SystemInfo tracker reset (closed)
2022-11-12 14:38:58.801 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SystemInfo tracker opened
2022-11-12 14:38:58.812 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : registering service listener for dependency SystemInfo
2022-11-12 14:38:58.813 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : No change in target property for dependency SysteminfoThingTypeProvider: currently registered: false
2022-11-12 14:38:58.819 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] :  No existing service listener to unregister for dependency SysteminfoThingTypeProvider
2022-11-12 14:38:58.846 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Setting target property for dependency SysteminfoThingTypeProvider to null
2022-11-12 14:38:58.847 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : New service tracker for SysteminfoThingTypeProvider, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider), initialReferenceFilter (objectClass=org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider)
2022-11-12 14:38:58.848 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SysteminfoThingTypeProvider tracker reset (closed)
2022-11-12 14:38:58.850 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SysteminfoThingTypeProvider tracker opened
2022-11-12 14:38:58.851 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : registering service listener for dependency SysteminfoThingTypeProvider
2022-11-12 14:38:58.852 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Changed state from disabled to unsatisfiedReference
2022-11-12 14:38:58.852 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Component enabled
2022-11-12 14:38:58.853 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : ActivateInternal
2022-11-12 14:38:58.854 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:38:58.855 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:38:58.856 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Activating component from state unsatisfiedReference
2022-11-12 14:38:58.856 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:38:58.977 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:38:58.979 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Dependency not satisfied: SystemInfo
2022-11-12 14:38:58.980 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Dependency not satisfied: SysteminfoThingTypeProvider
2022-11-12 14:38:58.997 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Not all dependencies satisfied, cannot activate
2022-11-12 14:38:58.998 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Dependency Manager created $000interface=org.openhab.core.thing.type.ThingTypeRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=0
2022-11-12 14:38:59.000 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Dependency Manager created $001interface=org.openhab.core.thing.type.ChannelGroupTypeRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=1
2022-11-12 14:38:59.001 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Dependency Manager created $002interface=org.openhab.core.thing.type.ChannelTypeRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=2
2022-11-12 14:38:59.014 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Component created: DS=DS14, implementation=org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider]
2022-11-12 14:38:59.015 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Component Services: scope=singleton, services=[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider, org.openhab.core.thing.binding.ThingTypeProvider]
2022-11-12 14:38:59.067 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Component Properties: {}
2022-11-12 14:38:59.068 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Querying state disabled
2022-11-12 14:38:59.069 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Querying state disabled
2022-11-12 14:38:59.070 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Component can not be activated since it is in state disabled
2022-11-12 14:38:59.070 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider] : Querying state disabled
2022-11-12 14:38:59.071 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Updating target filters
2022-11-12 14:38:59.072 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : No change in target property for dependency $000: currently registered: false
2022-11-12 14:38:59.073 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] :  No existing service listener to unregister for dependency $000
2022-11-12 14:38:59.074 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Setting target property for dependency $000 to null
2022-11-12 14:38:59.075 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.type.ThingTypeRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.type.ThingTypeRegistry)
2022-11-12 14:38:59.075 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $000 tracker reset (closed)
2022-11-12 14:38:59.137 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.thing.type.ThingTypeRegistry}={service.id=411, service.bundleid=211, service.scope=bundle, component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=269} (enter)
2022-11-12 14:38:59.138 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2022-11-12 14:38:59.139 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.thing.type.ThingTypeRegistry}={service.id=411, service.bundleid=211, service.scope=bundle, component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=269} (exit)
2022-11-12 14:38:59.140 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $000 tracker opened
2022-11-12 14:38:59.141 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : registering service listener for dependency $000
2022-11-12 14:38:59.142 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : No change in target property for dependency $001: currently registered: false
2022-11-12 14:38:59.143 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] :  No existing service listener to unregister for dependency $001
2022-11-12 14:38:59.143 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Setting target property for dependency $001 to null
2022-11-12 14:38:59.144 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.type.ChannelGroupTypeRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.type.ChannelGroupTypeRegistry)
2022-11-12 14:38:59.145 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $001 tracker reset (closed)
2022-11-12 14:38:59.228 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.thing.type.ChannelGroupTypeRegistry}={service.id=398, service.bundleid=211, service.scope=bundle, component.name=org.openhab.core.thing.type.ChannelGroupTypeRegistry, component.id=267} (enter)
2022-11-12 14:38:59.273 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2022-11-12 14:38:59.274 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.thing.type.ChannelGroupTypeRegistry}={service.id=398, service.bundleid=211, service.scope=bundle, component.name=org.openhab.core.thing.type.ChannelGroupTypeRegistry, component.id=267} (exit)
2022-11-12 14:38:59.275 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $001 tracker opened
2022-11-12 14:38:59.276 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : registering service listener for dependency $001
2022-11-12 14:38:59.358 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : No change in target property for dependency $002: currently registered: false
2022-11-12 14:38:59.360 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] :  No existing service listener to unregister for dependency $002
2022-11-12 14:38:59.361 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Setting target property for dependency $002 to null
2022-11-12 14:38:59.363 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : New service tracker for $002, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.type.ChannelTypeRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.type.ChannelTypeRegistry)
2022-11-12 14:38:59.364 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $002 tracker reset (closed)
2022-11-12 14:38:59.424 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.thing.type.ChannelTypeRegistry}={service.id=399, service.bundleid=211, service.scope=bundle, component.name=org.openhab.core.thing.type.ChannelTypeRegistry, component.id=268} (enter)
2022-11-12 14:38:59.425 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $002 tracking 3 SingleStatic active: false trackerOpened: false optional: false
2022-11-12 14:38:59.426 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.thing.type.ChannelTypeRegistry}={service.id=399, service.bundleid=211, service.scope=bundle, component.name=org.openhab.core.thing.type.ChannelTypeRegistry, component.id=268} (exit)
2022-11-12 14:38:59.437 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : dm $002 tracker opened
2022-11-12 14:38:59.439 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : registering service listener for dependency $002
2022-11-12 14:38:59.444 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Changed state from disabled to unsatisfiedReference
2022-11-12 14:38:59.446 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Component enabled
2022-11-12 14:38:59.462 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : ActivateInternal
2022-11-12 14:38:59.477 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.480 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.482 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Activating component from state unsatisfiedReference
2022-11-12 14:38:59.493 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.496 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.499 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Changed state from unsatisfiedReference to satisfied
2022-11-12 14:38:59.507 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : registration change queue [registered]
2022-11-12 14:38:59.516 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Checking constructor public org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(org.openhab.core.thing.type.ThingTypeRegistry,org.openhab.core.thing.type.ChannelGroupTypeRegistry,org.openhab.core.thing.type.ChannelTypeRegistry)
2022-11-12 14:38:59.575 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : getReferenceClass: Looking for interface class org.openhab.core.thing.type.ThingTypeRegistry through loader of org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider
2022-11-12 14:38:59.577 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : getParameterClass: Found class org.openhab.core.thing.type.ThingTypeRegistry
2022-11-12 14:38:59.578 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : getReferenceClass: Looking for interface class org.openhab.core.thing.type.ChannelGroupTypeRegistry through loader of org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider
2022-11-12 14:38:59.617 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : getParameterClass: Found class org.openhab.core.thing.type.ChannelGroupTypeRegistry
2022-11-12 14:38:59.618 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : getReferenceClass: Looking for interface class org.openhab.core.thing.type.ChannelTypeRegistry through loader of org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider
2022-11-12 14:38:59.619 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : getParameterClass: Found class org.openhab.core.thing.type.ChannelTypeRegistry
2022-11-12 14:38:59.620 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Found constructor with 3 arguments : public org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(org.openhab.core.thing.type.ThingTypeRegistry,org.openhab.core.thing.type.ChannelGroupTypeRegistry,org.openhab.core.thing.type.ChannelTypeRegistry)
2022-11-12 14:38:59.621 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : This thread collected dependencies
2022-11-12 14:38:59.622 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : getService (single component manager) dependencies collected.
2022-11-12 14:38:59.623 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Querying state satisfied
2022-11-12 14:38:59.624 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Querying state satisfied
2022-11-12 14:38:59.697 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.type.ThingTypeRegistry}={service.id=411, service.bundleid=211, service.scope=bundle, component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=269}] service: [null]]]
2022-11-12 14:38:59.699 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.type.ChannelGroupTypeRegistry}={service.id=398, service.bundleid=211, service.scope=bundle, component.name=org.openhab.core.thing.type.ChannelGroupTypeRegistry, component.id=267}] service: [null]]]
2022-11-12 14:38:59.701 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : For dependency $002, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.type.ChannelTypeRegistry}={service.id=399, service.bundleid=211, service.scope=bundle, component.name=org.openhab.core.thing.type.ChannelTypeRegistry, component.id=268}] service: [null]]]
2022-11-12 14:38:59.713 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : getting activate: activate
2022-11-12 14:38:59.714 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Locating method activate in class org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider
2022-11-12 14:38:59.716 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Declared Method org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-11-12 14:38:59.787 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Locating method activate in class java.lang.Object
2022-11-12 14:38:59.788 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-11-12 14:38:59.789 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : activate method [activate] not found, ignoring
2022-11-12 14:38:59.790 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Set implementation object for component
2022-11-12 14:38:59.791 [DEBUG] [internal.SysteminfoThingTypeProvider] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider(338)] : Changed state from satisfied to active
2022-11-12 14:38:59.792 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SysteminfoThingTypeProvider tracking 1 SingleStatic added {org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider, org.openhab.core.thing.binding.ThingTypeProvider}={service.id=538, service.bundleid=286, service.scope=bundle, component.name=org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider, component.id=338} (enter)
2022-11-12 14:38:59.793 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : ActivateInternal
2022-11-12 14:38:59.794 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.795 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.796 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Activating component from state unsatisfiedReference
2022-11-12 14:38:59.857 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.858 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.859 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Dependency not satisfied: SystemInfo
2022-11-12 14:38:59.860 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Not all dependencies satisfied, cannot activate
2022-11-12 14:38:59.861 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SysteminfoThingTypeProvider tracking 1 SingleStatic added {org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider, org.openhab.core.thing.binding.ThingTypeProvider}={service.id=538, service.bundleid=286, service.scope=bundle, component.name=org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider, component.id=338} (exit)
2022-11-12 14:38:59.862 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService] : Component created: DS=DS13, implementation=org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[discovery.systeminfo]
2022-11-12 14:38:59.863 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService] : Component Services: scope=singleton, services=[org.openhab.core.config.discovery.DiscoveryService]
2022-11-12 14:38:59.863 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService] : Component Properties: {}
2022-11-12 14:38:59.864 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService] : Querying state disabled
2022-11-12 14:38:59.865 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService] : Querying state disabled
2022-11-12 14:38:59.866 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService] : Component can not be activated since it is in state disabled
2022-11-12 14:38:59.867 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService] : Querying state disabled
2022-11-12 14:38:59.889 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Updating target filters
2022-11-12 14:38:59.889 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Changed state from disabled to unsatisfiedReference
2022-11-12 14:38:59.890 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Component enabled
2022-11-12 14:38:59.891 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : ActivateInternal
2022-11-12 14:38:59.892 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.893 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.894 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Activating component from state unsatisfiedReference
2022-11-12 14:38:59.894 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.895 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Querying state unsatisfiedReference
2022-11-12 14:38:59.896 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Changed state from unsatisfiedReference to satisfied
2022-11-12 14:38:59.907 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : registration change queue [registered]
2022-11-12 14:38:59.911 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Checking constructor public org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService()
2022-11-12 14:38:59.912 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Found constructor with 0 arguments : public org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService()
2022-11-12 14:38:59.913 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : This thread collected dependencies
2022-11-12 14:38:59.913 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : getService (single component manager) dependencies collected.
2022-11-12 14:38:59.914 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Querying state satisfied
2022-11-12 14:38:59.915 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Querying state satisfied
2022-11-12 14:39:00.060 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : getting activate: activate
2022-11-12 14:39:00.061 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Locating method activate in class org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService
2022-11-12 14:39:00.062 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Declared Method org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-11-12 14:39:00.063 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Locating method activate in class org.openhab.core.config.discovery.AbstractDiscoveryService
2022-11-12 14:39:00.064 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Declared Method org.openhab.core.config.discovery.AbstractDiscoveryService.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-11-12 14:39:00.127 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Found activate method: protected void org.openhab.core.config.discovery.AbstractDiscoveryService.activate(java.util.Map)
2022-11-12 14:39:00.127 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.helper.ReadOnlyDictionary]
2022-11-12 14:39:00.128 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : invoked activate: activate
2022-11-12 14:39:00.129 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Set implementation object for component
2022-11-12 14:39:00.130 [DEBUG] [discovery.SysteminfoDiscoveryService] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.discovery.SysteminfoDiscoveryService(339)] : Changed state from satisfied to active
2022-11-12 14:39:00.131 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo] : Component created: DS=DS13, implementation=org.openhab.binding.systeminfo.internal.model.OSHISysteminfo, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo]
2022-11-12 14:39:00.132 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo] : Component Services: scope=singleton, services=[org.openhab.binding.systeminfo.internal.model.SysteminfoInterface]
2022-11-12 14:39:00.133 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo] : Component Properties: {}
2022-11-12 14:39:00.134 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo] : Querying state disabled
2022-11-12 14:39:00.135 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo] : Querying state disabled
2022-11-12 14:39:00.135 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo] : Component can not be activated since it is in state disabled
2022-11-12 14:39:00.136 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo] : Querying state disabled
2022-11-12 14:39:00.145 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Updating target filters
2022-11-12 14:39:00.146 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Changed state from disabled to unsatisfiedReference
2022-11-12 14:39:00.147 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Component enabled
2022-11-12 14:39:00.148 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : ActivateInternal
2022-11-12 14:39:00.148 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Querying state unsatisfiedReference
2022-11-12 14:39:00.167 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Querying state unsatisfiedReference
2022-11-12 14:39:00.169 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Activating component from state unsatisfiedReference
2022-11-12 14:39:00.170 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Querying state unsatisfiedReference
2022-11-12 14:39:00.171 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Querying state unsatisfiedReference
2022-11-12 14:39:00.172 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Changed state from unsatisfiedReference to satisfied
2022-11-12 14:39:00.173 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : registration change queue [registered]
2022-11-12 14:39:00.176 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SystemInfo tracking 2 SingleStatic added {org.openhab.binding.systeminfo.internal.model.SysteminfoInterface}={service.id=542, service.bundleid=286, service.scope=bundle, component.name=org.openhab.binding.systeminfo.internal.model.OSHISysteminfo, component.id=340} (enter)
2022-11-12 14:39:00.201 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : ActivateInternal
2022-11-12 14:39:00.202 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:39:00.203 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:39:00.204 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Activating component from state unsatisfiedReference
2022-11-12 14:39:00.205 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:39:00.206 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state unsatisfiedReference
2022-11-12 14:39:00.237 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Changed state from unsatisfiedReference to satisfied
2022-11-12 14:39:00.238 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : registration change queue [registered]
2022-11-12 14:39:00.242 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Checking constructor public org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory()
2022-11-12 14:39:00.243 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Found constructor with 0 arguments : public org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory()
2022-11-12 14:39:00.244 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : getting bind: bindSystemInfo
2022-11-12 14:39:00.245 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Locating method bindSystemInfo in class org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory
2022-11-12 14:39:00.246 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : doFindMethod: Looking for method org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.bindSystemInfo
2022-11-12 14:39:00.265 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Declared Method org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.bindSystemInfo([interface org.osgi.framework.ServiceReference]) not found
2022-11-12 14:39:00.266 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Declared Method org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.bindSystemInfo([interface org.osgi.service.component.ComponentServiceObjects]) not found
2022-11-12 14:39:00.266 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : getReferenceClass: Looking for interface class org.openhab.binding.systeminfo.internal.model.SysteminfoInterface through loader of org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory
2022-11-12 14:39:00.268 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : getParameterClass: Found class org.openhab.binding.systeminfo.internal.model.SysteminfoInterface
2022-11-12 14:39:00.268 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.binding.systeminfo.internal.model.SysteminfoInterface
2022-11-12 14:39:00.269 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : doFindMethod: Found Method public void org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.bindSystemInfo(org.openhab.binding.systeminfo.internal.model.SysteminfoInterface)
2022-11-12 14:39:00.270 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Found bind method: public void org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.bindSystemInfo(org.openhab.binding.systeminfo.internal.model.SysteminfoInterface)
2022-11-12 14:39:00.312 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Checking constructor public org.openhab.binding.systeminfo.internal.model.OSHISysteminfo()
2022-11-12 14:39:00.313 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Found constructor with 0 arguments : public org.openhab.binding.systeminfo.internal.model.OSHISysteminfo()
2022-11-12 14:39:00.314 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : This thread collected dependencies
2022-11-12 14:39:00.315 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : getService (single component manager) dependencies collected.
2022-11-12 14:39:00.316 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Querying state satisfied
2022-11-12 14:39:00.317 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Querying state satisfied
2022-11-12 14:39:00.318 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - OSHISysteminfo service is created
2022-11-12 14:39:00.319 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : getting activate: activate
2022-11-12 14:39:00.320 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Locating method activate in class org.openhab.binding.systeminfo.internal.model.OSHISysteminfo
2022-11-12 14:39:00.323 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Declared Method org.openhab.binding.systeminfo.internal.model.OSHISysteminfo.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-11-12 14:39:00.324 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Locating method activate in class java.lang.Object
2022-11-12 14:39:00.325 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-11-12 14:39:00.326 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : activate method [activate] not found, ignoring
2022-11-12 14:39:00.332 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Set implementation object for component
2022-11-12 14:39:00.398 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.model.OSHISysteminfo(340)] : Changed state from satisfied to active
2022-11-12 14:39:00.399 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : getting bind: setSysteminfoThingTypeProvider
2022-11-12 14:39:00.400 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Locating method setSysteminfoThingTypeProvider in class org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory
2022-11-12 14:39:00.401 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : doFindMethod: Looking for method org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.setSysteminfoThingTypeProvider
2022-11-12 14:39:00.447 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Declared Method org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.setSysteminfoThingTypeProvider([interface org.osgi.framework.ServiceReference]) not found
2022-11-12 14:39:00.449 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Declared Method org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.setSysteminfoThingTypeProvider([interface org.osgi.service.component.ComponentServiceObjects]) not found
2022-11-12 14:39:00.450 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : getReferenceClass: Looking for interface class org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider through loader of org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory
2022-11-12 14:39:00.451 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : getParameterClass: Found class org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider
2022-11-12 14:39:00.452 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider
2022-11-12 14:39:00.452 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : doFindMethod: Found Method public void org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.setSysteminfoThingTypeProvider(org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider)
2022-11-12 14:39:00.453 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Found bind method: public void org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.setSysteminfoThingTypeProvider(org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider)
2022-11-12 14:39:00.454 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : This thread collected dependencies
2022-11-12 14:39:00.455 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : getService (single component manager) dependencies collected.
2022-11-12 14:39:00.456 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state satisfied
2022-11-12 14:39:00.456 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Querying state satisfied
2022-11-12 14:39:00.548 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : For dependency SystemInfo, optional: false; to bind: [[RefPair: ref: [{org.openhab.binding.systeminfo.internal.model.SysteminfoInterface}={service.id=542, service.bundleid=286, service.scope=bundle, component.name=org.openhab.binding.systeminfo.internal.model.OSHISysteminfo, component.id=340}] service: [org.openhab.binding.systeminfo.internal.model.OSHISysteminfo@f9c83d]]]
2022-11-12 14:39:00.572 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : For dependency SysteminfoThingTypeProvider, optional: false; to bind: [[RefPair: ref: [{org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider, org.openhab.core.thing.binding.ThingTypeProvider}={service.id=538, service.bundleid=286, service.scope=bundle, component.name=org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider, component.id=338}] service: [org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider@1fe943f]]]
2022-11-12 14:39:00.573 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : invoking bind: bindSystemInfo: parameters [org.openhab.binding.systeminfo.internal.model.OSHISysteminfo]
2022-11-12 14:39:00.574 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : invoked bind: bindSystemInfo
2022-11-12 14:39:00.575 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : invoking bind: setSysteminfoThingTypeProvider: parameters [org.openhab.binding.systeminfo.internal.SysteminfoThingTypeProvider]
2022-11-12 14:39:00.576 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : invoked bind: setSysteminfoThingTypeProvider
2022-11-12 14:39:00.577 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : getting activate: activate
2022-11-12 14:39:00.578 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Locating method activate in class org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory
2022-11-12 14:39:00.579 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Declared Method org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-11-12 14:39:00.647 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2022-11-12 14:39:00.648 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2022-11-12 14:39:00.649 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2022-11-12 14:39:00.652 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : invoked activate: activate
2022-11-12 14:39:00.653 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Set implementation object for component
2022-11-12 14:39:00.688 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : Changed state from satisfied to active
2022-11-12 14:39:00.727 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SystemInfo tracking 2 SingleStatic added {org.openhab.binding.systeminfo.internal.model.SysteminfoInterface}={service.id=542, service.bundleid=286, service.scope=bundle, component.name=org.openhab.binding.systeminfo.internal.model.OSHISysteminfo, component.id=340} (exit)
2022-11-12 14:39:05.472 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - OSHISysteminfo service starts initializing
2022-11-12 14:39:09.523 [DEBUG] [o.internal.handler.SysteminfoHandler] - Systeminfo implementation is instantiated!
2022-11-12 14:39:09.524 [DEBUG] [o.internal.handler.SysteminfoHandler] - Start reading Thing configuration.
2022-11-12 14:39:09.525 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for medium priority channels set to 60 s
2022-11-12 14:39:09.526 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for high priority channels set to 10 s
2022-11-12 14:39:10.093 [DEBUG] [o.internal.handler.SysteminfoHandler] - Properties updated!
2022-11-12 14:39:10.097 [DEBUG] [o.internal.handler.SysteminfoHandler] - Creating additional channel groups

Mherwege · November 12, 2022, 5:26pm

Can you switch to trace logging? There should be more info then. What platform are you on? What is the full thing status when it stalls? Does it still says initializing, or something else?
When extra channels get added, the thing should get recreated with a new thing type. So that would explain the deactivate messages. But it should come back with a new thing (same name) with more channels and continue initialization.

usambara · November 12, 2022, 5:36pm

The recreation might be the problem as I have the thing defined via file:

Thing  systeminfo:computer:kopf  "Server Kopf" @ "Flur EG"
[
  interval_high=10,
  interval_medium=60
]

The logs will take some time as I have to restart.
I’m on a current openhabian on a PI 4B

usambara · November 12, 2022, 5:47pm

the last lines and onlyones with TRACE

2022-11-12 18:40:29.071 [DEBUG] [fo.internal.SysteminfoHandlerFactory] - bundle org.openhab.binding.systeminfo:3.4.0.M4 (286)[org.openhab.binding.systeminfo.internal.SysteminfoHandlerFactory(337)] : dm SystemInfo tracking 2 SingleStatic added {org.openhab.binding.systeminfo.internal.model.SysteminfoInterface}={service.id=548, service.bundleid=286, service.scope=bundle, component.name=org.openhab.binding.systeminfo.internal.model.OSHISysteminfo, component.id=340} (exit)
2022-11-12 18:40:35.185 [TRACE] [internal.SysteminfoThingTypeProvider] - Creating thing type systeminfo:computer-kopf
2022-11-12 18:40:35.266 [TRACE] [internal.SysteminfoThingTypeProvider] - Adding channel group definitions to thing type
2022-11-12 18:40:35.859 [TRACE] [o.internal.handler.SysteminfoHandler] - Initializing thing kopf with thing type computer
2022-11-12 18:40:35.860 [TRACE] [o.internal.handler.SysteminfoHandler] - Restoring channel configurations
2022-11-12 18:40:35.862 [DEBUG] [eminfo.internal.model.OSHISysteminfo] - OSHISysteminfo service starts initializing
2022-11-12 18:40:37.894 [DEBUG] [o.internal.handler.SysteminfoHandler] - Systeminfo implementation is instantiated!
2022-11-12 18:40:37.910 [DEBUG] [o.internal.handler.SysteminfoHandler] - Start reading Thing configuration.
2022-11-12 18:40:37.911 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for medium priority channels set to 60 s
2022-11-12 18:40:37.920 [DEBUG] [o.internal.handler.SysteminfoHandler] - Refresh time for high priority channels set to 10 s
2022-11-12 18:40:38.219 [DEBUG] [o.internal.handler.SysteminfoHandler] - Properties updated!
2022-11-12 18:40:38.223 [TRACE] [o.internal.handler.SysteminfoHandler] - Adding channel group storage0
2022-11-12 18:40:38.224 [TRACE] [o.internal.handler.SysteminfoHandler] - Adding channel group storage1
2022-11-12 18:40:38.225 [TRACE] [o.internal.handler.SysteminfoHandler] - Adding channel group storage2
2022-11-12 18:40:38.281 [TRACE] [o.internal.handler.SysteminfoHandler] - Adding channel group storage3
2022-11-12 18:40:38.282 [TRACE] [o.internal.handler.SysteminfoHandler] - Adding channel group storage4
2022-11-12 18:40:38.284 [TRACE] [o.internal.handler.SysteminfoHandler] - Adding channel group storage5
2022-11-12 18:40:38.286 [TRACE] [o.internal.handler.SysteminfoHandler] - Adding channel group drive0
2022-11-12 18:40:38.374 [TRACE] [o.internal.handler.SysteminfoHandler] - Adding channel group drive1
2022-11-12 18:40:38.375 [DEBUG] [o.internal.handler.SysteminfoHandler] - Creating additional channel groups
2022-11-12 18:40:38.377 [TRACE] [internal.SysteminfoThingTypeProvider] - Adding channel group definitions to thing type
2022-11-12 18:40:38.378 [TRACE] [o.internal.handler.SysteminfoHandler] - Channel groups were added, changing the thing type
2022-11-12 18:40:38.380 [TRACE] [o.internal.handler.SysteminfoHandler] - Storing channel configurations

Mherwege · November 12, 2022, 7:08pm

Ok, the definition by file will be the problem. I overlooked that. I am not sure it will even be possible then to make the thing dynamic if defined by file. Notice it detected an extra drive and 5 extra storages and tries creating channels for it.
The best suggestion I have now is to replace your definition in the file by a UI definition. I will have a deeper look at it when I have more time. Feel free to log an issue in the addons repo.