[velux] New OpenHAB2 binding - feedback welcome!

openhab2
binding
Tags: #<Tag:0x00007f15d40b5630> #<Tag:0x00007f15d40b54f0>

(Guenther Schreiner) #14

Hello Bert,

thank you for providing detailed log extracts. Is there any other log entry

for some of the velux activities before this error event?


(Bert Kaufmann) #15

Hi Guenther,

no, there isn’t.

To get a clear basis, I just shut down the container, cleared cache- and tmp-directories, deleted all logs and started again.

And the results of a …

# more openhab.log | grep velux

2018-05-31 12:36:16.962 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'velux.items'
2018-05-31 12:36:26.936 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'velux.sitemap'
2018-05-31 12:36:27.094 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'velux.things'
2018-05-31 12:36:28.352 [ERROR] [org.openhab.binding.velux           ] - [binding.velux] Cannot register Component
org.osgi.service.component.ComponentException: The component name 'binding.velux' has already been registered by Bundle 195 (org.openhab.binding.velux) as Component of Class org.openhab.binding.velux.internal.VeluxHandlerFactory
2018-05-31 12:36:28.403 [INFO ] [ing.velux.handler.VeluxBridgeHandler] - Initializing Velux veluxBridge handler for 'velux:klf200:home'.
2018-05-31 12:36:33.409 [WARN ] [ome.core.thing.internal.ThingManager] - Initializing handler for thing 'velux:klf200:home' takes more than 5000ms.

and a …

# more openhab.log | grep ERROR

2018-05-31 12:36:28.352 [ERROR] [org.openhab.binding.velux           ] - [binding.velux] Cannot register Component

The error details stay the same (with the difference, that bundle ID changed to 195, what’s because I flushed the caches)

