Worx Landroid Binding

[Michael_Paier]

Hi,

so i can set the Zone Meters now, but with the new binding the robot does not react anymore when i send the command to “multi-zones#last-zone”

That was working in the version before…

Thank you

[RoRo (Roland Rosenfeld)]

I also observe this “An error occurred while calling method ‘ThingHandler.initialize()’ on ‘org.openhab.binding.worxlandroid.internal.WorxLandroidMowerHandler@519ff838’: multiple points” error with OpenHAB 3.4.4 and org.openhab.binding.worxlandroid-3.4.1.jar.
Seems that the available FW version of my mower changed from 3.52 to 3.52.0+1 according to the Android app.
Any idea how to fix/work around this with this old OH version?
Is there any patch for the source code (and some hint how to compile a single binding) or does the OH4 binding also run on OH3.3?
I didn’t want to upgrade to OH 4 at the moment to avoid new issues with other bindings…
(The landroid is my only cloud based device and all these issues tell me, that it was a bad idea to buy a cloud based device…)

[glhopital (Gaël L'hopital)]

@RoRo

the moment to avoid new issues with other bindings…
(The landroid is my only cloud based device and all these issues tell me, that it was a bad idea to buy a cloud based devic

Can you send me the error log ?

[RoRo (Roland Rosenfeld)]

@glhopital

Can you send me the error log ?

Sure, here’s what I get since I restarted OpenHAB yesterday:

2023-09-01 14:54:09.904 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.initialize()' on 'org.openhab.binding.worxlandroid.internal.WorxLandroidMowerHandler@687a125e': multiple points
java.lang.NumberFormatException: multiple points
        at jdk.internal.math.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1914) ~[?:?]
        at jdk.internal.math.FloatingDecimal.parseFloat(FloatingDecimal.java:122) ~[?:?]
        at java.lang.Float.parseFloat(Float.java:455) ~[?:?]
        at org.openhab.binding.worxlandroid.internal.WorxLandroidMowerHandler.initialize(WorxLandroidMowerHandler.java:227) ~[?:?]
        at jdk.internal.reflect.GeneratedMethodAccessor105.invoke(Unknown Source) ~[?:?]
        at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
        at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
        at org.openhab.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:154) [bundleFile:?]
        at org.openhab.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
        at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
        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) [?:?]
2023-09-01 14:54:09.910 [ERROR] [core.thing.internal.ThingManagerImpl] - Exception occurred while initializing handler of thing 'worxlandroid:mower:LawnieBridge:2018XXXXXXXXXXXXXX': multiple points
java.lang.NumberFormatException: multiple points
        at jdk.internal.math.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1914) ~[?:?]
        at jdk.internal.math.FloatingDecimal.parseFloat(FloatingDecimal.java:122) ~[?:?]
        at java.lang.Float.parseFloat(Float.java:455) ~[?:?]
        at org.openhab.binding.worxlandroid.internal.WorxLandroidMowerHandler.initialize(WorxLandroidMowerHandler.java:227) ~[?:?]
        at jdk.internal.reflect.GeneratedMethodAccessor105.invoke(Unknown Source) ~[?:?]
        at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
        at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
        at org.openhab.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:154) [bundleFile:?]
        at org.openhab.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
        at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
        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) [?:?]

Since I was looking for a quick workaround for this (it must work in the next weeks without changes), I just uninstalled the binding and started coding a substitute using GitHub - nefiertsrebliS/mqtt-landroid-bridge: Bridge for connecting the Worx (Kress or Landxcape) Lawn Mowers to IP-Symcon. and the MQTT binding.
After some coding this looks quite promising, at least the major items are working again including polling button and actions.

Maybe I’ll return to OpenHAB with the updated binding later, but for the moment this works for me…

[sihui]

@glhopital

Please, test this updated version

I have problems with this version on openHAB 4.0.2, mower is on 3.26.
Regardless which settings I use, the mower does not reconnect.
I tried Reconnect Interval with 0, 300, 599, none of them works.
Refresh Status Interval: 1200
Polling Interval: 3600

debug.log

