openHAB 3.4 Milestone discussion

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.

usambara · November 13, 2022, 9:09am

Changing the thing definition to UI solved the problem as expected.
Thanks!

Lolodomo · November 13, 2022, 9:12am

But a real solution has to be found, we cannot accept having bindings becoming incompatible with thing definition in config file.

AndrewFG · November 13, 2022, 7:59pm

Help!! I just used openhabian to upgrade my system from 3.4.M2 to 3.4.M4 but unfortunately although the upgrade completed successfully, it seems that OH is not actually running on my system anymore; command sudo systemctl start openhab.service produces no result, and the OH web server is not running. => Suggestions appreciated!

System is a Raspberry Pi4.

EDIT: (solved) a cache clean and a hard power cycle of the Pi solved it.

usambara · November 13, 2022, 8:56pm

What says systemctl status openhab?

mstormi · November 13, 2022, 9:29pm

Moderation reminder:
Please stay on topic and only discuss issues to affect everybody or many.
Open your own threads for anything else. Thank you.

AndrewFG · November 13, 2022, 9:54pm

It says the system is running.

AndrewFG · November 13, 2022, 10:16pm

It is definitely broken. I get this…

2022-11-13 22:09:52.696 [WARN ] [mon.registry.AbstractManagedProvider] - Could not update element with key systeminfo:computer:g24 in ManagedThingProvider, because it does not exists.

EDIT: … I get the above error when starting my system; however if I disable and re-enable the thing, then it goes online normally. (So @Mherwege you might just have a timing issue…)