2018-05-31 12:36:28.352 [ERROR] [org.openhab.binding.velux           ] - [binding.velux] Cannot register Component
org.osgi.service.component.ComponentException: The component name 'binding.velux' has already been registered by Bundle 195 (org.openhab.binding.velux) as Component of Class org.openhab.binding.velux.internal.VeluxHandlerFactory
        at org.apache.felix.scr.impl.ComponentRegistry.checkComponentName(ComponentRegistry.java:222) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.BundleComponentActivator.loadDescriptor(BundleComponentActivator.java:448) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.BundleComponentActivator.initialize(BundleComponentActivator.java:315) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.BundleComponentActivator.<init>(BundleComponentActivator.java:268) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.Activator.loadComponents(Activator.java:388) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.Activator.access$200(Activator.java:54) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.Activator$ScrExtension.start(Activator.java:265) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.utils.extender.AbstractExtender.createExtension(AbstractExtender.java:254) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.utils.extender.AbstractExtender.modifiedBundle(AbstractExtender.java:227) [39:org.apache.felix.scr:2.0.12]
        at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:482) [?:?]
        at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:415) [?:?]
        at org.osgi.util.tracker.AbstractTracked.track(AbstractTracked.java:232) [?:?]
        at org.osgi.util.tracker.BundleTracker$Tracked.bundleChanged(BundleTracker.java:444) [?:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:908) [?:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [?:?]
        at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [?:?]
        at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEventPrivileged(EquinoxEventPublisher.java:213) [?:?]
        at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:120) [?:?]
        at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:112) [?:?]
        at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor.publishModuleEvent(EquinoxContainerAdaptor.java:168) [?:?]
        at org.eclipse.osgi.container.Module.publishEvent(Module.java:476) [?:?]
        at org.eclipse.osgi.container.Module.start(Module.java:467) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1634) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1614) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1585) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1528) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1) [?:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [?:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340) [?:?]

If you could tell me how to raise log level I could perhaps try to send more information on that.


(Bert Kaufmann) #16

Hi there,

I found the solution for my problem. The binding is working now as expected. As I told before, my openHAB is running in a docker container. Giving this container a dedicated IP in my local network made the Velux gateway reachable for the binding now.

Before the container was running in a bridged network and can’t reach the KLN200-box, although I could ping the box out of the container.

BUT: The error message stays the same.

2018-05-31 14:57:06.414 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'velux.items'
2018-05-31 14:57:17.253 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'velux.sitemap'
2018-05-31 14:57:17.398 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'velux.things'
2018-05-31 14:57:18.890 [ERROR] [org.openhab.binding.velux           ] - [binding.velux] Cannot register Component
org.osgi.service.component.ComponentException: The component name 'binding.velux' has already been registered by Bundle 195 (org.openhab.binding.velux) as Component of Class org.openhab.binding.velux.internal.VeluxHandlerFactory
        at org.apache.felix.scr.impl.ComponentRegistry.checkComponentName(ComponentRegistry.java:222) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.BundleComponentActivator.loadDescriptor(BundleComponentActivator.java:448) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.BundleComponentActivator.initialize(BundleComponentActivator.java:315) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.BundleComponentActivator.<init>(BundleComponentActivator.java:268) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.Activator.loadComponents(Activator.java:388) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.Activator.access$200(Activator.java:54) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.scr.impl.Activator$ScrExtension.start(Activator.java:265) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.utils.extender.AbstractExtender.createExtension(AbstractExtender.java:254) [39:org.apache.felix.scr:2.0.12]
        at org.apache.felix.utils.extender.AbstractExtender.modifiedBundle(AbstractExtender.java:227) [39:org.apache.felix.scr:2.0.12]
        at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:482) [?:?]
        at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:415) [?:?]
        at org.osgi.util.tracker.AbstractTracked.track(AbstractTracked.java:232) [?:?]
        at org.osgi.util.tracker.BundleTracker$Tracked.bundleChanged(BundleTracker.java:444) [?:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:908) [?:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [?:?]
        at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [?:?]
        at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEventPrivileged(EquinoxEventPublisher.java:213) [?:?]
        at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:120) [?:?]
        at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:112) [?:?]
        at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor.publishModuleEvent(EquinoxContainerAdaptor.java:168) [?:?]
        at org.eclipse.osgi.container.Module.publishEvent(Module.java:476) [?:?]
        at org.eclipse.osgi.container.Module.start(Module.java:467) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1634) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1614) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1585) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1528) [?:?]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1) [?:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [?:?]
        at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340) [?:?]
2018-05-31 14:57:18.988 [INFO ] [ing.velux.handler.VeluxBridgeHandler] - Initializing Velux veluxBridge handler for 'velux:klf200:home'.
2018-05-31 14:57:23.992 [WARN ] [ome.core.thing.internal.ThingManager] - Initializing handler for thing 'velux:klf200:home' takes more than 5000ms.
2018-05-31 15:14:00.773 [INFO ] [ding.velux.bridge.VeluxBridgeExecute] - execute() finished successfully.
20

No offense! My windows open an close now. Guenther, I’m happy with your binding. Thanks for your support!

Cheers,
Bert


(Mike Bordignon) #17

I am also seeing this error (and have been for months), which seems to indicate to me this is a bug.