2023-09-01 17:45:21.966 [ERROR] [worxlandroid.internal.mqtt.AWSClient] - Exception: software.amazon.awssdk.crt.mqtt.MqttException: The connection was closed unexpectedly.
2023-09-01 17:49:01.881 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Querying state active
2023-09-01 17:49:01.881 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Deactivating component
2023-09-01 17:49:01.881 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : registration change queue [unregistered]
2023-09-01 17:49:01.884 [DEBUG] [al.handler.WorxLandroidBridgeHandler] - unsubscribed from topic: PRM100/E6_my_mac_A8/commandOut
2023-09-01 17:49:01.887 [DEBUG] [worxlandroid.internal.mqtt.AWSClient] - connection interrupted errorcode: 0
2023-09-01 17:49:01.889 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Querying state active
2023-09-01 17:49:01.889 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : getting deactivate: deactivate
2023-09-01 17:49:01.890 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Locating method deactivate in class org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory
2023-09-01 17:49:01.890 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Declared Method org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-09-01 17:49:01.890 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Locating method deactivate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2023-09-01 17:49:01.890 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Found deactivate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.deactivate(org.osgi.service.component.ComponentContext)
2023-09-01 17:49:01.890 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : invoking deactivate: deactivate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2023-09-01 17:49:01.891 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : invoked deactivate: deactivate
2023-09-01 17:49:01.891 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : DependencyManager: osgi.ds.satisfying.condition close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@325282b5 at tracking count 2 refpairs: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2023-09-01 17:49:01.891 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : DependencyManager: $001 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@325282b5 at tracking count 3 refpairs: [[RefPair: ref: [{org.openhab.binding.worxlandroid.internal.api.WorxApiHandler}={service.id=1383, service.bundleid=237, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.worxlandroid.internal.api.WorxApiHandler, component.id=427}] service: [org.openhab.binding.worxlandroid.internal.api.WorxApiHandler@64555cf]]]
2023-09-01 17:49:01.891 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : DependencyManager: $000 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@325282b5 at tracking count 1 refpairs: [[RefPair: ref: [{org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=364, service.bundleid=156, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=70}] service: [org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl@6b5145ea]]]
2023-09-01 17:49:01.891 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Unset and deconfigured implementation object for component in deleteComponent for reason Unspecified
2023-09-01 17:49:01.891 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Changed state from active to satisfied
2023-09-01 17:49:01.893 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Deactivating dependency managers
2023-09-01 17:49:01.894 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Querying state active
2023-09-01 17:49:01.894 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : getting deactivate: deactivate
2023-09-01 17:49:01.894 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Locating method deactivate in class org.openhab.binding.worxlandroid.internal.api.WorxApiHandler
2023-09-01 17:49:01.894 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Declared Method org.openhab.binding.worxlandroid.internal.api.WorxApiHandler.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-09-01 17:49:01.894 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Locating method deactivate in class java.lang.Object
2023-09-01 17:49:01.894 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Declared Method java.lang.Object.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-09-01 17:49:01.895 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : deactivate method [deactivate] not found, ignoring
2023-09-01 17:49:01.895 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : DependencyManager: osgi.ds.satisfying.condition close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@11dae0e1 at tracking count 3 refpairs: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2023-09-01 17:49:01.895 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : DependencyManager: $001 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@11dae0e1 at tracking count 2 refpairs: [[RefPair: ref: [{org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer}={service.id=1382, service.bundleid=237, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer, component.id=426}] service: [org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer@37bb9f45]]]
2023-09-01 17:49:01.895 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : DependencyManager: $000 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@11dae0e1 at tracking count 1 refpairs: [[RefPair: ref: [{org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=286, service.bundleid=174, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=159}] service: [org.openhab.core.io.net.http.internal.WebClientFactoryImpl@1b7c4f50]]]
2023-09-01 17:49:01.895 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Unset and deconfigured implementation object for component in deleteComponent for reason Unspecified
2023-09-01 17:49:01.895 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Changed state from active to satisfied
2023-09-01 17:49:01.895 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Disabling dependency managers
2023-09-01 17:49:01.895 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : unregistering service listener for dependency $000
2023-09-01 17:49:01.896 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : unregistering service listener for dependency $001
2023-09-01 17:49:01.896 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : unregistering service listener for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.896 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Querying state satisfied
2023-09-01 17:49:01.896 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Changed state from satisfied to disposed
2023-09-01 17:49:01.896 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(424)] : Disposing component (reason: 6)
2023-09-01 17:49:01.896 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Querying state active
2023-09-01 17:49:01.896 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Deactivating component
2023-09-01 17:49:01.896 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : registration change queue [unregistered]
2023-09-01 17:49:01.897 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Querying state active
2023-09-01 17:49:01.897 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : getting deactivate: deactivate
2023-09-01 17:49:01.897 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Locating method deactivate in class org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider
2023-09-01 17:49:01.897 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Declared Method org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-09-01 17:49:01.897 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Locating method deactivate in class java.lang.Object
2023-09-01 17:49:01.897 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Declared Method java.lang.Object.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-09-01 17:49:01.898 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : deactivate method [deactivate] not found, ignoring
2023-09-01 17:49:01.898 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : DependencyManager: osgi.ds.satisfying.condition close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@7a6337c0 at tracking count 2 refpairs: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2023-09-01 17:49:01.898 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : DependencyManager: $001 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@7a6337c0 at tracking count 1 refpairs: [[RefPair: ref: [{org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=53.139726,7.616282, service.id=170, service.bundleid=150, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.internal.i18n.I18nProviderImpl, service.config.label=Regional Settings, measurementSystem=SI, component.id=22, timezone=Europe/Berlin, service.config.category=system, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]}] service: [org.openhab.core.internal.i18n.I18nProviderImpl@3cf81d85]]]
2023-09-01 17:49:01.898 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Unset and deconfigured implementation object for component in deleteComponent for reason Unspecified
2023-09-01 17:49:01.898 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Changed state from active to satisfied
2023-09-01 17:49:01.898 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Deactivating dependency managers
2023-09-01 17:49:01.901 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Disabling dependency managers
2023-09-01 17:49:01.901 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : unregistering service listener for dependency $001
2023-09-01 17:49:01.901 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : unregistering service listener for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.901 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Querying state satisfied
2023-09-01 17:49:01.901 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Changed state from satisfied to disposed
2023-09-01 17:49:01.901 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(425)] : Disposing component (reason: 6)
2023-09-01 17:49:01.902 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Querying state active
2023-09-01 17:49:01.902 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Deactivating component
2023-09-01 17:49:01.902 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : registration change queue [unregistered]
2023-09-01 17:49:01.903 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : dm $001 tracking 4 SingleStatic removed {org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer}={service.id=1382, service.bundleid=237, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer, component.id=426} (enter)
2023-09-01 17:49:01.903 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Querying state satisfied
2023-09-01 17:49:01.903 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Deactivating component
2023-09-01 17:49:01.903 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : registration change queue [unregistered]
2023-09-01 17:49:01.903 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Deactivating dependency managers
2023-09-01 17:49:01.903 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Querying state active
2023-09-01 17:49:01.904 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : getting deactivate: deactivate
2023-09-01 17:49:01.904 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Locating method deactivate in class org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer
2023-09-01 17:49:01.904 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Declared Method org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-09-01 17:49:01.904 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Locating method deactivate in class java.lang.Object
2023-09-01 17:49:01.904 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Declared Method java.lang.Object.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-09-01 17:49:01.904 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : deactivate method [deactivate] not found, ignoring
2023-09-01 17:49:01.904 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : DependencyManager: osgi.ds.satisfying.condition close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@57d2fce8 at tracking count 2 refpairs: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2023-09-01 17:49:01.905 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : DependencyManager: $000 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@57d2fce8 at tracking count 1 refpairs: [[RefPair: ref: [{org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=53.139726,7.616282, service.id=170, service.bundleid=150, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.internal.i18n.I18nProviderImpl, service.config.label=Regional Settings, measurementSystem=SI, component.id=22, timezone=Europe/Berlin, service.config.category=system, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]}] service: [org.openhab.core.internal.i18n.I18nProviderImpl@3cf81d85]]]
2023-09-01 17:49:01.905 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Unset and deconfigured implementation object for component in deleteComponent for reason Unspecified
2023-09-01 17:49:01.905 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Changed state from active to satisfied
2023-09-01 17:49:01.905 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Querying state satisfied
2023-09-01 17:49:01.905 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Changed state from satisfied to unsatisfiedReference
2023-09-01 17:49:01.905 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : dm $001 tracking 4 SingleStatic removed {org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer}={service.id=1382, service.bundleid=237, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer, component.id=426} (exit)
2023-09-01 17:49:01.905 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : ActivateInternal
2023-09-01 17:49:01.905 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.905 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Bundle's component activator is not active; not activating component
2023-09-01 17:49:01.906 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Deactivating dependency managers
2023-09-01 17:49:01.906 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Disabling dependency managers
2023-09-01 17:49:01.906 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : unregistering service listener for dependency $000
2023-09-01 17:49:01.906 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : unregistering service listener for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.906 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Querying state satisfied
2023-09-01 17:49:01.907 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Changed state from satisfied to disposed
2023-09-01 17:49:01.907 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(426)] : Disposing component (reason: 6)
2023-09-01 17:49:01.908 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.908 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Deactivating component
2023-09-01 17:49:01.908 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Already in desired state unregistered
2023-09-01 17:49:01.908 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Component deactivation occuring on another thread
2023-09-01 17:49:01.908 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Deactivating dependency managers
2023-09-01 17:49:01.908 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Disabling dependency managers
2023-09-01 17:49:01.909 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : unregistering service listener for dependency $000
2023-09-01 17:49:01.909 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : unregistering service listener for dependency $001
2023-09-01 17:49:01.909 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : unregistering service listener for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.910 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.911 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Changed state from unsatisfiedReference to disposed
2023-09-01 17:49:01.911 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(427)] : Disposing component (reason: 6)
2023-09-01 17:49:01.915 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2023-09-01 17:49:01.916 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : BundleComponentActivator : ComponentHolder created.
2023-09-01 17:49:01.916 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : BundleComponentActivator : ComponentHolder created.
2023-09-01 17:49:01.916 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : BundleComponentActivator : ComponentHolder created.
2023-09-01 17:49:01.917 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Dependency Manager created $000interface=org.openhab.core.auth.client.oauth2.OAuthFactory, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=0
2023-09-01 17:49:01.917 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Dependency Manager created $001interface=org.openhab.binding.worxlandroid.internal.api.WorxApiHandler, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=1
2023-09-01 17:49:01.917 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-09-01 17:49:01.918 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.worxlandroid]
2023-09-01 17:49:01.918 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2023-09-01 17:49:01.918 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2023-09-01 17:49:01.918 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Querying state disabled
2023-09-01 17:49:01.918 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Querying state disabled
2023-09-01 17:49:01.918 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Component can not be activated since it is in state disabled
2023-09-01 17:49:01.918 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory] : Querying state disabled
2023-09-01 17:49:01.918 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Updating target filters
2023-09-01 17:49:01.919 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : No change in target property for dependency $000: currently registered: false
2023-09-01 17:49:01.919 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] :  No existing service listener to unregister for dependency $000
2023-09-01 17:49:01.919 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Setting target property for dependency $000 to null
2023-09-01 17:49:01.919 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.auth.client.oauth2.OAuthFactory), initialReferenceFilter (objectClass=org.openhab.core.auth.client.oauth2.OAuthFactory)
2023-09-01 17:49:01.919 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm $000 tracker reset (closed)
2023-09-01 17:49:01.919 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=364, service.bundleid=156, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=70} (enter)
2023-09-01 17:49:01.919 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-09-01 17:49:01.919 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=364, service.bundleid=156, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=70} (exit)
2023-09-01 17:49:01.920 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm $000 tracker opened
2023-09-01 17:49:01.920 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : registering service listener for dependency $000
2023-09-01 17:49:01.920 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : No change in target property for dependency $001: currently registered: false
2023-09-01 17:49:01.920 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] :  No existing service listener to unregister for dependency $001
2023-09-01 17:49:01.920 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Setting target property for dependency $001 to null
2023-09-01 17:49:01.920 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.worxlandroid.internal.api.WorxApiHandler), initialReferenceFilter (objectClass=org.openhab.binding.worxlandroid.internal.api.WorxApiHandler)
2023-09-01 17:49:01.920 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm $001 tracker reset (closed)
2023-09-01 17:49:01.920 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm $001 tracker opened
2023-09-01 17:49:01.920 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : registering service listener for dependency $001
2023-09-01 17:49:01.921 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.921 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2023-09-01 17:49:01.921 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2023-09-01 17:49:01.921 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2023-09-01 17:49:01.921 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2023-09-01 17:49:01.921 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2023-09-01 17:49:01.921 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : dm osgi.ds.satisfying.condition tracker opened
2023-09-01 17:49:01.921 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : registering service listener for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.921 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Changed state from disabled to unsatisfiedReference
2023-09-01 17:49:01.922 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Component enabled
2023-09-01 17:49:01.922 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : ActivateInternal
2023-09-01 17:49:01.922 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.922 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.922 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Activating component from state unsatisfiedReference
2023-09-01 17:49:01.922 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.922 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.926 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Dependency not satisfied: $001
2023-09-01 17:49:01.928 [DEBUG] [.internal.WorxLandroidHandlerFactory] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidHandlerFactory(428)] : Not all dependencies satisfied, cannot activate
2023-09-01 17:49:01.929 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : Dependency Manager created $001interface=org.openhab.core.i18n.TranslationProvider, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=1
2023-09-01 17:49:01.929 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-09-01 17:49:01.930 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : Component created: DS=DS14, implementation=org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider]
2023-09-01 17:49:01.930 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : Component Services: scope=singleton, services=[org.openhab.core.ui.icon.IconProvider, org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider]
2023-09-01 17:49:01.930 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2023-09-01 17:49:01.930 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : Querying state disabled
2023-09-01 17:49:01.931 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : Querying state disabled
2023-09-01 17:49:01.931 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : Component can not be activated since it is in state disabled
2023-09-01 17:49:01.932 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider] : Querying state disabled
2023-09-01 17:49:01.932 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Updating target filters
2023-09-01 17:49:01.933 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : No change in target property for dependency $001: currently registered: false
2023-09-01 17:49:01.933 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] :  No existing service listener to unregister for dependency $001
2023-09-01 17:49:01.934 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Setting target property for dependency $001 to null
2023-09-01 17:49:01.934 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.i18n.TranslationProvider), initialReferenceFilter (objectClass=org.openhab.core.i18n.TranslationProvider)
2023-09-01 17:49:01.934 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : dm $001 tracker reset (closed)
2023-09-01 17:49:01.935 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : dm $001 tracking 1 SingleStatic added {org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=53.139726,7.616282, service.id=170, service.bundleid=150, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.internal.i18n.I18nProviderImpl, service.config.label=Regional Settings, measurementSystem=SI, component.id=22, timezone=Europe/Berlin, service.config.category=system, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]} (enter)
2023-09-01 17:49:01.935 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : dm $001 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-09-01 17:49:01.935 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : dm $001 tracking 1 SingleStatic added {org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=53.139726,7.616282, service.id=170, service.bundleid=150, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.internal.i18n.I18nProviderImpl, service.config.label=Regional Settings, measurementSystem=SI, component.id=22, timezone=Europe/Berlin, service.config.category=system, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]} (exit)
2023-09-01 17:49:01.936 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : dm $001 tracker opened
2023-09-01 17:49:01.936 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : registering service listener for dependency $001
2023-09-01 17:49:01.936 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.936 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2023-09-01 17:49:01.936 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2023-09-01 17:49:01.937 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2023-09-01 17:49:01.937 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2023-09-01 17:49:01.937 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2023-09-01 17:49:01.937 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : dm osgi.ds.satisfying.condition tracker opened
2023-09-01 17:49:01.938 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : registering service listener for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.938 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Changed state from disabled to unsatisfiedReference
2023-09-01 17:49:01.938 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Component enabled
2023-09-01 17:49:01.938 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : ActivateInternal
2023-09-01 17:49:01.938 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.939 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.939 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Activating component from state unsatisfiedReference
2023-09-01 17:49:01.939 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.939 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.940 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Changed state from unsatisfiedReference to satisfied
2023-09-01 17:49:01.940 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : registration change queue [registered]
2023-09-01 17:49:01.940 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Checking constructor public org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(org.osgi.framework.BundleContext,org.openhab.core.i18n.TranslationProvider)
2023-09-01 17:49:01.941 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.i18n.TranslationProvider through loader of org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider
2023-09-01 17:49:01.941 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : getClassFromComponentClassLoader: Found class org.openhab.core.i18n.TranslationProvider
2023-09-01 17:49:01.941 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Found constructor with 2 arguments : public org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(org.osgi.framework.BundleContext,org.openhab.core.i18n.TranslationProvider)
2023-09-01 17:49:01.942 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : This thread collected dependencies
2023-09-01 17:49:01.942 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : getService (single component manager) dependencies collected.
2023-09-01 17:49:01.942 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Querying state satisfied
2023-09-01 17:49:01.942 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Querying state satisfied
2023-09-01 17:49:01.942 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=53.139726,7.616282, service.id=170, service.bundleid=150, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.internal.i18n.I18nProviderImpl, service.config.label=Regional Settings, measurementSystem=SI, component.id=22, timezone=Europe/Berlin, service.config.category=system, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]}] service: [null]]]
2023-09-01 17:49:01.943 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2023-09-01 17:49:01.943 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : getting activate: activate
2023-09-01 17:49:01.943 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Locating method activate in class org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider
2023-09-01 17:49:01.943 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Declared Method org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-09-01 17:49:01.944 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Locating method activate in class java.lang.Object
2023-09-01 17:49:01.944 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-09-01 17:49:01.944 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : activate method [activate] not found, ignoring
2023-09-01 17:49:01.944 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Set implementation object for component
2023-09-01 17:49:01.944 [DEBUG] [id.internal.WorxLandroidIconProvider] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.WorxLandroidIconProvider(429)] : Changed state from satisfied to active
2023-09-01 17:49:01.945 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : Dependency Manager created $000interface=org.openhab.core.i18n.TimeZoneProvider, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=0
2023-09-01 17:49:01.945 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-09-01 17:49:01.945 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : Component created: DS=DS14, implementation=org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer]
2023-09-01 17:49:01.945 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : Component Services: scope=singleton, services=[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer]
2023-09-01 17:49:01.946 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2023-09-01 17:49:01.946 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : Querying state disabled
2023-09-01 17:49:01.946 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : Querying state disabled
2023-09-01 17:49:01.946 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : Component can not be activated since it is in state disabled
2023-09-01 17:49:01.947 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer] : Querying state disabled
2023-09-01 17:49:01.947 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Updating target filters
2023-09-01 17:49:01.947 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : No change in target property for dependency $000: currently registered: false
2023-09-01 17:49:01.947 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] :  No existing service listener to unregister for dependency $000
2023-09-01 17:49:01.947 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Setting target property for dependency $000 to null
2023-09-01 17:49:01.948 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.i18n.TimeZoneProvider), initialReferenceFilter (objectClass=org.openhab.core.i18n.TimeZoneProvider)
2023-09-01 17:49:01.948 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : dm $000 tracker reset (closed)
2023-09-01 17:49:01.948 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=53.139726,7.616282, service.id=170, service.bundleid=150, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.internal.i18n.I18nProviderImpl, service.config.label=Regional Settings, measurementSystem=SI, component.id=22, timezone=Europe/Berlin, service.config.category=system, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]} (enter)
2023-09-01 17:49:01.948 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-09-01 17:49:01.949 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=53.139726,7.616282, service.id=170, service.bundleid=150, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.internal.i18n.I18nProviderImpl, service.config.label=Regional Settings, measurementSystem=SI, component.id=22, timezone=Europe/Berlin, service.config.category=system, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]} (exit)
2023-09-01 17:49:01.949 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : dm $000 tracker opened
2023-09-01 17:49:01.949 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : registering service listener for dependency $000
2023-09-01 17:49:01.949 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.950 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2023-09-01 17:49:01.950 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2023-09-01 17:49:01.950 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2023-09-01 17:49:01.950 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2023-09-01 17:49:01.951 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2023-09-01 17:49:01.951 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : dm osgi.ds.satisfying.condition tracker opened
2023-09-01 17:49:01.951 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : registering service listener for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.951 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Changed state from disabled to unsatisfiedReference
2023-09-01 17:49:01.951 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Component enabled
2023-09-01 17:49:01.952 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : ActivateInternal
2023-09-01 17:49:01.952 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.952 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.952 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Activating component from state unsatisfiedReference
2023-09-01 17:49:01.953 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.953 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Querying state unsatisfiedReference
2023-09-01 17:49:01.953 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : Changed state from unsatisfiedReference to satisfied
2023-09-01 17:49:01.953 [DEBUG] [oid.internal.api.WorxApiDeserializer] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer(430)] : registration change queue [registered]
2023-09-01 17:49:01.954 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Dependency Manager created $000interface=org.openhab.core.io.net.http.HttpClientFactory, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=0
2023-09-01 17:49:01.954 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Dependency Manager created $001interface=org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=1
2023-09-01 17:49:01.954 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-09-01 17:49:01.954 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Component created: DS=DS14, implementation=org.openhab.binding.worxlandroid.internal.api.WorxApiHandler, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler]
2023-09-01 17:49:01.955 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Component Services: scope=singleton, services=[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler]
2023-09-01 17:49:01.955 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2023-09-01 17:49:01.955 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Querying state disabled
2023-09-01 17:49:01.955 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Querying state disabled
2023-09-01 17:49:01.956 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Component can not be activated since it is in state disabled
2023-09-01 17:49:01.956 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler] : Querying state disabled
2023-09-01 17:49:01.956 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : Updating target filters
2023-09-01 17:49:01.956 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : No change in target property for dependency $000: currently registered: false
2023-09-01 17:49:01.956 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] :  No existing service listener to unregister for dependency $000
2023-09-01 17:49:01.957 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : Setting target property for dependency $000 to null
2023-09-01 17:49:01.957 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.net.http.HttpClientFactory), initialReferenceFilter (objectClass=org.openhab.core.io.net.http.HttpClientFactory)
2023-09-01 17:49:01.957 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $000 tracker reset (closed)
2023-09-01 17:49:01.957 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=286, service.bundleid=174, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=159} (enter)
2023-09-01 17:49:01.958 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-09-01 17:49:01.958 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=286, service.bundleid=174, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=159} (exit)
2023-09-01 17:49:01.958 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $000 tracker opened
2023-09-01 17:49:01.958 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : registering service listener for dependency $000
2023-09-01 17:49:01.959 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : No change in target property for dependency $001: currently registered: false
2023-09-01 17:49:01.959 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] :  No existing service listener to unregister for dependency $001
2023-09-01 17:49:01.959 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : Setting target property for dependency $001 to null
2023-09-01 17:49:01.959 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer), initialReferenceFilter (objectClass=org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer)
2023-09-01 17:49:01.959 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $001 tracker reset (closed)
2023-09-01 17:49:01.960 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $001 tracking 2 SingleStatic added {org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer}={service.id=1392, service.bundleid=237, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer, component.id=430} (enter)
2023-09-01 17:49:01.960 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2023-09-01 17:49:01.960 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $001 tracking 2 SingleStatic added {org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer}={service.id=1392, service.bundleid=237, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.worxlandroid.internal.api.WorxApiDeserializer, component.id=430} (exit)
2023-09-01 17:49:01.960 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm $001 tracker opened
2023-09-01 17:49:01.961 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : registering service listener for dependency $001
2023-09-01 17:49:01.961 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2023-09-01 17:49:01.961 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2023-09-01 17:49:01.961 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2023-09-01 17:49:01.961 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2023-09-01 17:49:01.962 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm osgi.ds.satisfying.condition tracking 3 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2023-09-01 17:49:01.962 [DEBUG] [landroid.internal.api.WorxApiHandler] - bundle org.openhab.binding.worxlandroid:4.1.0.202308311430 (237)[org.openhab.binding.worxlandroid.internal.api.WorxApiHandler(431)] : dm osgi.ds.satisfying.condition tracking 3 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)

