[ERROR] [mo.internal.handler.WemoMakerHandler] - Failed to get attributes for device 'wemo:Maker:ce808479f7'

Senna600 · February 26, 2022, 5:38pm

Hi,
I’m setting up a new openhab instance but I have issues to connect my wemo Maker which is connected to my garage door. This used to work well for several years on my previous openhab instance.
I have tried both via the UI and via file config.
in my things file:

wemo:Maker:Maker-1_0-221517S000XXXX "thegates" [udn="Maker-1_0-221517S000XXXX"]

definition of adding the thing via UI:

UID: wemo:Maker:ce808479f7
label: thegates
thingTypeUID: wemo:Maker
configuration:
  udn: Maker-1_0-221517S000XXXX

the udn I have copied over from my previous openhab instance.
But in the log there is always this:

2022-02-26 18:33:56.968 [DEBUG] [mo.internal.handler.WemoMakerHandler] - WeMoMakerHandler disposed.
2022-02-26 18:33:56.970 [DEBUG] [mo.internal.handler.WemoMakerHandler] - Initializing WemoMakerHandler for UDN 'Maker-1_0-221517S000XXXX'
2022-02-26 18:33:56.974 [ERROR] [mo.internal.handler.WemoMakerHandler] - Failed to get attributes for device 'wemo:Maker:ce808479f7'
java.lang.NullPointerException: null
	at org.openhab.binding.wemo.internal.WemoUtil.getWemoURL(WemoUtil.java:130) ~[bundleFile:?]
	at org.openhab.binding.wemo.internal.handler.WemoMakerHandler.updateWemoState(WemoMakerHandler.java:200) [bundleFile:?]
	at org.openhab.binding.wemo.internal.handler.WemoMakerHandler$1.run(WemoMakerHandler.java:76) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	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) [?:?]

The wemo is still functional over wifi via the wemo app on my phone.

Many thanks for any suggestions.

EDIT: forgot to mention: the things do show as online in the UI though.

hmerk · February 26, 2022, 5:48pm

We need some more information
OS/Java version/openHAB version.
Please enable TRACE log for wemo binding and post the logs here.

Senna600 · February 27, 2022, 9:33am

running the latest openhabian image which has openhab 3.2.0.
openjdk 11.0.14 2022-01-18
OpenJDK Runtime Environment (build 11.0.14+9-post-Raspbian-1deb11u1)
OpenJDK Server VM (build 11.0.14+9-post-Raspbian-1deb11u1, mixed mode)

But, an update: I noticed that today it is working. I have no idea what happened. In the log the error was repeatedly occuring untill 21:39:59. And then all of a sudden the error dispappeared and a dicovery was. I was not doing any “IT” related activities at that point.

2022-02-26 21:39:58.487 [ERROR] [mo.internal.handler.WemoMakerHandler] - Failed to get attributes for device 'wemo:Maker:12345678'
java.lang.NullPointerException: null
	at org.openhab.binding.wemo.internal.WemoUtil.getWemoURL(WemoUtil.java:130) ~[bundleFile:?]
	at org.openhab.binding.wemo.internal.handler.WemoMakerHandler.updateWemoState(WemoMakerHandler.java:200) [bundleFile:?]
	at org.openhab.binding.wemo.internal.handler.WemoMakerHandler$1.run(WemoMakerHandler.java:76) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	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) [?:?]