2018-06-07 20:38:30.879 [ERROR] [org.openhab.binding.velux           ] - [binding.velux] Cannot register Component
org.osgi.service.component.ComponentException: The component name 'binding.velux' has already been registered by Bundle 189 (org.openhab.binding.velux) as Component of Class org.openhab.binding.velux.internal.VeluxHandlerFactory
	at org.apache.felix.scr.impl.ComponentRegistry.checkComponentName(ComponentRegistry.java:222) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.loadDescriptor(BundleComponentActivator.java:448) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.initialize(BundleComponentActivator.java:315) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.<init>(BundleComponentActivator.java:268) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator.loadComponents(Activator.java:388) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator.access$200(Activator.java:54) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator$ScrExtension.start(Activator.java:265) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.utils.extender.AbstractExtender.createExtension(AbstractExtender.java:254) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.utils.extender.AbstractExtender.modifiedBundle(AbstractExtender.java:227) [39:org.apache.felix.scr:2.0.12]
	at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:482) [?:?]
	at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:415) [?:?]
	at org.osgi.util.tracker.AbstractTracked.track(AbstractTracked.java:232) [?:?]
	at org.osgi.util.tracker.BundleTracker$Tracked.bundleChanged(BundleTracker.java:444) [?:?]
	at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:908) [?:?]
	at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [?:?]
	at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEventPrivileged(EquinoxEventPublisher.java:213) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:120) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:112) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor.publishModuleEvent(EquinoxContainerAdaptor.java:168) [?:?]
	at org.eclipse.osgi.container.Module.publishEvent(Module.java:476) [?:?]
	at org.eclipse.osgi.container.Module.start(Module.java:467) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) [?:?]
	at org.apache.karaf.bundle.command.Start.executeOnBundle(Start.java:38) [43:org.apache.karaf.bundle.core:4.1.5]
	at org.apache.karaf.bundle.command.BundlesCommand.doExecute(BundlesCommand.java:62) [43:org.apache.karaf.bundle.core:4.1.5]
	at org.apache.karaf.bundle.command.BundlesCommand.execute(BundlesCommand.java:52) [43:org.apache.karaf.bundle.core:4.1.5]
	at org.apache.karaf.shell.impl.action.command.ActionCommand.execute(ActionCommand.java:84) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.karaf.shell.impl.console.osgi.secured.SecuredCommand.execute(SecuredCommand.java:68) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.karaf.shell.impl.console.osgi.secured.SecuredCommand.execute(SecuredCommand.java:86) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Closure.executeCmd(Closure.java:571) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Closure.executeStatement(Closure.java:497) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Closure.execute(Closure.java:386) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Pipe.doCall(Pipe.java:417) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Pipe.call(Pipe.java:229) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Pipe.call(Pipe.java:59) [12:org.apache.karaf.shell.core:4.1.5]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]

(Andrea Riela) #18

Sorry guys, I’m planning to implement te binding as I have 5 velux managed by a KLR-200 each.

How KLF200 works? Is there a way to merge each KLR-200 configuration in the gateway? The gateway will work as a remote, so reaching the velux via wireless and same way the IP network?

No wired infrastructure required, right?

Thanks
Andrea


(Stefan) #19

Good Morning,

after some testing for:

I also try to update my Binding from 2.2.0 to 2.3.0.
My openhab is runnig on 2.3.0 stable. So why not to try the binding for 2.3.0???

The old binding (2.2.0) was running perfect, but I tried to update.

After uninstall 2.2.0 and install 2.3.0 I got the following error:

2018-06-15 08:04:29.587 [ERROR] [org.openhab.binding.velux           ] - [binding.velux] Cannot register Component
org.osgi.service.component.ComponentException: The component name 'binding.velux' has already been registered by Bundle 233 (org.openhab.binding.velux) as Component of Class org.openhab.binding.velux.internal.VeluxHandlerFactory
	at org.apache.felix.scr.impl.ComponentRegistry.checkComponentName(ComponentRegistry.java:222) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.loadDescriptor(BundleComponentActivator.java:448) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.initialize(BundleComponentActivator.java:315) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.<init>(BundleComponentActivator.java:268) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator.loadComponents(Activator.java:388) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator.access$200(Activator.java:54) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator$ScrExtension.start(Activator.java:265) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.utils.extender.AbstractExtender.createExtension(AbstractExtender.java:254) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.utils.extender.AbstractExtender.modifiedBundle(AbstractExtender.java:227) [39:org.apache.felix.scr:2.0.12]
	at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:482) [?:?]
	at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:415) [?:?]
	at org.osgi.util.tracker.AbstractTracked.track(AbstractTracked.java:232) [?:?]
	at org.osgi.util.tracker.BundleTracker$Tracked.bundleChanged(BundleTracker.java:444) [?:?]
	at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:908) [?:?]
	at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [?:?]
	at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEventPrivileged(EquinoxEventPublisher.java:213) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:120) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:112) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor.publishModuleEvent(EquinoxContainerAdaptor.java:168) [?:?]
	at org.eclipse.osgi.container.Module.publishEvent(Module.java:476) [?:?]
	at org.eclipse.osgi.container.Module.start(Module.java:467) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) [?:?]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) [9:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) [9:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:520) [9:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [9:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [9:org.apache.felix.fileinstall:3.6.4]
2018-06-15 08:08:11.430 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.dispose()' on 'org.openhab.binding.velux.handler.VeluxBridgeHandler@339383': null
java.lang.NullPointerException: null
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeLogin(VeluxBridgeHandler.java:344) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeCommunicate(VeluxBridgeHandler.java:455) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeLogout(VeluxBridgeHandler.java:367) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.dispose(VeluxBridgeHandler.java:160) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2018-06-15 08:08:11.437 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occurred while disposing handler of thing 'velux:klf200:home': null
java.lang.NullPointerException: null
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeLogin(VeluxBridgeHandler.java:344) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeCommunicate(VeluxBridgeHandler.java:455) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeLogout(VeluxBridgeHandler.java:367) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.dispose(VeluxBridgeHandler.java:160) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2018-06-15 08:08:11.676 [ERROR] [org.openhab.binding.velux           ] - [binding.velux] Cannot register Component
org.osgi.service.component.ComponentException: The component name 'binding.velux' has already been registered by Bundle 233 (org.openhab.binding.velux) as Component of Class org.openhab.binding.velux.internal.VeluxHandlerFactory
	at org.apache.felix.scr.impl.ComponentRegistry.checkComponentName(ComponentRegistry.java:222) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.loadDescriptor(BundleComponentActivator.java:448) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.initialize(BundleComponentActivator.java:315) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.<init>(BundleComponentActivator.java:268) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator.loadComponents(Activator.java:388) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator.access$200(Activator.java:54) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator$ScrExtension.start(Activator.java:265) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.utils.extender.AbstractExtender.createExtension(AbstractExtender.java:254) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.utils.extender.AbstractExtender.modifiedBundle(AbstractExtender.java:227) [39:org.apache.felix.scr:2.0.12]
	at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:482) [?:?]
	at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:415) [?:?]
	at org.osgi.util.tracker.AbstractTracked.track(AbstractTracked.java:232) [?:?]
	at org.osgi.util.tracker.BundleTracker$Tracked.bundleChanged(BundleTracker.java:444) [?:?]
	at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:908) [?:?]
	at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [?:?]
	at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEventPrivileged(EquinoxEventPublisher.java:213) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:120) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:112) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor.publishModuleEvent(EquinoxContainerAdaptor.java:168) [?:?]
	at org.eclipse.osgi.container.Module.publishEvent(Module.java:476) [?:?]
	at org.eclipse.osgi.container.Module.start(Module.java:467) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) [?:?]
	at org.apache.karaf.bundle.command.Restart.doExecute(Restart.java:51) [43:org.apache.karaf.bundle.core:4.1.5]
	at org.apache.karaf.bundle.command.BundlesCommand.execute(BundlesCommand.java:52) [43:org.apache.karaf.bundle.core:4.1.5]
	at org.apache.karaf.shell.impl.action.command.ActionCommand.execute(ActionCommand.java:84) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.karaf.shell.impl.console.osgi.secured.SecuredCommand.execute(SecuredCommand.java:68) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.karaf.shell.impl.console.osgi.secured.SecuredCommand.execute(SecuredCommand.java:86) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Closure.executeCmd(Closure.java:571) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Closure.executeStatement(Closure.java:497) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Closure.execute(Closure.java:386) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Pipe.doCall(Pipe.java:417) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Pipe.call(Pipe.java:229) [12:org.apache.karaf.shell.core:4.1.5]
	at org.apache.felix.gogo.runtime.Pipe.call(Pipe.java:59) [12:org.apache.karaf.shell.core:4.1.5]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2018-06-15 08:10:17.707 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.dispose()' on 'org.openhab.binding.velux.handler.VeluxBridgeHandler@833984': null
