Receiving multiple events in persistence service.
206 views
Skip to first unread message
Michael Dave
Jul 9, 2015, 6:17:29 AM7/9/15
to open...@googlegroups.com
Hi
I have OH2 alpha2 code running on my windows machine with one hue lamp added .
In my persistence service why i am receiving update event twice for each item ?
Regards
Michael Dave
Kai Kreuzer
Jul 9, 2015, 6:48:20 AM7/9/15
to open...@googlegroups.com
Can you provide logs?
--
You received this message because you are subscribed to the Google Groups "openhab2" group.
To unsubscribe from this group and stop receiving emails from it, send an email to openhab2+u...@googlegroups.com.
To post to this group, send email to open...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/openhab2/56d63422-6f7c-4169-9442-95158942f242%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.
Michael Dave
Jul 9, 2015, 8:01:20 AM7/9/15
to open...@googlegroups.com
Here is a code snippet in persistence service where I am seeing this issue:
public void store(Item item, String alias)
{
/**
* This method receives item with their latest update.
* It does not consider item with UnDefType state.
*
*/
System.out.println("Item Name : " + item.getName());// This log prints for same item twice.
}
If I turn on the hue bulb for example, breakpoint on store method in persistense service hits twice for each item.
I have put some logs around my code in store method which prints twice for each item, here are the logs for hue bulb color item:
Item Name : hue_LCT002_00178811f684_1_color
Item Name : hue_LCT002_00178811f684_1_color
2015-07-09 17:19:33 [INFO ] [runtime.busevents :27 ] - hue_LCT002_00178811f684_1_color state updated to 86,34,94
Michael Dave
Jul 9, 2015, 9:38:46 AM7/9/15
to open...@googlegroups.com
Please see the duplicate event logs at the bottom of this post in bold red.
2015-07-09 19:00:56 [DEBUG] [re.init.internal.InitActivator:84 ] - UUID file already exists at 'F:\dp\openhab2\openhab2-master\distribution\openhabhome\userdata\id\uuid' with content '05314db0-7abb-4725-99ec-570df6a30c45'2015-07-09 19:00:56 [DEBUG] [re.init.internal.InitActivator:119 ] - Created file 'F:\dp\openhab2\openhab2-master\distribution\openhabhome\userdata\id\version' with content '2.0.0'2015-07-09 19:00:56 [WARN ] [.j.s.handler.RequestLogHandler:137 ] - !RequestLog2015-07-09 19:00:58 [DEBUG] [s.m.i.r.i.ItemRuntimeActivator:23 ] - Registered 'item' configuration parser2015-07-09 19:00:58 [INFO ] [uartz.impl.StdSchedulerFactory:1184 ] - Using default implementation for ThreadExecutor2015-07-09 19:00:58 [INFO ] [rtz.core.SchedulerSignalerImpl:61 ] - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl2015-07-09 19:00:58 [INFO ] [rg.quartz.core.QuartzScheduler:240 ] - Quartz Scheduler v.2.2.1 created.2015-07-09 19:00:58 [INFO ] [org.quartz.simpl.RAMJobStore :155 ] - RAMJobStore initialized.2015-07-09 19:00:58 [INFO ] [rg.quartz.core.QuartzScheduler:305 ] - Scheduler meta-data: Quartz Scheduler (v2.2.1) 'openHAB-job-scheduler' with instanceId 'NON_CLUSTERED'Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.NOT STARTED.Currently in standby mode.Number of jobs executed: 0Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 2 threads.Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.2015-07-09 19:00:58 [INFO ] [uartz.impl.StdSchedulerFactory:1339 ] - Quartz scheduler 'openHAB-job-scheduler' initialized from specified file: './runtime/etc/quartz.properties'2015-07-09 19:00:58 [INFO ] [uartz.impl.StdSchedulerFactory:1343 ] - Quartz scheduler version: 2.2.1!SESSION 2015-07-09 19:00:54.897 -----------------------------------------------eclipse.buildId=unknownjava.version=1.8.0_25java.vendor=Oracle CorporationBootLoader constants: OS=win32, ARCH=x86_64, WS=win32, NL=en_USFramework arguments: -applicationCommand-line arguments: -application -data F:\dp\openhab2\openhab2-master/../runtime-org.openhab.runtime.product.product -dev file:F:/dp/openhab2/openhab2-master/.metadata/.plugins/org.eclipse.pde.core/openHAB_Runtime/dev.properties -os win32 -ws win32 -arch x86_64 -consoleLog -console!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:00:58.863!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.persistence.manager. The reference is: Reference[name = ModelRepository, interface = org.eclipse.smarthome.model.core.ModelRepository, policy = dynamic, cardinality = 0..1, target = null, bind = setModelRepository, unbind = unsetModelRepository]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:00:58.869!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.persistence.manager. The reference is: Reference[name = PersistenceService, interface = org.eclipse.smarthome.core.persistence.PersistenceService, policy = dynamic, cardinality = 0..n, target = null, bind = addPersistenceService, unbind = removePersistenceService]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:00:58.879!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.persistence.manager. The reference is: Reference[name = ItemRegistry, interface = org.eclipse.smarthome.core.items.ItemRegistry, policy = dynamic, cardinality = 0..1, target = null, bind = setItemRegistry, unbind = unsetItemRegistry]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:00:58.887!MESSAGE Could not bind a reference of component org.eclipse.smarthome.model.persistence.extensions. The reference is: Reference[name = PersistenceService, interface = org.eclipse.smarthome.core.persistence.PersistenceService, policy = dynamic, cardinality = 0..n, target = null, bind = addPersistenceService, unbind = removePersistenceService]2015-07-09 19:00:59 [DEBUG] [.i.PersistenceRuntimeActivator:23 ] - Registered 'persistence' configuration parser2015-07-09 19:00:59 [DEBUG] [s.m.r.r.i.RuleRuntimeActivator:35 ] - Registered 'rule' configuration parser2015-07-09 19:01:00 [DEBUG] [m.s.r.i.ScriptRuntimeActivator:22 ] - Registered 'script' configuration parser2015-07-09 19:01:00 [DEBUG] [.s.r.i.SitemapRuntimeActivator:23 ] - Registered 'sitemap' configuration parser2015-07-09 19:01:00 [DEBUG] [.m.t.r.i.ThingRuntimeActivator:23 ] - Registered 'thing' configuration parserosgi>!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:01.853!MESSAGE Could not bind a reference of component ConfigDescriptionRegistry. The reference is: Reference[name = ConfigDescriptionProvider, interface = org.eclipse.smarthome.config.core.ConfigDescriptionProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addConfigDescriptionProvider, unbind = removeConfigDescriptionProvider]2015-07-09 19:01:01 [DEBUG] [s.c.d.AbstractDiscoveryService:346 ] - Background discovery for discovery service 'org.eclipse.smarthome.binding.yahooweather.discovery.YahooWeatherDiscoveryService' enabled.2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'services.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'classicui.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'denon.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'enocean.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'epsonprojector.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'freeswitch.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'heatmiser.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'homematic.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'http.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'ihc.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'insteonplm.cfg'2015-07-09 19:01:02 [ERROR] [d.YahooWeatherDiscoveryService:70 ] - Error accessing url 'http://freegeoip.net/json/' : Server returned HTTP response code: 503 for URL: http://freegeoip.net/json/2015-07-09 19:01:02 [DEBUG] [d.YahooWeatherDiscoveryService:141 ] - Error while getting location ID: null2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'jpa.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'knx.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'logging.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'mail.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'marytts.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'milight.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'mysql.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'nibeheatpump.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'ntp.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'onewire.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'onkyo.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'openenergymonitor.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'owserver.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'rrd4j.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'runtime.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'snmp.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'swegonventilation.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'systeminfo.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'tinkerforge.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'weather.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'xmpp.cfg'2015-07-09 19:01:02 [DEBUG] [.c.d.internal.ConfigDispatcher:222 ] - Processing config file 'zwave.cfg'2015-07-09 19:01:03 [DEBUG] [.s.core.internal.CoreActivator:30 ] - Core bundle has been started.!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:03.161!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.binding.BindingInfoRegistry. The reference is: Reference[name = BindingInfoProvider, interface = org.eclipse.smarthome.core.binding.BindingInfoProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addBindingInfoProvider, unbind = removeBindingInfoProvider]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:03.336!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.itemregistry. The reference is: Reference[name = ItemProvider, interface = org.eclipse.smarthome.core.items.ItemProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addProvider, unbind = removeProvider]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:03.356!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.itemregistry. The reference is: Reference[name = EventPublisher, interface = org.eclipse.smarthome.core.events.EventPublisher, policy = dynamic, cardinality = 0..1, target = null, bind = setEventPublisher, unbind = unsetEventPublisher]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:03.372!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.itemregistry. The reference is: Reference[name = StateDescriptionProvider, interface = org.eclipse.smarthome.core.types.StateDescriptionProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addStateDescriptionProvider, unbind = removeStateDescriptionProvider]2015-07-09 19:01:03 [DEBUG] [.e.s.c.a.i.AutoUpdateActivator:29 ] - AutoUpdate binding has been started.2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/binding/binding.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:103 ] - Create an empty XmlDocumentProvider for the module 'org.eclipse.smarthome.binding.hue'.2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/binding/binding.xml' in module 'org.eclipse.smarthome.binding.yahooweather'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:103 ] - Create an empty XmlDocumentProvider for the module 'org.eclipse.smarthome.binding.yahooweather'.2015-07-09 19:01:03 [DEBUG] [o.e.s.c.s.i.SchedulerActivator:34 ] - Scheduler has been started.2015-07-09 19:01:03 [INFO ] [rg.quartz.core.QuartzScheduler:575 ] - Scheduler openHAB-job-scheduler_$_NON_CLUSTERED started.!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:03.512!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.thing.ThingRegistry. The reference is: Reference[name = ThingProvider, interface = org.eclipse.smarthome.core.thing.ThingProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addProvider, unbind = removeProvider]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:03.531!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.thing.link.ItemChannelLinkRegistry. The reference is: Reference[name = ItemChannelLinkProvider, interface = org.eclipse.smarthome.core.thing.link.ItemChannelLinkProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addProvider, unbind = removeProvider]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:03.538!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.thing.type.ThingTypeRegistry. The reference is: Reference[name = ThingTypeProvider, interface = org.eclipse.smarthome.core.thing.binding.ThingTypeProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addThingTypeProvider, unbind = removeThingTypeProvider]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:03.550!MESSAGE Could not bind a reference of component org.eclipse.smarthome.core.thing.link.ItemThingLinkRegistry. The reference is: Reference[name = ItemThingLinkProvider, interface = org.eclipse.smarthome.core.thing.link.ItemThingLinkProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addProvider, unbind = removeProvider]2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/bridge.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:103 ] - Create an empty XmlDocumentProvider for the module 'org.eclipse.smarthome.binding.hue'.2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/channels.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/Classic_A60_RGBW.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LCT001.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LCT002.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LCT003.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LLC001.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LLC006.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LLC007.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LLC010.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LLC011.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LLC012.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LLC013.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LST001.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LWB004.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/LWL001.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/Surface_Light_TW.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/ZLL_Light.xml' in module 'org.eclipse.smarthome.binding.hue'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/thing-types.xml' in module 'org.eclipse.smarthome.binding.yahooweather'...2015-07-09 19:01:03 [DEBUG] [c.x.o.XmlDocumentBundleTracker:103 ] - Create an empty XmlDocumentProvider for the module 'org.eclipse.smarthome.binding.yahooweather'.2015-07-09 19:01:03 [DEBUG] [.c.t.i.TransformationActivator:36 ] - Transformation Service has been started.2015-07-09 19:01:03 [DEBUG] [.e.s.i.m.i.MultimediaActivator:32 ] - Multimedia I/O bundle has been started.2015-07-09 19:01:03 [DEBUG] [io.rest.internal.RESTActivator:32 ] - REST API has been started.!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:03.886!MESSAGE Could not bind a reference of component org.eclipse.smarthome.io.rest.root. The reference is: Reference[name = RESTResource, interface = org.eclipse.smarthome.io.rest.RESTResource, policy = dynamic, cardinality = 0..n, target = null, bind = addRESTResource, unbind = removeRESTResource]2015-07-09 19:01:03 [DEBUG] [.i.r.sse.internal.SseActivator:46 ] - SSE API - SseFeature registered.2015-07-09 19:01:03 [DEBUG] [.i.r.sse.internal.SseActivator:55 ] - SSE API has been started.2015-07-09 19:01:04 [DEBUG] [s.i.t.m.internal.MDNSActivator:27 ] - mDNS service has been started.!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:04.096!MESSAGE Could not bind a reference of component org.eclipse.smarthome.io.transport.mdns. The reference is: Reference[name = MDNSClient, interface = org.eclipse.smarthome.io.transport.mdns.MDNSClient, policy = dynamic, cardinality = 0..1, target = null, bind = setMDNSClient, unbind = unsetMDNSClient]2015-07-09 19:01:04 [DEBUG] [.i.t.m.internal.MDNSClientImpl:37 ] - mDNS service has been started2015-07-09 19:01:04 [DEBUG] [i.t.m.internal.MDNSServiceImpl:49 ] - Registering new service _openhab-server._tcp.local. at port 80802015-07-09 19:01:04 [DEBUG] [.io.transport.mqtt.MqttService:118 ] - Starting MQTT Service...2015-07-09 19:01:05 [DEBUG] [.s.s.mapdb.MapDbStorageService:50 ] - Opened MapDB file at 'F:\dp\openhab2\openhab2-master\distribution\openhabhome\userdata\mapdb\storage.mapdb'.2015-07-09 19:01:05 [DEBUG] [.c.c.registry.AbstractRegistry:184 ] - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.2015-07-09 19:01:05 [DEBUG] [.c.c.registry.AbstractRegistry:184 ] - Provider 'org.eclipse.smarthome.core.thing.ManagedThingProvider' has been added.2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:414 ] - Thing handler factory 'HueThingHandlerFactory' added2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:414 ] - Thing handler factory 'YahooWeatherHandlerFactory' added2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:278 ] - Thing 'hue:LCT002:00178811f684:1' is tracked by ThingManager.2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:381 ] - Creating handler for thing 'hue:LCT002:00178811f684:1'.2015-07-09 19:01:05 [DEBUG] [.b.hue.handler.HueLightHandler:96 ] - Initializing hue light handler.2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:278 ] - Thing 'hue:bridge:00178811f684' is tracked by ThingManager.2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:381 ] - Creating handler for thing 'hue:bridge:00178811f684'.2015-07-09 19:01:05 [DEBUG] [b.hue.handler.HueBridgeHandler:232 ] - Initializing hue bridge handler.2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:71 ] - Thing handler for thing 'hue:LCT002:00178811f684:1' added.2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:191 ] - Assigning handler for thing 'hue:LCT002:00178811f684:1'.2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:71 ] - Thing handler for thing 'hue:bridge:00178811f684' added.2015-07-09 19:01:05 [DEBUG] [.c.thing.internal.ThingManager:191 ] - Assigning handler for thing 'hue:bridge:00178811f684'.2015-07-09 19:01:05 [DEBUG] [.c.t.internal.ThingLinkManager:298 ] - Adding linked item 'hue_LCT002_00178811f684_1_color' to channel 'hue:LCT002:00178811f684:1:color'.2015-07-09 19:01:05 [DEBUG] [.c.t.internal.ThingLinkManager:298 ] - Adding linked item 'hue_LCT002_00178811f684_1_color_temperature' to channel 'hue:LCT002:00178811f684:1:color_temperature'.2015-07-09 19:01:05 [DEBUG] [.c.t.internal.ThingLinkManager:298 ] - Adding linked item 'hue_LCT002_00178811f684_1_switch' to channel 'hue:LCT002:00178811f684:1:switch'.2015-07-09 19:01:05 [DEBUG] [.c.c.registry.AbstractRegistry:184 ] - Provider 'org.eclipse.smarthome.core.thing.link.ManagedItemChannelLinkProvider' has been added.2015-07-09 19:01:05 [DEBUG] [.c.t.internal.ThingLinkManager:309 ] - Assigning linked group item 'hue_LCT002_00178811f684_1' to thing 'hue:LCT002:00178811f684:1'.2015-07-09 19:01:05 [DEBUG] [.c.t.internal.ThingLinkManager:309 ] - Assigning linked group item 'hue_bridge_00178811f684' to thing 'hue:bridge:00178811f684'.2015-07-09 19:01:05 [DEBUG] [.c.c.registry.AbstractRegistry:184 ] - Provider 'org.eclipse.smarthome.core.thing.link.ManagedItemThingLinkProvider' has been added.!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:06.134!MESSAGE Could not bind a reference of component org.eclipse.smarthome.ui. The reference is: Reference[name = ItemUIProvider, interface = org.eclipse.smarthome.ui.items.ItemUIProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addItemUIProvider, unbind = removeItemUIProvider]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:06.149!MESSAGE Could not bind a reference of component org.eclipse.smarthome.ui. The reference is: Reference[name = IconProvider, interface = org.eclipse.smarthome.ui.icon.IconProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addIconProvider, unbind = removeIconProvider]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:06.170!MESSAGE Could not bind a reference of component org.eclipse.smarthome.io.rest.sitemap. The reference is: Reference[name = SitemapProvider, interface = org.eclipse.smarthome.model.sitemap.SitemapProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addSitemapProvider, unbind = removeSitemapProvider]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:06.180!MESSAGE Could not bind a reference of component org.eclipse.smarthome.ui.chart. The reference is: Reference[name = ChartProvider, interface = org.eclipse.smarthome.ui.chart.ChartProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addChartProvider, unbind = removeChartProvider]2015-07-09 19:01:06 [DEBUG] [ui.internal.chart.ChartServlet:122 ] - Starting up chart servlet at /chart!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:06.215!MESSAGE Could not bind a reference of component org.eclipse.smarthome.ui.chart.defaultprovider. The reference is: Reference[name = PersistenceService, interface = org.eclipse.smarthome.core.persistence.PersistenceService, policy = dynamic, cardinality = 0..n, target = null, bind = addPersistenceService, unbind = removePersistenceService]2015-07-09 19:01:06 [DEBUG] [u.i.chart.DefaultChartProvider:97 ] - Starting up default chart provider.!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:06.266!MESSAGE Could not bind a reference of component org.eclipse.smarthome.ui.classic.pagerenderer. The reference is: Reference[name = WidgetRenderer, interface = org.eclipse.smarthome.ui.classic.render.WidgetRenderer, policy = dynamic, cardinality = 0..n, target = null, bind = addWidgetRenderer, unbind = removeWidgetRenderer]2015-07-09 19:01:06 [DEBUG] [o.e.s.u.c.i.servlet.CmdServlet:54 ] - Starting up CMD servlet at /CMD2015-07-09 19:01:06 [DEBUG] [s.ui.icon.internal.IconServlet:70 ] - Starting up icon servlet at /images2015-07-09 19:01:06 [INFO ] [ui.internal.servlet.PaperUIApp:31 ] - Started Paper UI at /ui2015-07-09 19:01:06 [DEBUG] [s.c.d.AbstractDiscoveryService:346 ] - Background discovery for discovery service 'org.eclipse.smarthome.config.discovery.internal.UpnpDiscoveryService' enabled.2015-07-09 19:01:06 [DEBUG] [e.s.i.t.upnp.UpnpIOServiceImpl:152 ] - Starting UPnP IO service...2015-07-09 19:01:06 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/binding/binding.xml' in module 'org.openhab.binding.astro'...2015-07-09 19:01:06 [DEBUG] [c.x.o.XmlDocumentBundleTracker:103 ] - Create an empty XmlDocumentProvider for the module 'org.openhab.binding.astro'.2015-07-09 19:01:06 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/channels.xml' in module 'org.openhab.binding.astro'...2015-07-09 19:01:07 [DEBUG] [c.x.o.XmlDocumentBundleTracker:103 ] - Create an empty XmlDocumentProvider for the module 'org.openhab.binding.astro'.2015-07-09 19:01:07 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/moon.xml' in module 'org.openhab.binding.astro'...2015-07-09 19:01:07 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/thing/sun.xml' in module 'org.openhab.binding.astro'...2015-07-09 19:01:07 [DEBUG] [c.x.o.XmlDocumentBundleTracker:169 ] - Reading the XML document '/ESH-INF/config/config.xml' in module 'org.openhab.binding.astro'...2015-07-09 19:01:07 [DEBUG] [c.x.o.XmlDocumentBundleTracker:103 ] - Create an empty XmlDocumentProvider for the module 'org.openhab.binding.astro'.2015-07-09 19:01:07 [DEBUG] [.c.thing.internal.ThingManager:414 ] - Thing handler factory 'AstroHandlerFactory' added!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:07.364!MESSAGE Could not bind a reference of component org.openhab.core.compat1x.actionservicefactory. The reference is: Reference[name = ActionService, interface = org.openhab.core.scriptengine.action.ActionService, policy = dynamic, cardinality = 0..n, target = null, bind = addActionService, unbind = removeActionService]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:08.203!MESSAGE Could not bind a reference of component org.openhab.core.compat1x.autoupdateproviderdelegate. The reference is: Reference[name = AutoUpdateBindingProvider, interface = org.openhab.core.autoupdate.AutoUpdateBindingProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addAutoUpdateBindingProvider, unbind = removeAutoUpdateBindingProvider]2015-07-09 19:01:08 [DEBUG] [s.c.d.internal.PersistentInbox:135 ] - Discovery result with thing 'hue:bridge:00178811f684' not added as inbox entry. It is already present as thing in the ThingRegistry.!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:08.752!MESSAGE Could not bind a reference of component org.openhab.core.compat1x.bindingconfigreaderfactory. The reference is: Reference[name = BindingConfigReader, interface = org.openhab.model.item.binding.BindingConfigReader, policy = dynamic, cardinality = 0..n, target = null, bind = addBindingConfigReader, unbind = removeBindingConfigReader]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:10.750!MESSAGE Could not bind a reference of component org.openhab.core.compat1x.chartproviderfactory. The reference is: Reference[name = ChartProvider, interface = org.openhab.ui.chart.ChartProvider, policy = dynamic, cardinality = 0..n, target = null, bind = addChartProvider, unbind = removeChartProvider]2015-07-09 19:01:10 [DEBUG] [s.c.d.internal.PersistentInbox:127 ] - Updated discovery result for 'hue:bridge:001788136e30'.!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:12.242!MESSAGE Could not bind a reference of component org.openhab.core.compat1x.persistenceservicefactory. The reference is: Reference[name = PersistenceService, interface = org.openhab.core.persistence.PersistenceService, policy = dynamic, cardinality = 0..n, target = null, bind = addPersistenceService, unbind = removePersistenceService]!ENTRY org.eclipse.equinox.ds 1 0 2015-07-09 19:01:12.624!MESSAGE Could not bind a reference of component org.openhab.core.compat1x.ttsservicefactory. The reference is: Reference[name = TTSService, interface = org.openhab.io.multimedia.tts.TTSService, policy = dynamic, cardinality = 0..n, target = null, bind = addTTSService, unbind = removeTTSService]2015-07-09 19:01:12 [DEBUG] [.rrd4j.internal.RRD4jActivator:31 ] - RRD4j persistence bundle has been started.2015-07-09 19:01:12 [DEBUG] [o.e.s.m.p.i.PersistenceManager:142 ] - Initializing rrd4j persistence service.2015-07-09 19:01:13 [DEBUG] [.p.rrd4j.internal.RRD4jService:352 ] - using default configuration only2015-07-09 19:01:13 [DEBUG] [p.r.i.charts.RRD4jChartServlet:118 ] - Starting up rrd chart servlet at /rrdchart.png2015-07-09 19:01:13 [INFO ] [.u.d.internal.DashboardService:55 ] - Started dashboard at /start2015-07-09 19:01:14 [DEBUG] [.c.c.registry.AbstractRegistry:184 ] - Provider 'org.eclipse.smarthome.model.item.internal.GenericItemProvider' has been added.2015-07-09 19:01:14 [INFO ] [rg.quartz.core.QuartzScheduler:2311 ] - JobFactory set to: org.eclipse.smarthome.model.rule.runtime.internal.engine.GuiceAwareJobFactory@78be3ea82015-07-09 19:01:14 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:99 ] - Started rule engine2015-07-09 19:01:14 [INFO ] [.s.u.c.i.servlet.WebAppServlet:85 ] - Started Classic UI at /classicui2015-07-09 19:01:14 [DEBUG] [e.s.m.t.i.GenericThingProvider:562 ] - ThingHandlerFactory added org.eclipse.smarthome.binding.hue.internal.HueThingHandlerFactory@7e20d2f82015-07-09 19:01:14 [DEBUG] [e.s.m.t.i.GenericThingProvider:562 ] - ThingHandlerFactory added org.eclipse.smarthome.binding.yahooweather.internal.YahooWeatherHandlerFactory@4027abbd2015-07-09 19:01:14 [DEBUG] [e.s.m.t.i.GenericThingProvider:562 ] - ThingHandlerFactory added org.openhab.binding.astro.internal.AstroHandlerFactory@60ed4fca2015-07-09 19:01:14 [DEBUG] [.c.c.registry.AbstractRegistry:184 ] - Provider 'org.eclipse.smarthome.model.thing.internal.GenericThingProvider' has been added.2015-07-09 19:01:14 [DEBUG] [.c.c.registry.AbstractRegistry:184 ] - Provider 'org.eclipse.smarthome.model.thing.internal.GenericItemChannelLinkProvider' has been added.2015-07-09 19:01:14 [DEBUG] [ui.internal.proxy.ProxyServlet:94 ] - Starting up proxy servlet at /proxy2015-07-09 19:01:15 [INFO ] [.o.core.internal.CoreActivator:52 ] - openHAB runtime has been started (v2.0.0).2015-07-09 19:01:15 [DEBUG] [.o.core.internal.CoreActivator:62 ] - Startup took 20550 ms2015-07-09 19:01:15 [INFO ] [.e.s.m.c.i.ModelRepositoryImpl:86 ] - Loading model 'demo.sitemap'2015-07-09 19:01:15 [INFO ] [.e.s.m.c.i.ModelRepositoryImpl:86 ] - Loading model 'demo.things'2015-07-09 19:01:15 [DEBUG] [e.s.m.t.i.GenericThingProvider:94 ] - Read things from model 'demo.things'2015-07-09 19:01:15 [WARN ] [.e.s.m.c.i.ModelRepositoryImpl:64 ] - Configuration model 'demo.things' is either empty or cannot be parsed correctly!2015-07-09 19:01:15 [INFO ] [.e.s.m.c.i.ModelRepositoryImpl:86 ] - Loading model 'logging.persist'2015-07-09 19:01:16 [INFO ] [.e.s.m.c.i.ModelRepositoryImpl:86 ] - Loading model 'rrd4j.persist'2015-07-09 19:01:16 [DEBUG] [o.e.s.m.p.i.PersistenceManager:317 ] - Item 'DemoSwitch' does not exist.2015-07-09 19:01:16 [DEBUG] [o.e.s.m.p.i.PersistenceManager:317 ] - Item 'NoOfLights' does not exist.2015-07-09 19:01:16 [DEBUG] [o.e.s.m.p.i.PersistenceManager:317 ] - Item 'Window_GF_Toilet' does not exist.2015-07-09 19:01:16 [DEBUG] [o.e.s.m.p.i.PersistenceManager:330 ] - Item group 'Heating' does not exist.2015-07-09 19:01:16 [DEBUG] [o.e.s.m.p.i.PersistenceManager:381 ] - Restored item state from 'Jul 9, 2015 6:58:10 PM' for item 'hue_LCT002_00178811f684_1_color' -> '0.939999999999999946709294817992486059665679931640625'2015-07-09 19:01:16 [DEBUG] [o.e.s.m.p.i.PersistenceManager:381 ] - Restored item state from 'Jul 9, 2015 6:58:09 PM' for item 'hue_LCT002_00178811f684_1_color_temperature' -> '0.450000000000000011102230246251565404236316680908203125'2015-07-09 19:01:16 [INFO ] [.e.s.m.c.i.ModelRepositoryImpl:86 ] - Loading model 'demo.rules'2015-07-09 19:01:16 [DEBUG] [b.hue.handler.HueBridgeHandler:85 ] - Connection to Hue Bridge 192.168.11.182 established.2015-07-09 19:01:16 [DEBUG] [b.hue.handler.HueBridgeHandler:274 ] - Bridge connection resumed. Updating thing status to ONLINE.2015-07-09 19:01:16 [DEBUG] [.c.thing.internal.ThingManager:140 ] - Status of hue:bridge:00178811f684 changed to ONLINE2015-07-09 19:01:16 [DEBUG] [.b.hue.handler.HueLightHandler:96 ] - Initializing hue light handler.2015-07-09 19:01:16 [DEBUG] [.c.thing.internal.ThingManager:140 ] - Status of hue:LCT002:00178811f684:1 changed to ONLINE2015-07-09 19:01:16 [DEBUG] [b.hue.handler.HueBridgeHandler:111 ] - Hue light 1 added.2015-07-09 19:01:16 [DEBUG] [s.c.d.internal.PersistentInbox:135 ] - Discovery result with thing 'hue:LCT002:00178811f684:1' not added as inbox entry. It is already present as thing in the ThingRegistry.2015-07-09 19:01:16 [DEBUG] [.c.thing.internal.ThingManager:140 ] - Status of hue:LCT002:00178811f684:1 changed to ONLINE2015-07-09 19:01:16 [DEBUG] [b.hue.handler.HueBridgeHandler:111 ] - Hue light 2 added.******** Item Name hue_LCT002_00178811f684_1_color Item state 86,34,02015-07-09 19:01:16 [DEBUG] [.p.rrd4j.internal.RRD4jService:120 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '0.939999999999999946709294817992486059665679931640625' in rrd4j database2015-07-09 19:01:16 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '86,34,0' in rrd4j database2015-07-09 19:01:16 [DEBUG] [s.c.d.internal.PersistentInbox:127 ] - Updated discovery result for 'hue:LCT002:00178811f684:2'.2015-07-09 19:01:16 [DEBUG] [b.hue.handler.HueBridgeHandler:111 ] - Hue light 3 added.2015-07-09 19:01:16 [DEBUG] [s.c.d.internal.PersistentInbox:127 ] - Updated discovery result for 'hue:LCT002:00178811f684:3'.2015-07-09 19:01:16 [DEBUG] [i.t.m.internal.MDNSServiceImpl:49 ] - Registering new service _openhab-server-ssl._tcp.local. at port 8443******** Item Name hue_LCT002_00178811f684_1_color Item state 86,34,02015-07-09 19:01:17 [DEBUG] [s.m.r.r.i.e.RuleTriggerManager:491 ] - Scheduled rule Set random room temperatures with cron expression 0 0/5 * * * ?2015-07-09 19:01:17 [DEBUG] [s.m.r.r.i.e.RuleTriggerManager:491 ] - Scheduled rule Update max and min temperatures with cron expression 0 0 0 * * ?2015-07-09 19:01:17 [INFO ] [.e.s.m.c.i.ModelRepositoryImpl:86 ] - Loading model 'demo.script'2015-07-09 19:01:17 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '86,34,0' in rrd4j database2015-07-09 19:01:17 [INFO ] [runtime.busevents :27 ] - hue_LCT002_00178811f684_1_color state updated to 86,34,0******** Item Name hue_LCT002_00178811f684_1_color_temperature Item state 452015-07-09 19:01:17 [DEBUG] [.p.rrd4j.internal.RRD4jService:120 ] - Stored 'hue_LCT002_00178811f684_1_color_temperature' with state '0.450000000000000011102230246251565404236316680908203125' in rrd4j database2015-07-09 19:01:17 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color_temperature' with state '45' in rrd4j database2015-07-09 19:01:17 [INFO ] [.e.s.m.c.i.ModelRepositoryImpl:86 ] - Loading model 'demo.items'******** Item Name hue_LCT002_00178811f684_1_color_temperature Item state 452015-07-09 19:01:17 [DEBUG] [.e.s.m.i.i.GenericItemProvider:151 ] - Processing binding configs for items from model 'demo.items'2015-07-09 19:01:17 [DEBUG] [.e.s.m.i.i.GenericItemProvider:130 ] - Read items from model 'demo.items'2015-07-09 19:01:17 [INFO ] [runtime.busevents :27 ] - hue_LCT002_00178811f684_1_color_temperature state updated to 45******** Item Name hue_LCT002_00178811f684_1_color_temperature Item state 452015-07-09 19:01:18 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color_temperature' with state '45' in rrd4j database2015-07-09 19:01:19 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:289 ] - Executing startup rule 'Initialize light states'2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:300 ] - Execution of startup rule 'Initialize light states' has been postponed as items are still missing.2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:289 ] - Executing startup rule 'Initialize heating states'2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:300 ] - Execution of startup rule 'Initialize heating states' has been postponed as items are still missing.2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:289 ] - Executing startup rule 'Initialize contact states'2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:300 ] - Execution of startup rule 'Initialize contact states' has been postponed as items are still missing.2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:289 ] - Executing startup rule 'Initialize Location'2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:300 ] - Execution of startup rule 'Initialize Location' has been postponed as items are still missing.2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:289 ] - Executing startup rule 'Set random room temperatures'2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:300 ] - Execution of startup rule 'Set random room temperatures' has been postponed as items are still missing.2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:289 ] - Executing startup rule 'Update max and min temperatures'2015-07-09 19:01:24 [DEBUG] [.m.r.r.i.engine.RuleEngineImpl:300 ] - Execution of startup rule 'Update max and min temperatures' has been postponed as items are still missing.2015-07-09 19:01:56 [DEBUG] [b.hue.handler.HueBridgeHandler:98 ] - Status update for Hue light 1 detected.******** Item Name hue_LCT002_00178811f684_1_color Item state 86,34,942015-07-09 19:01:56 [DEBUG] [.p.rrd4j.internal.RRD4jService:120 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '0' in rrd4j database2015-07-09 19:01:56 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '86,34,94' in rrd4j database******** Item Name hue_LCT002_00178811f684_1_color Item state 86,34,942015-07-09 19:01:56 [INFO ] [runtime.busevents :27 ] - hue_LCT002_00178811f684_1_color state updated to 86,34,94******** Item Name hue_LCT002_00178811f684_1_color_temperature Item state 452015-07-09 19:01:56 [DEBUG] [.p.rrd4j.internal.RRD4jService:120 ] - Stored 'hue_LCT002_00178811f684_1_color_temperature' with state '0.450000000000000011102230246251565404236316680908203125' in rrd4j database2015-07-09 19:01:56 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color_temperature' with state '45' in rrd4j database2015-07-09 19:01:56 [INFO ] [runtime.busevents :27 ] - hue_LCT002_00178811f684_1_color_temperature state updated to 45******** Item Name hue_LCT002_00178811f684_1_color Item state 86,34,942015-07-09 19:01:57 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '86,34,94' in rrd4j database2015-07-09 19:02:19 [DEBUG] [io.rest.core.item.ItemResource:126 ] - Received HTTP GET request at 'items'2015-07-09 19:02:23 [DEBUG] [io.rest.core.item.ItemResource:201 ] - Received HTTP POST request at 'items/hue_LCT002_00178811f684_1_color' with value 'OFF'.2015-07-09 19:02:23 [DEBUG] [.c.thing.internal.ThingManager:222 ] - Delegating command 'OFF' for item 'hue_LCT002_00178811f684_1_color' to handler for channel 'hue:LCT002:00178811f684:1:color'2015-07-09 19:02:23 [INFO ] [runtime.busevents :22 ] - hue_LCT002_00178811f684_1_color received command OFF******** Item Name hue_LCT002_00178811f684_1_color Item state 86,34,02015-07-09 19:02:23 [DEBUG] [.p.rrd4j.internal.RRD4jService:120 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '0.939999999999999946709294817992486059665679931640625' in rrd4j database2015-07-09 19:02:23 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '86,34,0' in rrd4j database******** Item Name hue_LCT002_00178811f684_1_color Item state 86,34,0******** Item Name hue_LCT002_00178811f684_1_color Item state 86,34,02015-07-09 19:02:24 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '86,34,0' in rrd4j database2015-07-09 19:02:26 [DEBUG] [b.hue.handler.HueBridgeHandler:98 ] - Status update for Hue light 1 detected.******** Item Name hue_LCT002_00178811f684_1_color Item state 86,34,02015-07-09 19:02:26 [DEBUG] [.p.rrd4j.internal.RRD4jService:120 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '0' in rrd4j database2015-07-09 19:02:26 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color' with state '86,34,0' in rrd4j database2015-07-09 19:02:26 [INFO ] [runtime.busevents :27 ] - hue_LCT002_00178811f684_1_color state updated to 86,34,0******** Item Name hue_LCT002_00178811f684_1_color_temperature Item state 452015-07-09 19:02:26 [DEBUG] [.p.rrd4j.internal.RRD4jService:120 ] - Stored 'hue_LCT002_00178811f684_1_color_temperature' with state '0.450000000000000011102230246251565404236316680908203125' in rrd4j database2015-07-09 19:02:26 [DEBUG] [.p.rrd4j.internal.RRD4jService:139 ] - Stored 'hue_LCT002_00178811f684_1_color_temperature' with state '45' in rrd4j database2015-07-09 19:02:26 [INFO ] [runtime.busevents :27 ] - hue_LCT002_00178811f684_1_color_temperature state updated to 45
Kai Kreuzer
Jul 13, 2015, 7:55:13 AM7/13/15
to open...@googlegroups.com
Michael,
I cannot reproduce this on the latest code.
If I add the RGBLight item to the rrd4j.persist file and choose some colors on the colorpicker in the UI, I get:
2015-07-13 13:51:24 [INFO ] [arthome.event.ItemCommandEvent:43 ] - Item 'RGBLight' received command 173,70.58823529411765,100
2015-07-13 13:51:24 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'RGBLight' with state '173,70.58823529411765,100' in rrd4j database
2015-07-13 13:51:24 [INFO ] [smarthome.event.ItemStateEvent:43 ] - RGBLight updated to 173,70.58823529411765,100
2015-07-13 13:51:25 [INFO ] [arthome.event.ItemCommandEvent:43 ] - Item 'RGBLight' received command 94.92537313432837,52.549019607843135,100
2015-07-13 13:51:25 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'RGBLight' with state '94.92537313432837,52.549019607843135,100' in rrd4j database
2015-07-13 13:51:25 [INFO ] [smarthome.event.ItemStateEvent:43 ] - RGBLight updated to 94.92537313432837,52.549019607843135,100
Looks all ok for me. Could you please try with the latest snapshot as well?
Regards,
Kai
--
You received this message because you are subscribed to the Google Groups "openhab2" group.
To unsubscribe from this group and stop receiving emails from it, send an email to openhab2+u...@googlegroups.com.
To post to this group, send email to open...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/openhab2/6d682107-6f22-4be4-a412-5e032d7ae5de%40googlegroups.com.
Jim Howard
Sep 3, 2015, 5:17:28 PM9/3/15
to openhab2
I knew I recalled reading this somewhere. Here it is :)
I just upgraded to the latest Cloudbees build, and since doing that, I am getting double events.
I have not been able to track it down. So far, in common are that they are zwave devices. All my config files remained the same as before the update. I will dig into it more later.
JIm
I just upgraded to the latest Cloudbees build, and since doing that, I am getting double events.
2015-09-03 21:13:31.122 [INFO ] [smarthome.event.ItemStateEvent] - Aeon_door updated to OPEN
2015-09-03 21:13:31.135 [INFO ] [smarthome.event.ItemStateEvent] - Aeon_door updated to OPEN
2015-09-03 21:13:31.140 [INFO ] [arthome.event.ItemCommandEvent] - Item 'Z_strip4' received command ON
2015-09-03 21:13:31.157 [INFO ] [smarthome.event.ItemStateEvent] - Z_strip4 updated to ON
2015-09-03 21:13:31.168 [INFO ] [smarthome.event.ItemStateEvent] - Z_strip4 updated to ON
2015-09-03 21:13:32.636 [INFO ] [smarthome.event.ItemStateEvent] - Aeon_door updated to CLOSED
2015-09-03 21:13:32.651 [INFO ] [smarthome.event.ItemStateEvent] - Aeon_door updated to CLOSED
2015-09-03 21:13:32.655 [INFO ] [arthome.event.ItemCommandEvent] - Item 'Z_strip4' received command OFF
2015-09-03 21:13:32.671 [INFO ] [smarthome.event.ItemStateEvent] - Z_strip4 updated to OFF
2015-09-03 21:13:32.679 [INFO ] [smarthome.event.ItemStateEvent] - Z_strip4 updated to OFF
I have not been able to track it down. So far, in common are that they are zwave devices. All my config files remained the same as before the update. I will dig into it more later.
JIm
...
Jim Howard
Sep 3, 2015, 5:29:02 PM9/3/15
to openhab2
Going to take it over here: https://community.openhab.org/t/multiple-identical-updates-from-items-problem/2338
...
Reply all
Reply to author
Forward
0 new messages