2022-02-26 21:40:15.016 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - Discovered a WeMo Maker thing with UDN 'Maker-1_0-221517S000XXXX'
2022-02-26 21:40:15.020 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - Created a DiscoveryResult for device 'thegates' with UDN 'Maker-1_0-221517S000XXXX'
2022-02-26 21:40:15.053 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'wemo:Maker:Maker-1_0-221517S000XXXX' to inbox.
2022-02-26 21:40:42.906 [TRACE] [mo.internal.handler.WemoMakerHandler] - Escaped Maker response for device 'wemo:Maker:ce808479f7' :
2022-02-26 21:40:42.909 [TRACE] [mo.internal.handler.WemoMakerHandler] - '&amp;lt;attribute&amp;gt;&amp;lt;name&amp;gt;Switch&amp;lt;/name&amp;gt;&amp;lt;value&amp;gt;0&amp;lt;/value&amp;gt;&amp;lt;/attribute&amp;gt;&amp;lt;attribute&amp;gt;&amp;lt;name&amp;gt;Sensor&amp;lt;/name&amp;gt;&amp;lt;value&amp;gt;1&amp;lt;/value&amp;gt;&amp;lt;/attribute&amp;gt;&amp;lt;attribute&amp;gt;&amp;lt;name&amp;gt;SwitchMode&amp;lt;/name&amp;gt;&amp;lt;value&amp;gt;1&amp;lt;/value&amp;gt;&amp;lt;/attribute&amp;gt;&amp;lt;attribute&amp;gt;&amp;lt;name&amp;gt;SensorPresent&amp;lt;/name&amp;gt;&amp;lt;value&amp;gt;0&amp;lt;/value&amp;gt;&amp;lt;/attribute&amp;gt;
'
2022-02-26 21:40:42.916 [TRACE] [mo.internal.handler.WemoMakerHandler] - Maker response '<attribute><name>Switch</name><value>0</value></attribute><attribute><name>Sensor</name><value>1</value></attribute><attribute><name>SwitchMode</name><value>1</value></attribute><attribute><name>SensorPresent</name><value>0</value></attribute>
' for device 'wemo:Maker:ce808479f7' received
2022-02-26 21:40:42.936 [TRACE] [mo.internal.handler.WemoMakerHandler] - attributeName: Switch
2022-02-26 21:40:42.939 [TRACE] [mo.internal.handler.WemoMakerHandler] - attributeValue: 0
2022-02-26 21:40:42.942 [DEBUG] [mo.internal.handler.WemoMakerHandler] - New relayState 'OFF' for device 'wemo:Maker:ce808479f7' received
2022-02-26 21:40:42.947 [TRACE] [mo.internal.handler.WemoMakerHandler] - attributeName: Sensor
2022-02-26 21:40:42.950 [TRACE] [mo.internal.handler.WemoMakerHandler] - attributeValue: 1

Senna600 · March 14, 2022, 4:15pm

Hi,
Since my previous post, the wemo maker has been running reliable in openhab.
But, today, I had to reboot the rpi and noticed the problem is back. Then tried unplugging the Wemo Maker. Shortly after that, openhab recognised it and it was fully controllable via openhab again.

I tried the procedure a few times and the behaviour was confirmed. Openhab does not recognise it until shortly after unplugging the wemo. So it looks like the reconnection of the wemo to the network does the trick.

But unplugging after each reboot or powercut, is not a stable solution. So, if possible in any way, I would like to get rid of the error. Any suggestion appreciated.

Below is the log during the boot. The ERROR at the end keeps repeating at regular intervals