java.lang.NullPointerException: null
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeLogin(VeluxBridgeHandler.java:344) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeCommunicate(VeluxBridgeHandler.java:455) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeLogout(VeluxBridgeHandler.java:367) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.dispose(VeluxBridgeHandler.java:160) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2018-06-15 08:10:17.711 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occurred while disposing handler of thing 'velux:klf200:home': null
java.lang.NullPointerException: null
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeLogin(VeluxBridgeHandler.java:344) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeCommunicate(VeluxBridgeHandler.java:455) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.bridgeLogout(VeluxBridgeHandler.java:367) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at org.openhab.binding.velux.handler.VeluxBridgeHandler.dispose(VeluxBridgeHandler.java:160) [233:org.openhab.binding.velux:2.3.0.201802142040]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]

I switched back to 2.2.0 and now it is running again:

2018-06-15 08:12:20.295 [INFO ] [ing.velux.handler.VeluxBridgeHandler] - Initializing Velux bridge handler for 'velux:klf200:home'.
2018-06-15 08:12:25.315 [WARN ] [ome.core.thing.internal.ThingManager] - Initializing handler for thing 'velux:klf200:home' takes more than 5000ms.
2018-06-15 08:12:20.282 [hingStatusInfoChangedEvent] - 'velux:klf200:home' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2018-06-15 08:12:24.372 [hingStatusInfoChangedEvent] - 'velux:klf200:home' changed from INITIALIZING to ONLINE
2018-06-15 08:12:24.413 [hingStatusInfoChangedEvent] - 'velux:scene:home:links_0' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-06-15 08:12:24.428 [hingStatusInfoChangedEvent] - 'velux:scene:home:links_0' changed from INITIALIZING to ONLINE
2018-06-15 08:12:24.464 [hingStatusInfoChangedEvent] - 'velux:scene:home:ALLE_50' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-06-15 08:12:24.470 [hingStatusInfoChangedEvent] - 'velux:scene:home:rechts_50' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-06-15 08:12:24.476 [hingStatusInfoChangedEvent] - 'velux:scene:home:rechts_50' changed from INITIALIZING to ONLINE
2018-06-15 08:12:24.480 [hingStatusInfoChangedEvent] - 'velux:scene:home:ALLE_50' changed from INITIALIZING to ONLINE
2018-06-15 08:12:24.484 [hingStatusInfoChangedEvent] - 'velux:scene:home:ALLE_auf' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-06-15 08:12:24.486 [hingStatusInfoChangedEvent] - 'velux:scene:home:links_50' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-06-15 08:12:24.492 [hingStatusInfoChangedEvent] - 'velux:scene:home:ALLE_auf' changed from INITIALIZING to ONLINE
2018-06-15 08:12:24.495 [hingStatusInfoChangedEvent] - 'velux:scene:home:rechts_0' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-06-15 08:12:24.501 [hingStatusInfoChangedEvent] - 'velux:scene:home:links_100' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-06-15 08:12:24.506 [hingStatusInfoChangedEvent] - 'velux:scene:home:rechts_100' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-06-15 08:12:24.509 [hingStatusInfoChangedEvent] - 'velux:scene:home:ALLE_zu' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-06-15 08:12:24.512 [hingStatusInfoChangedEvent] - 'velux:scene:home:links_50' changed from INITIALIZING to ONLINE
2018-06-15 08:12:24.515 [hingStatusInfoChangedEvent] - 'velux:scene:home:rechts_0' changed from INITIALIZING to ONLINE
2018-06-15 08:12:24.517 [hingStatusInfoChangedEvent] - 'velux:scene:home:ALLE_zu' changed from INITIALIZING to ONLINE
2018-06-15 08:12:24.520 [hingStatusInfoChangedEvent] - 'velux:scene:home:rechts_100' changed from INITIALIZING to ONLINE
2018-06-15 08:12:24.523 [hingStatusInfoChangedEvent] - 'velux:scene:home:links_100' changed from INITIALIZING to ONLINE