[cypresss (Thomas)]

Same here!

Since I installed this version, reconnect is not working anymore…

[glhopital (Gaël L'hopital)]

I would need the json that generates this error. Can you enable debug logging ?

[glhopital (Gaël L'hopital)]

I observe the same. Will investigate soon.

[BobMiles]

Maybe they changed the token lifetime? I tried refresh interval 300 and it seems to stay online since yesterday. However I think something might be still broken as I don’t get commands through…

[glhopital (Gaël L'hopital)]

No, I think I spotted the origin of the issue : I implement a mechanism to unsubscribe topics when disconnecting. This is fine when you’re connected, not when the connection has dropped
I launched a debug session to ensure this is works now.

[Josef]

Sure, this is the one I get by trying to enable the mower device:

java.lang.NullPointerException: Cannot invoke “java.lang.Enum.name()” because “value” is null

at org.openhab.binding.worxlandroid.internal.handler.WorxLandroidMowerHandler.updateChannelEnum(WorxLandroidMowerHandler.java:677) ~[?:?]

at org.openhab.binding.worxlandroid.internal.handler.WorxLandroidMowerHandler.lambda$13(WorxLandroidMowerHandler.java:554) ~[?:?]

at java.util.Optional.ifPresent(Optional.java:178) ~[?:?]

at org.openhab.binding.worxlandroid.internal.handler.WorxLandroidMowerHandler.updateStateCfg(WorxLandroidMowerHandler.java:552) ~[?:?]

at org.openhab.binding.worxlandroid.internal.handler.WorxLandroidMowerHandler.processStatusMessage(WorxLandroidMowerHandler.java:213) ~[?:?]

at org.openhab.binding.worxlandroid.internal.handler.WorxLandroidMowerHandler.initializeData(WorxLandroidMowerHandler.java:142) ~[?:?]

at org.openhab.binding.worxlandroid.internal.handler.WorxLandroidMowerHandler.initialize(WorxLandroidMowerHandler.java:109) ~[?:?]

at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) ~[?:?]

at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

at java.lang.reflect.Method.invoke(Method.java:568) ~[?:?]

at org.openhab.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:147) ~[?:?]

at org.openhab.core.internal.common.Invocation.call(Invocation.java:52) ~[?:?]

at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]

at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) ~[?:?]

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) ~[?:?]

at java.lang.Thread.run(Thread.java:833) ~[?:?]

[glhopital (Gaël L'hopital)]

@josef : your issue will be solved in the next version made available.
@Michael_Paier : this one will be adressed also. Solved.

[glhopital (Gaël L'hopital)]

Please test this version that must solve the three problems above (and especially disconnection).

[PeterS]

Hi @glhopital
Thanks for the new version, but at least with OH 4.0.2 I get

2023-09-04 15:53:57.241 [WARN ] [org.apache.felix.fileinstall        ] - Error while starting bundle: file:/openhab/addons/org.openhab.binding.worxlandroid-4.1.0-SNAPSHOT.jar
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.worxlandroid [301]
  Unresolved requirement: Import-Package: javax.measure; version="[2.2.0,3.0.0)"

        at org.eclipse.osgi.container.Module.start(Module.java:463) ~[org.eclipse.osgi-3.18.0.jar:?]
        at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:445) ~[org.eclipse.osgi-3.18.0.jar:?]
        at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) ~[?:?]
        at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) ~[?:?]
        at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:520) ~[?:?]
        at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) ~[?:?]
        at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) ~[?:?]

[cypresss (Thomas)]

same here

[Michael_Paier]

Hi @glhopital ,

same on my side

[Jiri_Kratochvil]

Hi @glhopital ,

same issue

thanks

[Josef]

Sounds prommising, thank you for your efforts in advance.

[Josef]

I can confim, the same error in my case too for the new version:

org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.worxlandroid [314]

Unresolved requirement: Import-Package: javax.measure; version=“[2.2.0,3.0.0)”

at org.eclipse.osgi.container.Module.start(Module.java:463) ~[org.eclipse.osgi-3.18.0.jar:?]

at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:445) ~[org.eclipse.osgi-3.18.0.jar:?]

at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) ~[?:?]

at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) ~[?:?]

at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:520) ~[?:?]

at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) ~[?:?]

at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) ~[?:?]

[glhopital (Gaël L'hopital)]

Same player shoot again. It’s gone unnoticed on my prod system because I’m on snapshot version. Try this, and cross fingers.