2022-03-14 17:01:15.425 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2022-03-14 17:01:15.440 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant] : BundleComponentActivator : ComponentHolder created.
2022-03-14 17:01:15.454 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService] : BundleComponentActivator : ComponentHolder created.
2022-03-14 17:01:15.467 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : Dependency Manager created $000interface=org.openhab.core.io.transport.upnp.UpnpIOService, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=0
2022-03-14 17:01:15.475 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : Dependency Manager created WemoHttpCallFactoryinterface=org.openhab.binding.wemo.internal.WemoHttpCallFactory, filter=null, policy=dynamic, cardinality=0..1, bind=setWemoHttpCallFactory, unbind=unsetWemoHttpCallFactory, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2022-03-14 17:01:15.480 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.wemo.internal.WemoHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.wemo]
2022-03-14 17:01:15.486 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2022-03-14 17:01:15.492 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : Component Properties: {}
2022-03-14 17:01:15.497 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : Querying state disabled
2022-03-14 17:01:15.502 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : Querying state disabled
2022-03-14 17:01:15.511 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : Component can not be activated since it is in state disabled
2022-03-14 17:01:15.516 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory] : Querying state disabled
2022-03-14 17:01:15.532 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Updating target filters
2022-03-14 17:01:15.537 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : No change in target property for dependency $000: currently registered: false
2022-03-14 17:01:15.555 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] :  No existing service listener to unregister for dependency $000
2022-03-14 17:01:15.571 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Setting target property for dependency $000 to null
2022-03-14 17:01:15.586 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.transport.upnp.UpnpIOService), initialReferenceFilter (objectClass=org.openhab.core.io.transport.upnp.UpnpIOService)
2022-03-14 17:01:15.591 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : dm $000 tracker reset (closed)
2022-03-14 17:01:15.600 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : dm $000 tracker opened
2022-03-14 17:01:15.606 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : registering service listener for dependency $000
2022-03-14 17:01:15.612 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : No change in target property for dependency WemoHttpCallFactory: currently registered: false
2022-03-14 17:01:15.617 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] :  No existing service listener to unregister for dependency WemoHttpCallFactory
2022-03-14 17:01:15.633 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Setting target property for dependency WemoHttpCallFactory to null
2022-03-14 17:01:15.638 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : New service tracker for WemoHttpCallFactory, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.wemo.internal.WemoHttpCallFactory), initialReferenceFilter (objectClass=org.openhab.binding.wemo.internal.WemoHttpCallFactory)
2022-03-14 17:01:15.645 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : dm WemoHttpCallFactory tracker reset (closed)
2022-03-14 17:01:15.654 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : dm WemoHttpCallFactory tracker opened
2022-03-14 17:01:15.671 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : registering service listener for dependency WemoHttpCallFactory
2022-03-14 17:01:15.675 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Changed state from disabled to unsatisfiedReference
2022-03-14 17:01:15.695 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Component enabled
2022-03-14 17:01:15.710 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : ActivateInternal
2022-03-14 17:01:15.752 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state unsatisfiedReference
2022-03-14 17:01:15.757 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state unsatisfiedReference
2022-03-14 17:01:15.773 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Activating component from state unsatisfiedReference
2022-03-14 17:01:15.779 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state unsatisfiedReference
2022-03-14 17:01:15.785 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state unsatisfiedReference
2022-03-14 17:01:15.791 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Dependency not satisfied: $000
2022-03-14 17:01:15.797 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Not all dependencies satisfied, cannot activate
2022-03-14 17:01:15.803 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant] : Component created: DS=DS13, implementation=org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant]
2022-03-14 17:01:15.809 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant] : Component Services: scope=singleton, services=[org.openhab.core.config.discovery.upnp.UpnpDiscoveryParticipant]
2022-03-14 17:01:15.814 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant] : Component Properties: {}
2022-03-14 17:01:15.821 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant] : Querying state disabled
2022-03-14 17:01:15.827 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant] : Querying state disabled
2022-03-14 17:01:15.833 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant] : Component can not be activated since it is in state disabled
2022-03-14 17:01:15.838 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant] : Querying state disabled
2022-03-14 17:01:15.854 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Updating target filters
2022-03-14 17:01:15.870 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Changed state from disabled to unsatisfiedReference
2022-03-14 17:01:15.876 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Component enabled
2022-03-14 17:01:15.881 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : ActivateInternal
2022-03-14 17:01:15.888 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Querying state unsatisfiedReference
2022-03-14 17:01:15.894 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Querying state unsatisfiedReference
2022-03-14 17:01:15.900 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Activating component from state unsatisfiedReference
2022-03-14 17:01:15.915 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Querying state unsatisfiedReference
2022-03-14 17:01:15.934 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Querying state unsatisfiedReference
2022-03-14 17:01:15.938 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Changed state from unsatisfiedReference to satisfied
2022-03-14 17:01:15.963 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : registration change queue [registered]
2022-03-14 17:01:15.998 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService] : Dependency Manager created UpnpServiceinterface=org.jupnp.UpnpService, filter=null, policy=static, cardinality=1..1, bind=setUpnpService, unbind=unsetUpnpService, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2022-03-14 17:01:16.004 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService] : Component created: DS=DS13, implementation=org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[discovery.wemo]
2022-03-14 17:01:16.023 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService] : Component Services: scope=singleton, services=[org.openhab.core.config.discovery.DiscoveryService]
2022-03-14 17:01:16.036 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService] : Component Properties: {}
2022-03-14 17:01:16.044 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService] : Querying state disabled
2022-03-14 17:01:16.049 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService] : Querying state disabled
2022-03-14 17:01:16.055 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService] : Component can not be activated since it is in state disabled
2022-03-14 17:01:16.070 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService] : Querying state disabled
2022-03-14 17:01:16.097 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Updating target filters
2022-03-14 17:01:16.102 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : No change in target property for dependency UpnpService: currently registered: false
2022-03-14 17:01:16.108 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] :  No existing service listener to unregister for dependency UpnpService
2022-03-14 17:01:16.113 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Setting target property for dependency UpnpService to null
2022-03-14 17:01:16.119 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : New service tracker for UpnpService, initial active: false, previous references: {}, classFilter: (objectClass=org.jupnp.UpnpService), initialReferenceFilter (objectClass=org.jupnp.UpnpService)
2022-03-14 17:01:16.124 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : dm UpnpService tracker reset (closed)
2022-03-14 17:01:16.135 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : dm UpnpService tracking 1 SingleStatic added {org.jupnp.UpnpService}={service.id=502, service.bundleid=263, service.scope=bundle, component.name=org.jupnp.upnpservice, component.id=304} (enter)
2022-03-14 17:01:16.140 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : dm UpnpService tracking 1 SingleStatic active: false trackerOpened: false optional: false
2022-03-14 17:01:16.150 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : dm UpnpService tracking 1 SingleStatic added {org.jupnp.UpnpService}={service.id=502, service.bundleid=263, service.scope=bundle, component.name=org.jupnp.upnpservice, component.id=304} (exit)
2022-03-14 17:01:16.174 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : dm UpnpService tracker opened
2022-03-14 17:01:16.178 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : registering service listener for dependency UpnpService
2022-03-14 17:01:16.185 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Changed state from disabled to unsatisfiedReference
2022-03-14 17:01:16.190 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Component enabled
2022-03-14 17:01:16.195 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : ActivateInternal
2022-03-14 17:01:16.200 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Querying state unsatisfiedReference
2022-03-14 17:01:16.218 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Querying state unsatisfiedReference
2022-03-14 17:01:16.231 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Activating component from state unsatisfiedReference
2022-03-14 17:01:16.236 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Querying state unsatisfiedReference
2022-03-14 17:01:16.241 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Querying state unsatisfiedReference
2022-03-14 17:01:16.248 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Changed state from unsatisfiedReference to satisfied
2022-03-14 17:01:16.254 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : registration change queue [registered]
2022-03-14 17:01:16.269 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Checking constructor public org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService()
2022-03-14 17:01:16.274 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Found constructor with 0 arguments : public org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService()
2022-03-14 17:01:16.279 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : getting bind: setUpnpService
2022-03-14 17:01:16.284 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Locating method setUpnpService in class org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService
2022-03-14 17:01:16.289 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : doFindMethod: Looking for method org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService.setUpnpService
2022-03-14 17:01:16.297 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Declared Method org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService.setUpnpService([interface org.osgi.framework.ServiceReference]) not found
2022-03-14 17:01:16.303 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Declared Method org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService.setUpnpService([interface org.osgi.service.component.ComponentServiceObjects]) not found
2022-03-14 17:01:16.322 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : getReferenceClass: Looking for interface class org.jupnp.UpnpService through loader of org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService
2022-03-14 17:01:16.327 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : getParameterClass: Found class org.jupnp.UpnpService
2022-03-14 17:01:16.332 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : doFindMethod: No method taking ServiceReference found, checking method taking org.jupnp.UpnpService
2022-03-14 17:01:16.339 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : doFindMethod: Found Method protected void org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService.setUpnpService(org.jupnp.UpnpService)
2022-03-14 17:01:16.344 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Found bind method: protected void org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService.setUpnpService(org.jupnp.UpnpService)
2022-03-14 17:01:16.349 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : This thread collected dependencies
2022-03-14 17:01:16.354 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : getService (single component manager) dependencies collected.
2022-03-14 17:01:16.359 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Querying state satisfied
2022-03-14 17:01:16.364 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Querying state satisfied
2022-03-14 17:01:16.370 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : For dependency UpnpService, optional: false; to bind: [[RefPair: ref: [{org.jupnp.UpnpService}={service.id=502, service.bundleid=263, service.scope=bundle, component.name=org.jupnp.upnpservice, component.id=304}] service: [org.jupnp.UpnpServiceImpl@10d79cd]]]
2022-03-14 17:01:16.382 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : invoking bind: setUpnpService: parameters [org.jupnp.UpnpServiceImpl]
2022-03-14 17:01:16.387 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : invoked bind: setUpnpService
2022-03-14 17:01:16.392 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : getting activate: activate
2022-03-14 17:01:16.397 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Locating method activate in class org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService
2022-03-14 17:01:16.403 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Declared Method org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-03-14 17:01:16.408 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Found activate method: public void org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService.activate()
2022-03-14 17:01:16.412 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : invoking activate: activate: parameters []
2022-03-14 17:01:16.417 [DEBUG] [ernal.discovery.WemoDiscoveryService] - Starting WeMo UPnP discovery...
2022-03-14 17:01:16.422 [DEBUG] [ernal.discovery.WemoDiscoveryService] - Starting UPnP RootDevice search...
2022-03-14 17:01:16.433 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : invoked activate: activate
2022-03-14 17:01:16.438 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Set implementation object for component
2022-03-14 17:01:16.444 [DEBUG] [ernal.discovery.WemoDiscoveryService] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryService(309)] : Changed state from satisfied to active
2022-03-14 17:01:16.615 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Checking constructor public org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant()
2022-03-14 17:01:16.620 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Found constructor with 0 arguments : public org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant()
2022-03-14 17:01:16.653 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : This thread collected dependencies
2022-03-14 17:01:16.658 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : getService (single component manager) dependencies collected.
2022-03-14 17:01:16.781 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Querying state satisfied
2022-03-14 17:01:16.786 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Querying state satisfied
2022-03-14 17:01:16.827 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : getting activate: activate
2022-03-14 17:01:16.844 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Locating method activate in class org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant
2022-03-14 17:01:16.902 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Declared Method org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-03-14 17:01:16.907 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Locating method activate in class java.lang.Object
2022-03-14 17:01:16.922 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-03-14 17:01:16.927 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : activate method [activate] not found, ignoring
2022-03-14 17:01:16.955 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Set implementation object for component
2022-03-14 17:01:16.960 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.discovery.WemoDiscoveryParticipant(308)] : Changed state from satisfied to active
2022-03-14 17:01:17.295 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.transport.upnp.UpnpIOService, org.jupnp.registry.RegistryListener}={service.id=507, service.bundleid=266, service.scope=bundle, component.name=org.openhab.core.io.transport.upnp.internal.UpnpIOServiceImpl, component.id=311} (enter)
2022-03-14 17:01:17.300 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : ActivateInternal
2022-03-14 17:01:17.332 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state unsatisfiedReference
2022-03-14 17:01:17.337 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state unsatisfiedReference
2022-03-14 17:01:17.402 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Activating component from state unsatisfiedReference
2022-03-14 17:01:17.407 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state unsatisfiedReference
2022-03-14 17:01:17.442 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state unsatisfiedReference
2022-03-14 17:01:17.447 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Changed state from unsatisfiedReference to satisfied
2022-03-14 17:01:17.555 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : registration change queue [registered]
2022-03-14 17:01:17.590 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Checking constructor public org.openhab.binding.wemo.internal.WemoHandlerFactory(org.openhab.core.io.transport.upnp.UpnpIOService)
2022-03-14 17:01:17.675 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : getReferenceClass: Looking for interface class org.openhab.core.io.transport.upnp.UpnpIOService through loader of org.openhab.binding.wemo.internal.WemoHandlerFactory
2022-03-14 17:01:17.705 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : getParameterClass: Found class org.openhab.core.io.transport.upnp.UpnpIOService
2022-03-14 17:01:17.765 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Found constructor with 1 arguments : public org.openhab.binding.wemo.internal.WemoHandlerFactory(org.openhab.core.io.transport.upnp.UpnpIOService)
2022-03-14 17:01:17.884 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : This thread collected dependencies
2022-03-14 17:01:17.901 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : getService (single component manager) dependencies collected.
2022-03-14 17:01:17.905 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state satisfied
2022-03-14 17:01:17.909 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Querying state satisfied
2022-03-14 17:01:17.925 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.transport.upnp.UpnpIOService, org.jupnp.registry.RegistryListener}={service.id=507, service.bundleid=266, service.scope=bundle, component.name=org.openhab.core.io.transport.upnp.internal.UpnpIOServiceImpl, component.id=311}] service: [null]]]
2022-03-14 17:01:17.935 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : For dependency WemoHttpCallFactory, optional: true; to bind: []
2022-03-14 17:01:18.146 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : getting activate: activate
2022-03-14 17:01:18.151 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Locating method activate in class org.openhab.binding.wemo.internal.WemoHandlerFactory
2022-03-14 17:01:18.184 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Declared Method org.openhab.binding.wemo.internal.WemoHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-03-14 17:01:18.211 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2022-03-14 17:01:18.216 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2022-03-14 17:01:18.241 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2022-03-14 17:01:18.248 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : invoked activate: activate
2022-03-14 17:01:18.296 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Set implementation object for component
2022-03-14 17:01:18.338 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : Changed state from satisfied to active
2022-03-14 17:01:18.453 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - bundle org.openhab.binding.wemo:3.2.0 (264)[org.openhab.binding.wemo.internal.WemoHandlerFactory(307)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.transport.upnp.UpnpIOService, org.jupnp.registry.RegistryListener}={service.id=507, service.bundleid=266, service.scope=bundle, component.name=org.openhab.core.io.transport.upnp.internal.UpnpIOServiceImpl, component.id=311} (exit)
2022-03-14 17:01:18.774 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - Trying to create a handler for ThingType 'wemo:Maker
2022-03-14 17:01:18.801 [DEBUG] [ing.wemo.internal.WemoHandlerFactory] - Creating a WemoMakerHandler for thing 'wemo:Maker:123456789' with UDN 'Maker-1_0-221517S000++++'
2022-03-14 17:01:18.838 [DEBUG] [mo.internal.handler.WemoMakerHandler] - Creating a WemoMakerHandler for thing 'wemo:Maker:123456789'
2022-03-14 17:01:19.220 [DEBUG] [mo.internal.handler.WemoMakerHandler] - Initializing WemoMakerHandler for UDN 'Maker-1_0-221517S000++++'
2022-03-14 17:01:19.320 [ERROR] [mo.internal.handler.WemoMakerHandler] - Failed to get attributes for device 'wemo:Maker:123456789'
java.lang.NullPointerException: null
	at org.openhab.binding.wemo.internal.WemoUtil.getWemoURL(WemoUtil.java:130) ~[bundleFile:?]
	at org.openhab.binding.wemo.internal.handler.WemoMakerHandler.updateWemoState(WemoMakerHandler.java:200) [bundleFile:?]
	at org.openhab.binding.wemo.internal.handler.WemoMakerHandler$1.run(WemoMakerHandler.java:76) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	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) [?:?]

untill shortly after unplugging

2022-03-14 17:06:44.265 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - Discovered a WeMo Maker thing with UDN 'Maker-1_0-221517S000++++'
2022-03-14 17:06:44.269 [DEBUG] [l.discovery.WemoDiscoveryParticipant] - Created a DiscoveryResult for device 'thegates' with UDN 'Maker-1_0-221517S000++++'

hmerk · March 14, 2022, 5:38pm

Sorry, but I cannot reproduce this behaviour.
My maker is working reliable, even after a reboot of my openHAB server.
Difference I can see is me using Azul Zulu 11, not OpenJDK.