I don´t know why the 2.3.0 binding is not running!?

Additional Info:

please pass the appropriate log line back to the maintainer to incorporate the new Velux device type.

here is my log line:

2018-06-15 08:12:28.810 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.815 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.819 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.823 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.827 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.830 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.834 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.838 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.842 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.845 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.849 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.
2018-06-15 08:12:28.853 [ERROR] [g.velux.things.VeluxProductReference] - PLEASE REPORT THIS TO MAINTAINER: VeluxProductReference(13) has found an unregistered ProductTypeId.


(Kristoffer Ernest) #20

Did you clear your cache- and tmp directiories after uninstall of 2.2.0?


(Stefan) #21

nope i didn´t. I also didn´t stop -> clear cache and temp -> start openhab.

I only uninstalled binding 2.2.0 via karaf -> removed 2.2.0 JAR from addons -> placed 2.3.0 JAR to addons.


(Kristoffer Ernest) #22

Try to follow this to clear:

Clear cache etc.

This worked for me.

Also be sure that the addon is removed.

Use this command in openhab console:

bundle:list | grep elux

If correctly removed and installed, there should onlly be a 2.3 version running.


(Stefan) #23

THX, I know what I have to do for clear cache and temp.

The reason why I did not that, I do not want to stop/start openhab now.
I have a very big installation with a lot of items etc.
A total restart of openhab takes round about 20-30min until all items and reported things (mostly Z-Wave) back.


(Kristoffer Ernest) #24

Ah okay. I see.

But believe this is the reason, as OH already see the bundle as registered.


(Stefan) #25

I will give the binding a try by the next Start/stop action :wink:


(Stefan) #26

Today I gave it a try.

Uninstall 2.2.0 Binding via KARAF -> remove JAR File from addons folder -> Stop OH -> clear TMP and Cache folder -> Start OH -> put 2.3.0 binding in addons

But I got the same Error log as days before


(Kim Andersen) #27

Just bought a KLF200.
I can´t get velux binding 2.3 to load at all.
I have placed org.openhab.binding.velux-2.3.0-SNAPSHOT.jar in the usr/share/openhab2/addons
But I get absolutly no messages/logs at all. it just seems like it´s not there.

Did a bundle:list | grep elux from karaf console… it returned nothing

I have not had velux installed before, so I guess I shouldn´t have to clear cache and tmp.

Using apt-get openhab2.3 stable on Rpi3 B
This is my velux.cfg

Bridge velux:klf200:home   [ bridgeIPAddress="10.4.28.252", bridgeTCPPort=80, bridgePassword="xxxxxxxx", timeoutMsecs=2000, retries=10 ]  {
 Thing   scene   aVinduerluk    [ sceneName="Alle_vinduer_luk" ]
 Thing   scene   aVinduerVentilation  [ sceneName="Alle_vinduer_ventilation" ]
 Thing   scene   aVinduerÅbne    [ sceneName="Alle_vinduer_åben_100%" ] }

(Kim Andersen) #28

Finally managed to get the binidng installed… Guess I needed to download it another way…

Anyway, after installed, this is the event.log:

