For Atmosphere Framework Commercial Support, visit http://www.async-io.org/ or send an email to support@async-io.org 07:58:42.736 [INFO ] [penhab.io.rest.RESTApplication:144 ] - Started REST API at /rest 07:58:42.736 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66 ] - Registering new service _openhab-server._tcp.local. at port 8080 07:58:49.002 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'cometVISU.sitemap' 07:58:49.035 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66 ] - Registering new service _openhab-server-ssl._tcp.local. at port 8443 07:58:49.083 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'Tinkerforge.sitemap' 07:58:49.099 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'db4o.persist' 07:58:49.116 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'exec.persist' 07:58:49.116 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'logging.persist' 07:58:49.116 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'rrd4j.persist' 07:58:49.149 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'demo.script' 07:58:49.260 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'cometVISU.items' 07:58:49.260 [DEBUG] [i.internal.GenericItemProvider:154 ] - Processing binding configs for items from model 'cometVISU.items' 07:58:49.276 [DEBUG] [i.internal.GenericItemProvider:133 ] - Read items from model 'cometVISU.items' 07:58:49.276 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'TinkerForge.items' 07:58:49.276 [DEBUG] [i.internal.GenericItemProvider:154 ] - Processing binding configs for items from model 'TinkerForge.items' 07:58:49.276 [DEBUG] [i.internal.GenericItemProvider:133 ] - Read items from model 'cometVISU.items' 07:58:49.276 [DEBUG] [i.internal.GenericItemProvider:133 ] - Read items from model 'TinkerForge.items' 07:58:51.817 [INFO ] [.o.u.w.i.servlet.WebAppServlet:80 ] - Started Classic UI at /classicui/openhab.app 07:58:51.931 [DEBUG] [.r.internal.RuleModelActivator:42 ] - Registered 'rules' configuration parser 07:58:51.947 [DEBUG] [m.r.internal.engine.RuleEngine:77 ] - Started rule engine 07:58:55.764 [DEBUG] [j.internal.Jsr223CoreActivator:35 ] - Registered 'jsr223' scripting-engine 07:58:55.764 [DEBUG] [j.internal.engine.Jsr223Engine:100 ] - itemRegistry set 07:58:55.764 [DEBUG] [j.internal.engine.Jsr223Engine:70 ] - activate() 07:58:55.780 [INFO ] [o.o.c.j.i.e.s.ScriptManager :63 ] - Available engines: 07:58:55.780 [INFO ] [o.o.c.j.i.e.s.ScriptManager :65 ] - Oracle Nashorn 07:58:55.780 [WARN ] [j.i.e.scriptmanager.ScriptRule:132 ] - No Engine found for File: jsr223_demo.py 07:58:56.020 [INFO ] [j.i.e.scriptmanager.ScriptRule:128 ] - EngineName: Oracle Nashorn 07:58:56.020 [WARN ] [j.i.e.scriptmanager.ScriptRule:172 ] - Found wrong classloader: To prevent Class loading Problems use this directive in start.sh/-.bat: -Dorg.osgi.framework.bundle.parent=ext 07:58:56.020 [INFO ] [j.i.e.scriptmanager.ScriptRule:176 ] - initializeSciptGlobals for : Oracle Nashorn 07:58:56.263 [ERROR] [j.i.e.scriptmanager.ScriptRule:113 ] - Error in script javax.script.ScriptException: TypeError: Can not find a common class loader for ScriptObject and org.openhab.core.jsr223.internal.shared.Rule. in at line number 16 at jdk.nashorn.api.scripting.NashornScriptEngine.throwAsScriptException(NashornScriptEngine.java:467) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.evalImpl(NashornScriptEngine.java:451) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.evalImpl(NashornScriptEngine.java:403) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.evalImpl(NashornScriptEngine.java:399) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.eval(NashornScriptEngine.java:150) ~[nashorn.jar:na] at javax.script.AbstractScriptEngine.eval(Unknown Source) ~[na:1.8.0_65] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptRule.(ScriptRule.java:43) ~[bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptBase.tryNormalScript(ScriptBase.java:111) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptBase.returnScript(ScriptBase.java:102) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptManager.returnNewScript(ScriptManager.java:151) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptManager.loadScript(ScriptManager.java:93) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptManager.loadScripts(ScriptManager.java:84) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptManager.(ScriptManager.java:73) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.Jsr223Engine.activate(Jsr223Engine.java:73) [bundlefile:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_65] at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_65] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_65] at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0_65] at org.eclipse.equinox.internal.ds.model.ServiceComponent.activate(ServiceComponent.java:235) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.model.ServiceComponentProp.activate(ServiceComponentProp.java:146) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.model.ServiceComponentProp.build(ServiceComponentProp.java:345) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.InstanceProcess.buildComponent(InstanceProcess.java:620) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.InstanceProcess.buildComponents(InstanceProcess.java:197) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.Resolver.buildNewlySatisfied(Resolver.java:473) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.Resolver.enableComponents(Resolver.java:217) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.SCRManager.performWork(SCRManager.java:816) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.SCRManager$QueuedJob.dispatch(SCRManager.java:783) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.WorkThread.run(WorkThread.java:89) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.util.impl.tpt.threadpool.Executor.run(Executor.java:70) [org.eclipse.equinox.util_1.0.400.v20120917-192807.jar:na] Caused by: jdk.nashorn.internal.runtime.ECMAException: TypeError: Can not find a common class loader for ScriptObject and org.openhab.core.jsr223.internal.shared.Rule. at jdk.nashorn.internal.runtime.ECMAErrors.error(ECMAErrors.java:57) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:213) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:185) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:172) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.AdaptationResult.typeError(AdaptationResult.java:70) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.JavaAdapterFactory$AdapterInfo.getAdapterClass(JavaAdapterFactory.java:314) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.JavaAdapterFactory.getAdapterClassFor(JavaAdapterFactory.java:150) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.JavaAdapterFactory.getAdapterClassFor(JavaAdapterFactory.java:136) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.NashornStaticClassLinker.getGuardedInvocation(NashornStaticClassLinker.java:85) ~[nashorn.jar:na] at jdk.internal.dynalink.support.CompositeTypeBasedGuardingDynamicLinker.getGuardedInvocation(CompositeTypeBasedGuardingDynamicLinker.java:176) ~[nashorn.jar:na] at jdk.internal.dynalink.support.CompositeGuardingDynamicLinker.getGuardedInvocation(CompositeGuardingDynamicLinker.java:124) ~[nashorn.jar:na] at jdk.internal.dynalink.support.LinkerServicesImpl.getGuardedInvocation(LinkerServicesImpl.java:154) ~[nashorn.jar:na] at jdk.internal.dynalink.DynamicLinker.relink(DynamicLinker.java:253) ~[nashorn.jar:na] at jdk.nashorn.internal.scripts.Script$1$\^eval\_.:program(:16) ~[na:na] at jdk.nashorn.internal.runtime.ScriptFunctionData.invoke(ScriptFunctionData.java:640) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ScriptFunction.invoke(ScriptFunction.java:228) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ScriptRuntime.apply(ScriptRuntime.java:393) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.evalImpl(NashornScriptEngine.java:446) ~[nashorn.jar:na] ... 27 common frames omitted 07:58:56.279 [ERROR] [o.o.c.j.i.e.s.ScriptManager :110 ] - unknown exception javax.script.ScriptException: TypeError: Can not find a common class loader for ScriptObject and org.openhab.core.jsr223.internal.shared.Rule. in at line number 16 at jdk.nashorn.api.scripting.NashornScriptEngine.throwAsScriptException(NashornScriptEngine.java:467) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.evalImpl(NashornScriptEngine.java:451) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.evalImpl(NashornScriptEngine.java:403) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.evalImpl(NashornScriptEngine.java:399) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.eval(NashornScriptEngine.java:150) ~[nashorn.jar:na] at javax.script.AbstractScriptEngine.eval(Unknown Source) ~[na:1.8.0_65] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptRule.(ScriptRule.java:43) ~[bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptBase.tryNormalScript(ScriptBase.java:111) ~[bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptBase.returnScript(ScriptBase.java:102) ~[bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptManager.returnNewScript(ScriptManager.java:151) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptManager.loadScript(ScriptManager.java:93) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptManager.loadScripts(ScriptManager.java:84) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.scriptmanager.ScriptManager.(ScriptManager.java:73) [bundlefile:na] at org.openhab.core.jsr223.internal.engine.Jsr223Engine.activate(Jsr223Engine.java:73) [bundlefile:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_65] at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_65] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_65] at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0_65] at org.eclipse.equinox.internal.ds.model.ServiceComponent.activate(ServiceComponent.java:235) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.model.ServiceComponentProp.activate(ServiceComponentProp.java:146) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.model.ServiceComponentProp.build(ServiceComponentProp.java:345) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.InstanceProcess.buildComponent(InstanceProcess.java:620) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.InstanceProcess.buildComponents(InstanceProcess.java:197) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.Resolver.buildNewlySatisfied(Resolver.java:473) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.Resolver.enableComponents(Resolver.java:217) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.SCRManager.performWork(SCRManager.java:816) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.SCRManager$QueuedJob.dispatch(SCRManager.java:783) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.ds.WorkThread.run(WorkThread.java:89) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na] at org.eclipse.equinox.internal.util.impl.tpt.threadpool.Executor.run(Executor.java:70) [org.eclipse.equinox.util_1.0.400.v20120917-192807.jar:na] Caused by: jdk.nashorn.internal.runtime.ECMAException: TypeError: Can not find a common class loader for ScriptObject and org.openhab.core.jsr223.internal.shared.Rule. at jdk.nashorn.internal.runtime.ECMAErrors.error(ECMAErrors.java:57) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:213) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:185) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:172) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.AdaptationResult.typeError(AdaptationResult.java:70) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.JavaAdapterFactory$AdapterInfo.getAdapterClass(JavaAdapterFactory.java:314) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.JavaAdapterFactory.getAdapterClassFor(JavaAdapterFactory.java:150) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.JavaAdapterFactory.getAdapterClassFor(JavaAdapterFactory.java:136) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.linker.NashornStaticClassLinker.getGuardedInvocation(NashornStaticClassLinker.java:85) ~[nashorn.jar:na] at jdk.internal.dynalink.support.CompositeTypeBasedGuardingDynamicLinker.getGuardedInvocation(CompositeTypeBasedGuardingDynamicLinker.java:176) ~[nashorn.jar:na] at jdk.internal.dynalink.support.CompositeGuardingDynamicLinker.getGuardedInvocation(CompositeGuardingDynamicLinker.java:124) ~[nashorn.jar:na] at jdk.internal.dynalink.support.LinkerServicesImpl.getGuardedInvocation(LinkerServicesImpl.java:154) ~[nashorn.jar:na] at jdk.internal.dynalink.DynamicLinker.relink(DynamicLinker.java:253) ~[nashorn.jar:na] at jdk.nashorn.internal.scripts.Script$1$\^eval\_.:program(:16) ~[na:na] at jdk.nashorn.internal.runtime.ScriptFunctionData.invoke(ScriptFunctionData.java:640) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ScriptFunction.invoke(ScriptFunction.java:228) ~[nashorn.jar:na] at jdk.nashorn.internal.runtime.ScriptRuntime.apply(ScriptRuntime.java:393) ~[nashorn.jar:na] at jdk.nashorn.api.scripting.NashornScriptEngine.evalImpl(NashornScriptEngine.java:446) ~[nashorn.jar:na] ... 27 common frames omitted 07:58:56.311 [DEBUG] [j.internal.engine.Jsr223Engine:82 ] - Started jsr223 engine 07:58:56.327 [DEBUG] [hab.io.cv.internal.CVActivator:32 ] - Cometvisu API has been started. 07:58:56.327 [INFO ] [.a.core.PackagesResourceConfig:101 ] - Scanning for root resource and provider classes in the packages: org.openhab.io.cv.internal.resources 07:58:56.327 [INFO ] [.a.core.ScanningResourceConfig:153 ] - Root resource classes found: class org.openhab.io.cv.internal.resources.LoginResource class org.openhab.io.cv.internal.resources.ReadResource class org.openhab.io.cv.internal.resources.RrdResource class org.openhab.io.cv.internal.resources.WriteResource 07:58:56.327 [INFO ] [.a.core.ScanningResourceConfig:94 ] - No provider classes found. 07:58:56.327 [INFO ] [org.atmosphere.util.IOUtils :370 ] - META-INF/services/org.atmosphere.cpr.AtmosphereFramework not found in class loader 07:58:56.343 [INFO ] [sphere.cpr.AtmosphereFramework:2601 ] - Atmosphere is using org.atmosphere.cpr.DefaultAnnotationProcessor for processing annotation 07:58:56.343 [INFO ] [cpr.DefaultAnnotationProcessor:138 ] - AnnotationProcessor class org.atmosphere.cpr.DefaultAnnotationProcessor$BytecodeBasedAnnotationProcessor being used 07:58:56.343 [WARN ] [cpr.DefaultAnnotationProcessor:178 ] - Unable to detect annotations. Application may fail to deploy. 07:58:56.343 [INFO ] [sphere.cpr.AtmosphereFramework:1891 ] - Auto detecting atmosphere handlers /WEB-INF/classes/ 07:58:56.359 [INFO ] [sphere.cpr.AtmosphereFramework:639 ] - Installed AtmosphereHandler org.atmosphere.handler.ReflectorServletProcessor mapped to context-path /* and Broadcaster Class org.atmosphere.jersey.JerseyBroadcaster 07:58:56.359 [INFO ] [sphere.cpr.AtmosphereFramework:1945 ] - Auto detecting WebSocketHandler in /WEB-INF/classes/ 07:58:56.359 [INFO ] [sphere.cpr.AtmosphereFramework:1602 ] - Installed WebSocketProtocol org.atmosphere.websocket.protocol.SimpleHttpProtocol 07:58:56.359 [INFO ] [.a.h.ReflectorServletProcessor:103 ] - Installing Servlet com.sun.jersey.spi.container.servlet.ServletContainer 07:58:56.359 [INFO ] [c.s.j.s.i.a.WebApplicationImpl:815 ] - Initiating Jersey application, version 'Jersey: 1.18.1 02/19/2014 03:28 AM' 07:58:56.359 [INFO ] [c.s.j.s.i.a.WebApplicationImpl:826 ] - Adding the following classes declared in META-INF/services/jersey-server-components to the resource configuration: class org.atmosphere.jersey.AtmosphereResourceConfigurator 07:58:56.375 [INFO ] [.a.core.PackagesResourceConfig:101 ] - Scanning for root resource and provider classes in the packages: org.openhab.io.cv.internal.resources 07:58:56.375 [INFO ] [.a.core.ScanningResourceConfig:153 ] - Root resource classes found: class org.openhab.io.cv.internal.resources.LoginResource class org.openhab.io.cv.internal.resources.ReadResource class org.openhab.io.cv.internal.resources.RrdResource class org.openhab.io.cv.internal.resources.WriteResource 07:58:56.375 [INFO ] [.a.core.ScanningResourceConfig:94 ] - No provider classes found. 07:58:56.391 [INFO ] [j.s.i.a.DeferredResourceConfig:101 ] - Instantiated the Application class org.openhab.io.cv.CVApplication 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Default Response's Headers Interceptor with priority AFTER_DEFAULT 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Android Interceptor Support with priority AFTER_DEFAULT 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor SSE Interceptor Support with priority AFTER_DEFAULT 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor JSONP Interceptor Support with priority AFTER_DEFAULT 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Atmosphere JavaScript Protocol with priority AFTER_DEFAULT 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Browser disconnection detection with priority AFTER_DEFAULT 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:935 ] - Using EndpointMapper class org.atmosphere.util.DefaultEndpointMapper 07:58:56.603 [WARN ] [sphere.cpr.AtmosphereFramework:941 ] - No BroadcasterCache configured. Broadcasted message between client reconnection will be LOST. It is recommended to configure the org.atmosphere.cache.UUIDBroadcasterCache 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:949 ] - Default Broadcaster Class: org.atmosphere.jersey.JerseyBroadcaster 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:950 ] - Broadcaster Polling Wait Time 100 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:951 ] - Shared ExecutorService supported: true 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:958 ] - Messaging Thread Pool Size: Unlimited 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:968 ] - Async I/O Thread Pool Size: 200 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:979 ] - Using BroadcasterFactory: org.atmosphere.cpr.DefaultBroadcasterFactory 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:980 ] - Using WebSocketProcessor: org.atmosphere.websocket.DefaultWebSocketProcessor 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:990 ] - Invoke AtmosphereInterceptor on WebSocket message true 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:991 ] - HttpSession supported: false 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:993 ] - Atmosphere is using DefaultAtmosphereObjectFactory for dependency injection and object creation 07:58:56.603 [INFO ] [sphere.cpr.AtmosphereFramework:994 ] - Atmosphere is using async support: org.atmosphere.container.JettyAsyncSupportWithWebSocket running under container: jetty/8.1.3.v20120522 with WebSocket enabled. 07:58:56.618 [INFO ] [sphere.cpr.AtmosphereFramework:996 ] - Atmosphere Framework 2.2.5 started. 07:58:56.618 [INFO ] [sphere.cpr.AtmosphereFramework:998 ] - For Atmosphere Framework Commercial Support, visit http://www.async-io.org/ or send an email to support@async-io.org 07:58:56.618 [INFO ] [rg.openhab.io.cv.CVApplication:166 ] - Started Cometvisu API at /services/cv 07:58:56.618 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66 ] - Registering new service _openhab-cv-server._tcp.local. at port 8080 07:58:59.195 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66 ] - Registering new service _openhab-cv-server-ssl._tcp.local. at port 8443 07:58:59.290 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'cometVISU.rules' 07:58:59.371 [DEBUG] [.r.i.engine.RuleTriggerManager:438 ] - Scheduled rule SwitchWindowState with cron expression 0/15 * * * * ? 07:58:59.371 [DEBUG] [.r.i.engine.RuleTriggerManager:438 ] - Scheduled rule Crontest with cron expression 0/15 * * * * ? 07:58:59.371 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'tinkerforge.rules' 07:58:59.403 [DEBUG] [.r.i.engine.RuleTriggerManager:438 ] - Scheduled rule Checkhumidity with cron expression 0 0 0/1 * * ? 07:58:59.403 [DEBUG] [m.r.internal.engine.RuleEngine:264 ] - Executing startup rule 'startup' 07:58:59.483 [DEBUG] [riptExtensionClassNameProvider:63 ] - Script actions have changed: AudioActionService, PingActionService, HTTPActionService, ExecActionService, TransformationActionService, 07:58:59.563 [DEBUG] [m.r.internal.engine.RuleEngine:273 ] - Not all required items in place yet for rule startup, trying again later: The name 'IPConnection' cannot be resolved to an item or type. 07:59:00.025 [DEBUG] [.o.m.r.i.engine.ExecuteRuleJob:53 ] - Executing scheduled rule 'SwitchWindowState' 07:59:00.025 [DEBUG] [.o.m.r.i.engine.ExecuteRuleJob:53 ] - Executing scheduled rule 'Crontest' 07:59:06.237 [DEBUG] [o.o.b.t.i.TinkerforgeActivator:34 ] - Tinkerforge binding has been started. 07:59:06.237 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'temperature (Type=NumberItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: name=temperature 07:59:06.237 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'humidity (Type=NumberItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: name=ainv2 07:59:06.237 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'humidityprobe (Type=SwitchItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: uid=h2t 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: subid=out0 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: type=io4_actuator 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: defaultState=true 07:59:06.237 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'valve (Type=SwitchItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: uid=h2t 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: subid=out1 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: type=io4_actuator 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: defaultState=true 07:59:06.237 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'out0 (Type=SwitchItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 07:59:06.237 [DEBUG] [kerforgeGenericBindingProvider:81 ] - token: name=io4 07:59:06.285 [DEBUG] [.rrd4j.internal.RRD4jActivator:31 ] - RRD4j persistence bundle has been started. 07:59:06.301 [DEBUG] [.p.rrd4j.internal.RRD4jService:395 ] - Unknown property name : org.openhab.persistence.rrd4j 07:59:06.301 [DEBUG] [.p.rrd4j.internal.RRD4jService:364 ] - config 'objectClass' should have the format 'name.configkey' 07:59:06.301 [INFO ] [.p.rrd4j.internal.RRD4jService:407 ] - Removing invalid defintion component = null heartbeat = 0 min/max = 0.0/0.0 step = 0 0 archives(s) = [] 0 items(s) = [] 07:59:06.301 [DEBUG] [.p.rrd4j.internal.RRD4jService:405 ] - Created default_other = GAUGE heartbeat = 3600 min/max = NaN/NaN step = 1 6 archives(s) = [ MAX xff = 0.999 steps = 1 rows = 3600 MAX xff = 0.999 steps = 10 rows = 1440 MAX xff = 0.999 steps = 60 rows = 1440 MAX xff = 0.999 steps = 900 rows = 2880 MAX xff = 0.999 steps = 21600 rows = 1460 MAX xff = 0.999 steps = 86400 rows = 3650] 0 items(s) = [] 07:59:06.301 [DEBUG] [.p.rrd4j.internal.RRD4jService:405 ] - Created default_numeric = GAUGE heartbeat = 60 min/max = NaN/NaN step = 60 6 archives(s) = [ AVERAGE xff = 0.5 steps = 1 rows = 480 AVERAGE xff = 0.5 steps = 4 rows = 360 AVERAGE xff = 0.5 steps = 14 rows = 644 AVERAGE xff = 0.5 steps = 60 rows = 720 AVERAGE xff = 0.5 steps = 720 rows = 730 AVERAGE xff = 0.5 steps = 10080 rows = 520] 0 items(s) = [] 07:59:06.301 [DEBUG] [.p.internal.PersistenceManager:146 ] - Initializing rrd4j persistence service. 07:59:06.317 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'DemoSwitch' does not exist. 07:59:06.317 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'NoOfLights' does not exist. 07:59:06.319 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'Window_GF_Toilet' does not exist. 07:59:06.320 [DEBUG] [.p.internal.PersistenceManager:328 ] - Item group 'Heating' does not exist. 07:59:06.321 [DEBUG] [.p.internal.PersistenceManager:328 ] - Item group 'Temperature' does not exist. 07:59:06.323 [DEBUG] [.p.internal.PersistenceManager:328 ] - Item group 'Weather_Chart' does not exist. 07:59:06.323 [DEBUG] [.p.internal.PersistenceManager:428 ] - Scheduled strategy rrd4j.everyMinute with cron expression 0 * * * * ? 07:59:06.323 [DEBUG] [p.r.i.charts.RRD4jChartServlet:118 ] - Starting up rrd chart servlet at /rrdchart.png 07:59:06.339 [DEBUG] [.b.http.internal.HttpActivator:34 ] - HTTP binding has been started. 07:59:06.356 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'Weather_Temperature (Type=NumberItem, State=Uninitialized)' with 'HttpGenericBindingProvider' reader. 07:59:06.356 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'Weather_Pressure (Type=NumberItem, State=Uninitialized)' with 'HttpGenericBindingProvider' reader. 07:59:06.356 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'Weather_Humidity (Type=NumberItem, State=Uninitialized)' with 'HttpGenericBindingProvider' reader. 07:59:06.356 [DEBUG] [.t.internal.TinkerforgeBinding:194 ] - TFINIT connectModel called 07:59:06.356 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'Weather_WindSpeed (Type=NumberItem, State=Uninitialized)' with 'HttpGenericBindingProvider' reader. 07:59:06.356 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'Weather_WindDirection (Type=NumberItem, State=Uninitialized)' with 'HttpGenericBindingProvider' reader. 07:59:06.356 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value ainv2.callbackPeriod : 500 07:59:06.356 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value ainv2.movingAverage : 50 07:59:06.356 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value ainv2.threshold : 10 07:59:06.356 [INFO ] [.service.AbstractActiveService:169 ] - HTTP Refresh Service has been started 07:59:06.356 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value ainv2.type : bricklet_analoginv2 07:59:06.356 [DEBUG] [.i.LoggingPersistenceActivator:31 ] - Logging persistence bundle has been started. 07:59:06.372 [DEBUG] [.o.b.http.internal.HttpBinding:163 ] - item 'Weather_Humidity' is about to be refreshed now 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value ainv2.uid : vgh 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value hosts : 127.0.0.1 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io0.subid : out0 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io0.type : io4_actuator 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io0.uid : h2t 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io1.pullUpResistorEnabled : true 07:59:06.372 [DEBUG] [.p.internal.PersistenceManager:146 ] - Initializing logging persistence service. 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io1.subid : in1 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io1.type : io4sensor 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io1.uid : h2t 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io4.debouncePeriod : 200 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io4.type : bricklet_io4 07:59:06.372 [DEBUG] [.b.exec.internal.ExecActivator:34 ] - Exec binding has been started. 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value io4.uid : h2t 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value service.pid : org.openhab.tinkerforge 07:59:06.372 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value temperature.slowI2C : False 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value temperature.type : bricklet_temperature 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:179 ] - CONFIG key:value temperature.uid : tf7 07:59:06.391 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'Wohnzimmer_Stehlampe (Type=SwitchItem, State=Uninitialized)' with 'ExecGenericBindingProvider' reader. 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:148 ] - deviceConfig {ohId=temperature, subid=null, uid=tf7, slowI2C=False, type=bricklet_temperature} 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:723 ] - *** uid is "tf7" 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:771 ] - configuring feature: slowI2C for uid tf7 subid null 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:781 ] - CONFIG found boolean value 07:59:06.391 [DEBUG] [.o.b.ntp.internal.NtpActivator:31 ] - NTP binding has been started. 07:59:06.391 [INFO ] [.service.AbstractActiveService:169 ] - Exec Refresh Service has been started 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:148 ] - deviceConfig {ohId=ainv2, subid=null, uid=vgh, threshold=10, type=bricklet_analoginv2, callbackPeriod=500, movingAverage=50} 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:723 ] - *** uid is "vgh" 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:771 ] - configuring feature: threshold for uid vgh subid null 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:787 ] - CONFIG found BigDecimal value 07:59:06.391 [DEBUG] [o.b.t.i.c.ConfigurationHandler:771 ] - configuring feature: callbackPeriod for uid vgh subid null 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:771 ] - configuring feature: movingAverage for uid vgh subid null 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:148 ] - deviceConfig {ohId=io1, subid=in1, uid=h2t, pullUpResistorEnabled=true, type=io4sensor} 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:393 ] - CONFIG setting io4sensor config 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:723 ] - *** uid is "h2t" 07:59:06.406 [DEBUG] [xecPersistenceServiceActivator:33 ] - Exec persistence bundle has been started. 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:771 ] - configuring feature: pullUpResistorEnabled for uid h2t subid in1 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:781 ] - CONFIG found boolean value 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:148 ] - deviceConfig {ohId=io0, subid=out0, uid=h2t, type=io4_actuator} 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:378 ] - CONFIG setting io4_actuator config 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:723 ] - *** uid is "h2t" 07:59:06.406 [DEBUG] [.p.internal.PersistenceManager:146 ] - Initializing exec persistence service. 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:148 ] - deviceConfig {ohId=io4, subid=null, uid=h2t, debouncePeriod=200, type=bricklet_io4} 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:403 ] - CONFIG setting TFInterruptListenerConfiguration device_type bricklet_io4 07:59:06.406 [DEBUG] [.p.internal.PersistenceManager:428 ] - Scheduled strategy exec.everyMinute with cron expression 0 * * * * ? 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:723 ] - *** uid is "h2t" 07:59:06.406 [DEBUG] [o.b.t.i.c.ConfigurationHandler:771 ] - configuring feature: debouncePeriod for uid h2t subid null 07:59:06.406 [DEBUG] [.t.internal.TinkerforgeBinding:918 ] - CONFIG updated called 07:59:06.406 [DEBUG] [.t.internal.TinkerforgeBinding:952 ] - parse brickd config: host 127.0.0.1, port 4223, authkey is set false 07:59:06.422 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.422 [DEBUG] [.t.internal.TinkerforgeBinding:396 ] - TFINIT unhandled notifier org.openhab.binding.tinkerforge.internal.model.impl.EcosystemImpl@11c0f49 (logger: null) 07:59:06.422 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.422 [DEBUG] [.t.internal.TinkerforgeBinding:218 ] - TFINIT Tinkerforge new brickd for host: 127.0.0.1 07:59:06.422 [INFO ] [.service.AbstractActiveService:169 ] - Tinkerforge Refresh Service has been started 07:59:06.422 [INFO ] [.service.AbstractActiveService:169 ] - NTP Refresh Service has been started 07:59:06.438 [DEBUG] [inding.ntp.internal.NtpBinding:78 ] - There is no existing NTP binding configuration => refresh cycle aborted! 07:59:06.438 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:759 ] - TFINIT Connected listener was called. 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid 127.0.0.1:4223, subid isconnected 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid 127.0.0.1:4223, subid connected_counter 07:59:06.438 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:845 ] - TFINIT EnumerateListener was called, type 0 07:59:06.438 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:889 ] - TFINIT addDevice called for uid: 6k48Dc 07:59:06.438 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:845 ] - TFINIT EnumerateListener was called, type 0 07:59:06.438 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:889 ] - TFINIT addDevice called for uid: h2t 07:59:06.438 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:984 ] - addDevice BrickletIO4 07:59:06.438 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:1070 ] - TFINIT addDevice uid: h2t 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:344 ] - TFINIT notifier is Brickd 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:302 ] - TFINIT found MTFConfigConsumer id h2t 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:312 ] - TFINIT setting tfConfig for h2t 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:313 ] - TFINIT adding/enabling device h2t with config: org.openhab.binding.tinkerforge.internal.model.impl.TFInterruptListenerConfigurationImpl@af04f9 (debouncePeriod: 200) 07:59:06.438 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.454 [DEBUG] [.b.t.i.m.impl.MBrickletIO4Impl:777 ] - TFINIT BrickletIO4 setting debouncePeriod to 200 07:59:06.454 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:1076 ] - TFINIT initSubDevices uid: h2t 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:376 ] - TFINITSUB MSubdevices Notifier called 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:288 ] - TFINITSUB found no device configuration for uid "h2t" subid "in0" 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid in0 07:59:06.454 [DEBUG] [b.t.i.m.i.DigitalSensorIO4Impl:655 ] - TFINIT setting InterruptListener for DigitalSensorIO4: in0 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid in0 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:326 ] - TFINIT adding/enabling device: h2t in0 07:59:06.454 [DEBUG] [.b.t.i.m.impl.MBrickletIO4Impl:737 ] - TFINIT addSubDevice in0 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:376 ] - TFINITSUB MSubdevices Notifier called 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:302 ] - TFINIT found MTFConfigConsumer id h2t in1 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:312 ] - TFINIT setting tfConfig for h2t in1 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:313 ] - TFINIT adding/enabling device h2t in1 with config: org.openhab.binding.tinkerforge.internal.model.impl.TFIOSensorConfigurationImpl@983237 (pullUpResistorEnabled: true) 07:59:06.454 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid in1 07:59:06.470 [DEBUG] [b.t.i.m.i.DigitalSensorIO4Impl:644 ] - TFINIT found config for DigitalSensor 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.470 [DEBUG] [b.t.i.m.i.DigitalSensorIO4Impl:646 ] - TFINIT pull-up resistor state is true 07:59:06.470 [DEBUG] [b.t.i.m.i.DigitalSensorIO4Impl:655 ] - TFINIT setting InterruptListener for DigitalSensorIO4: in1 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid in1 07:59:06.470 [DEBUG] [.b.t.i.m.impl.MBrickletIO4Impl:737 ] - TFINIT addSubDevice in1 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:376 ] - TFINITSUB MSubdevices Notifier called 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:288 ] - TFINITSUB found no device configuration for uid "h2t" subid "in2" 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid in2 07:59:06.470 [DEBUG] [b.t.i.m.i.DigitalSensorIO4Impl:655 ] - TFINIT setting InterruptListener for DigitalSensorIO4: in2 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid in2 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:326 ] - TFINIT adding/enabling device: h2t in2 07:59:06.470 [DEBUG] [.b.t.i.m.impl.MBrickletIO4Impl:737 ] - TFINIT addSubDevice in2 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:376 ] - TFINITSUB MSubdevices Notifier called 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:288 ] - TFINITSUB found no device configuration for uid "h2t" subid "in3" 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.470 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid in3 07:59:06.470 [DEBUG] [b.t.i.m.i.DigitalSensorIO4Impl:655 ] - TFINIT setting InterruptListener for DigitalSensorIO4: in3 07:59:06.485 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid in3 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:326 ] - TFINIT adding/enabling device: h2t in3 07:59:06.486 [DEBUG] [.b.t.i.m.impl.MBrickletIO4Impl:737 ] - TFINIT addSubDevice in3 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:376 ] - TFINITSUB MSubdevices Notifier called 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:302 ] - TFINIT found MTFConfigConsumer id h2t out0 07:59:06.486 [ERROR] [.t.internal.TinkerforgeBinding:263 ] - CONFIG existing device is uid h2t subId in0 07:59:06.486 [ERROR] [.t.internal.TinkerforgeBinding:306 ] - CONFIG ignoring duplicate device uid: h2t, subId out0, genericId {}. Fix your openhab.cfg! 07:59:06.486 [DEBUG] [.b.t.i.m.impl.MBrickletIO4Impl:749 ] - TFINIT addSubDevice out0 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:376 ] - TFINITSUB MSubdevices Notifier called 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:288 ] - TFINITSUB found no device configuration for uid "h2t" subid "out1" 07:59:06.486 [DEBUG] [t.i.m.impl.DigitalActorIO4Impl:732 ] - TFINIT enable called on DigitalActorIO4 07:59:06.486 [DEBUG] [t.i.m.impl.DigitalActorIO4Impl:751 ] - TFINIT keep: no new port configuration set 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:751 ] - TFMODELUPDATE postupdate: found sensorValue: HIGH for item valve 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:326 ] - TFINIT adding/enabling device: h2t out1 07:59:06.486 [DEBUG] [.b.t.i.m.impl.MBrickletIO4Impl:749 ] - TFINIT addSubDevice out1 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:376 ] - TFINITSUB MSubdevices Notifier called 07:59:06.486 [DEBUG] [.t.internal.TinkerforgeBinding:288 ] - TFINITSUB found no device configuration for uid "h2t" subid "out2" 07:59:06.486 [DEBUG] [t.i.m.impl.DigitalActorIO4Impl:732 ] - TFINIT enable called on DigitalActorIO4 07:59:06.501 [DEBUG] [t.i.m.impl.DigitalActorIO4Impl:751 ] - TFINIT keep: no new port configuration set 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid out2 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:326 ] - TFINIT adding/enabling device: h2t out2 07:59:06.501 [DEBUG] [.b.t.i.m.impl.MBrickletIO4Impl:749 ] - TFINIT addSubDevice out2 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:376 ] - TFINITSUB MSubdevices Notifier called 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:288 ] - TFINITSUB found no device configuration for uid "h2t" subid "out3" 07:59:06.501 [DEBUG] [t.i.m.impl.DigitalActorIO4Impl:732 ] - TFINIT enable called on DigitalActorIO4 07:59:06.501 [DEBUG] [t.i.m.impl.DigitalActorIO4Impl:751 ] - TFINIT keep: no new port configuration set 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid h2t, subid out3 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:326 ] - TFINIT adding/enabling device: h2t out3 07:59:06.501 [DEBUG] [.b.t.i.m.impl.MBrickletIO4Impl:749 ] - TFINIT addSubDevice out3 07:59:06.501 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:845 ] - TFINIT EnumerateListener was called, type 0 07:59:06.501 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:889 ] - TFINIT addDevice called for uid: yg5 07:59:06.501 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:924 ] - TFINIT addDevice AmbientLighV2 07:59:06.501 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:1070 ] - TFINIT addDevice uid: yg5 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.501 [DEBUG] [.t.internal.TinkerforgeBinding:344 ] - TFINIT notifier is Brickd 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:288 ] - TFINITSUB found no device configuration for uid "yg5" subid "null" 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:666 ] - TFMODELUPDATE found no item for uid yg5, subid null 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:326 ] - TFINIT adding/enabling device: yg5 07:59:06.517 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:845 ] - TFINIT EnumerateListener was called, type 0 07:59:06.517 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:889 ] - TFINIT addDevice called for uid: vgh 07:59:06.517 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:1056 ] - addDevice BrickletAnalogInV2 07:59:06.517 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:1070 ] - TFINIT addDevice uid: vgh 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:344 ] - TFINIT notifier is Brickd 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:302 ] - TFINIT found MTFConfigConsumer id vgh 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:312 ] - TFINIT setting tfConfig for vgh 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:313 ] - TFINIT adding/enabling device vgh with config: org.openhab.binding.tinkerforge.internal.model.impl.TFAnalogInV2ConfigurationImpl@888446 (threshold: 10, callbackPeriod: 500) (movingAverage: 50) 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.517 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.517 [DEBUG] [.t.i.m.i.MBrickletAnalogInImpl:858 ] - TFINIT setting movingAverage: 50 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:751 ] - TFMODELUPDATE postupdate: found sensorValue: 10 for item humidity 07:59:06.533 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:845 ] - TFINIT EnumerateListener was called, type 0 07:59:06.533 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:889 ] - TFINIT addDevice called for uid: tf7 07:59:06.533 [DEBUG] [m.r.internal.engine.RuleEngine:305 ] - Executing rule 'water' 07:59:06.533 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:896 ] - TFINIT addDevice temperature 07:59:06.533 [DEBUG] [o.b.t.i.model.impl.MBrickdImpl:1070 ] - TFINIT addDevice uid: tf7 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:344 ] - TFINIT notifier is Brickd 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:302 ] - TFINIT found MTFConfigConsumer id tf7 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:312 ] - TFINIT setting tfConfig for tf7 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:313 ] - TFINIT adding/enabling device tf7 with config: org.openhab.binding.tinkerforge.internal.model.impl.TFTemperatureConfigurationImpl@15d18c7 (threshold: null, callbackPeriod: 0) (slowI2C: false) 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.533 [DEBUG] [i.m.i.MBrickletTemperatureImpl:870 ] - working with I2C fast mode 07:59:06.533 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.551 [DEBUG] [.t.internal.TinkerforgeBinding:751 ] - TFMODELUPDATE postupdate: found sensorValue: 22.87 for item temperature 07:59:06.681 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:06.681 [WARN ] [.httpclient.HttpMethodDirector:697 ] - Unable to respond to any of these challenges: {oauth=OAuth oauth_problem="OST_OAUTH_PARAMETER_ABSENT_ERROR", realm="yahooapis.com"} 07:59:06.681 [DEBUG] [.t.internal.TinkerforgeBinding:751 ] - TFMODELUPDATE postupdate: found sensorValue: 20 for item humidity 07:59:06.681 [WARN ] [g.openhab.io.net.http.HttpUtil:205 ] - Method failed: HTTP/1.1 401 Unauthorized 07:59:06.681 [DEBUG] [m.r.internal.engine.RuleEngine:305 ] - Executing rule 'water' 07:59:06.681 [DEBUG] [.i.s.XsltTransformationService:74 ] - about to transform 'Please provide valid credentials. OAuth oauth_problem="OST_OAUTH_PARAMETER_ABSENT_ERROR", realm="yahooapis.com"Please provide valid credentials. OAuth oauth_problem="OST_OAUTH_PARAMETER_ABSENT_ERROR", realm="yahooapis.com"' by the function 'javax.xml.transform.stream.StreamSource@17d7b69' 07:59:06.845 [DEBUG] [.i.s.XsltTransformationService:89 ] - transformation resulted in ' ' 07:59:06.845 [DEBUG] [.o.b.http.internal.HttpBinding:197 ] - transformed response is ' ' 07:59:06.845 [DEBUG] [.o.b.http.internal.HttpBinding:262 ] - Couldn't create state of type 'class org.openhab.core.library.items.NumberItem' for value ' ' 07:59:06.845 [DEBUG] [.o.b.http.internal.HttpBinding:163 ] - item 'Weather_Temperature' is about to be refreshed now 07:59:06.845 [DEBUG] [ore.internal.items.ItemUpdater:73 ] - Received update of a not accepted type (StringType) for item Weather_Humidity 07:59:07.195 [WARN ] [.httpclient.HttpMethodDirector:697 ] - Unable to respond to any of these challenges: {oauth=OAuth oauth_problem="OST_OAUTH_PARAMETER_ABSENT_ERROR", realm="yahooapis.com"} 07:59:07.195 [WARN ] [g.openhab.io.net.http.HttpUtil:205 ] - Method failed: HTTP/1.1 401 Unauthorized 07:59:07.195 [DEBUG] [.i.s.XsltTransformationService:74 ] - about to transform 'Please provide valid credentials. OAuth oauth_problem="OST_OAUTH_PARAMETER_ABSENT_ERROR", realm="yahooapis.com"Please provide valid credentials. OAuth oauth_problem="OST_OAUTH_PARAMETER_ABSENT_ERROR", realm="yahooapis.com"' by the function 'javax.xml.transform.stream.StreamSource@1a8f488' 07:59:07.195 [DEBUG] [.t.internal.TinkerforgeBinding:239 ] - TinkerforgeNotifier was notified 07:59:07.211 [DEBUG] [.t.internal.TinkerforgeBinding:751 ] - TFMODELUPDATE postupdate: found sensorValue: 10 for item humidity 07:59:07.211 [DEBUG] [m.r.internal.engine.RuleEngine:305 ] - Executing rule 'water' 07:59:07.211 [DEBUG] [.i.s.XsltTransformationService:89 ] - transformation resulted in ' ' 07:59:07.211 [DEBUG] [.o.b.http.internal.HttpBinding:197 ] - transformed response is '