2018-06-24 22:18:26.009 [DEBUG] [org.openhab.binding.velux           ] - BundleEvent INSTALLED - org.openhab.binding.velux
2018-06-24 22:18:26.244 [DEBUG] [org.openhab.binding.velux           ] - BundleEvent RESOLVED - org.openhab.binding.velux
2018-06-24 22:18:26.638 [DEBUG] [org.openhab.binding.velux           ] - BundleEvent STARTING - org.openhab.binding.velux
2018-06-24 22:18:26.643 [DEBUG] [org.openhab.binding.velux           ] - BundleEvent STARTED - org.openhab.binding.velux
2018-06-24 22:18:26.657 [ERROR] [org.openhab.binding.velux           ] - [binding.velux] Cannot register Component
org.osgi.service.component.ComponentException: The component name 'binding.velux' has already been registered by Bundle 241 (org.openhab.binding.velux) as Component of Class org.openhab.binding.velux.internal.VeluxHandlerFactory
	at org.apache.felix.scr.impl.ComponentRegistry.checkComponentName(ComponentRegistry.java:222) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.loadDescriptor(BundleComponentActivator.java:448) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.initialize(BundleComponentActivator.java:315) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.BundleComponentActivator.<init>(BundleComponentActivator.java:268) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator.loadComponents(Activator.java:388) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator.access$200(Activator.java:54) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.scr.impl.Activator$ScrExtension.start(Activator.java:265) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.utils.extender.AbstractExtender.createExtension(AbstractExtender.java:254) [39:org.apache.felix.scr:2.0.12]
	at org.apache.felix.utils.extender.AbstractExtender.modifiedBundle(AbstractExtender.java:227) [39:org.apache.felix.scr:2.0.12]
	at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:482) [?:?]
	at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:415) [?:?]
	at org.osgi.util.tracker.AbstractTracked.track(AbstractTracked.java:232) [?:?]
	at org.osgi.util.tracker.BundleTracker$Tracked.bundleChanged(BundleTracker.java:444) [?:?]
	at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:908) [?:?]
	at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [?:?]
	at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEventPrivileged(EquinoxEventPublisher.java:213) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:120) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:112) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor.publishModuleEvent(EquinoxContainerAdaptor.java:168) [?:?]
	at org.eclipse.osgi.container.Module.publishEvent(Module.java:476) [?:?]
	at org.eclipse.osgi.container.Module.start(Module.java:467) [?:?]
	at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) [?:?]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) [9:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) [9:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:520) [9:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [9:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [9:org.apache.felix.fileinstall:3.6.4]
2018-06-24 22:18:26.714 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:controller) called and returns false.
2018-06-24 22:18:26.723 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.726 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.729 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.733 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.736 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.740 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.744 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(netatmo:NAModule1) called and returns false.
2018-06-24 22:18:26.747 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(astro:sun) called and returns false.
2018-06-24 22:18:26.751 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:sensor_switch) called and returns false.
2018-06-24 22:18:26.754 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0010) called and returns false.
2018-06-24 22:18:26.758 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:sensor_ht) called and returns false.
2018-06-24 22:18:26.761 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:vision_zp3102_08_000) called and returns false.
2018-06-24 22:18:26.764 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:serial_zstick) called and returns false.
2018-06-24 22:18:26.768 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:sensor_magnet) called and returns false.
2018-06-24 22:18:26.771 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:device) called and returns false.
2018-06-24 22:18:26.775 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:vision_zg8101_00_000) called and returns false.
2018-06-24 22:18:26.778 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(netatmo:netatmoapi) called and returns false.
2018-06-24 22:18:26.781 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:shenzhen_motionsensor_00_000) called and returns false.
2018-06-24 22:18:26.785 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(netatmo:NAMain) called and returns false.
2018-06-24 22:18:26.788 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:bridge) called and returns false.
2018-06-24 22:18:26.791 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:bridge) called and returns false.
2018-06-24 22:18:26.795 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(lgwebos:WebOSTV) called and returns false.
2018-06-24 22:18:26.798 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0200) called and returns false.
2018-06-24 22:18:26.802 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0200) called and returns false.
2018-06-24 22:18:26.805 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0210) called and returns false.
2018-06-24 22:18:26.809 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:fibaro_fgd212_00_000) called and returns false.
2018-06-24 22:18:26.812 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0210) called and returns false.
2018-06-24 22:18:26.815 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:aeon_zw100_01_007) called and returns false.
2018-06-24 22:18:26.819 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(astro:moon) called and returns false.
2018-06-24 22:18:26.822 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:everspring_st814_00_000) called and returns false.
2018-06-24 22:18:26.826 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0200) called and returns false.
2018-06-24 22:18:26.829 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:sensor_motion) called and returns false.
2018-06-24 22:18:26.832 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:gateway) called and returns false.
2018-06-24 22:18:26.843 [DEBUG] [org.openhab.binding.velux           ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=427, service.bundleid=241, service.scope=bundle, component.name=binding.velux, component.id=271} - org.openhab.binding.velux
2018-06-24 22:18:26.951 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:controller) called and returns false.
2018-06-24 22:18:26.954 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.958 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.961 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.964 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.967 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.970 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.974 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:controller) called and returns false.
2018-06-24 22:18:26.976 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.979 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.982 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.985 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.988 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.991 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(unifi:client) called and returns false.
2018-06-24 22:18:26.994 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(netatmo:NAModule1) called and returns false.
2018-06-24 22:18:26.997 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(astro:sun) called and returns false.
2018-06-24 22:18:27.000 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:sensor_switch) called and returns false.
2018-06-24 22:18:27.003 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0010) called and returns false.
2018-06-24 22:18:27.006 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:sensor_ht) called and returns false.
2018-06-24 22:18:27.009 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:vision_zp3102_08_000) called and returns false.
2018-06-24 22:18:27.012 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:serial_zstick) called and returns false.
2018-06-24 22:18:27.015 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:sensor_magnet) called and returns false.
2018-06-24 22:18:27.018 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:device) called and returns false.
2018-06-24 22:18:27.021 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:vision_zg8101_00_000) called and returns false.
2018-06-24 22:18:27.024 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(netatmo:netatmoapi) called and returns false.
2018-06-24 22:18:27.028 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:shenzhen_motionsensor_00_000) called and returns false.
2018-06-24 22:18:27.031 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(netatmo:NAMain) called and returns false.
2018-06-24 22:18:27.034 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:bridge) called and returns false.
2018-06-24 22:18:27.037 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:bridge) called and returns false.
2018-06-24 22:18:27.040 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(lgwebos:WebOSTV) called and returns false.
2018-06-24 22:18:27.044 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0200) called and returns false.
2018-06-24 22:18:27.047 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0200) called and returns false.
2018-06-24 22:18:27.050 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0210) called and returns false.
2018-06-24 22:18:27.053 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:fibaro_fgd212_00_000) called and returns false.
2018-06-24 22:18:27.055 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0210) called and returns false.
2018-06-24 22:18:27.058 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:aeon_zw100_01_007) called and returns false.
2018-06-24 22:18:27.061 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(astro:moon) called and returns false.
2018-06-24 22:18:27.064 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(zwave:everspring_st814_00_000) called and returns false.
2018-06-24 22:18:27.067 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(hue:0200) called and returns false.
2018-06-24 22:18:27.069 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:sensor_motion) called and returns false.
2018-06-24 22:18:27.072 [TRACE] [g.velux.internal.VeluxHandlerFactory] - supportsThingType(mihome:gateway) called and returns false.

Forgot to mention… Everything seems to work though.


Installing binding manually, nothing happens
(Udo Hartmann) #29

Well, set the log level to DEFAULT or INFO…
in karaf console:

log:set DEFAULT org.openhab.binding.velux

(Andrea Riela) #30

Sorry guys to bother,

May I ask your support in defining the physical layout? I need to understand if the solution with gateway is viable for me or not (as I don’t have any wire from all my 5 velux … as I said, everythings is managed via wireless and remote controllers, one controller for each velux).

thanks for your support, much appreciated

Andrea


(Kim Andersen) #31

Thx @Udo_Hartmann , I had it set for debug, since I couldn´t get the binding to load.
Everything is working now. And the loglevel is back to INFO.


(Kim Andersen) #32

Is there any special reason why I can´t log into the Velux KLF200 web interface. It said it´s beeing occupied by another user (guess thats OpenHab). ? Would I have to stop OpenHab to be able to get into the web interface of the KLF200, or is something wrong?


(Kim Andersen) #33

Guess it just need some patient :slight_smile: Working again.