23:05:43.964 INFO o.o.io.rest.RESTApplication[:159]- Stopped REST API 23:05:48.085 INFO o.o.u.w.i.s.WebAppServlet[:90]- Stopped Classic UI 23:07:20.194 DEBUG o.o.c.s.i.SchedulerActivator[:36]- Scheduler has been started. 23:07:21.161 DEBUG o.o.c.core.ConfigDispatcher[:146]- Processing openHAB default configuration file '/opt/openhab/configurations/openhab_default.cfg'. 23:07:21.171 DEBUG o.o.c.core.ConfigDispatcher[:168]- Processing openHAB main configuration file '/opt/openhab/configurations/openhab.cfg'. 23:07:22.250 DEBUG o.o.c.internal.CoreActivator[:93]- UUID file already exists at '/opt/openhab/webapps/static/uuid' with content '8ffa82c4-23d2-4490-b494-783f1559d349' 23:07:22.273 DEBUG o.o.c.internal.CoreActivator[:115]- Created file '/opt/openhab/webapps/static/version' with content '1.5.0' 23:07:22.289 INFO o.o.c.internal.CoreActivator[:61]- openHAB runtime has been started (v1.5.0). 23:07:22.449 DEBUG o.o.c.core.ConfigDispatcher[:270]- Scheduled refresh job 'ConfigDispatcher.Refresh' in DefaulScheduler 23:07:23.110 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248842200' (lasModified='1403629062000') -> initialization aborted. 23:07:23.235 DEBUG o.o.c.a.i.AutoUpdateActivator[:31]- AutoUpdate binding has been started. 23:07:26.680 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:07:32.349 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248843128' (lasModified='1403629062000') -> initialization aborted. 23:07:42.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248852372' (lasModified='1403629062000') -> initialization aborted. 23:07:47.609 DEBUG o.o.m.p.i.PersistenceModelActivator[:23]- Registered 'persistence' configuration parser 23:07:48.474 DEBUG o.o.c.t.i.TransformationActivator[:38]- Transformation Service has been started. 23:07:50.353 DEBUG o.o.i.g.internal.GCalActivator[:34]- Google Calendar IO has been started. 23:07:50.921 TRACE o.o.c.s.AbstractActiveService[:61]- Google Calender Event-Downloader won't be started because it isn't yet properly configured. 23:07:51.268 DEBUG o.o.i.m.i.MultimediaActivator[:34]- Multimedia I/O bundle has been started. 23:07:52.306 DEBUG o.o.i.s.i.DiscoveryServiceActivator[:27]- Discovery service has been started. 23:07:52.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248862368' (lasModified='1403629062000') -> initialization aborted. 23:07:52.608 INFO o.o.m.c.i.ModelRepositoryImpl[:79]- Loading model 'logging.persist' 23:07:53.024 DEBUG o.o.i.t.mqtt.MqttService[:121]- Starting MQTT Service... 23:07:55.603 INFO o.o.m.c.i.ModelRepositoryImpl[:79]- Loading model 'rrd4j.persist' 23:07:55.748 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:07:55.763 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:07:55.791 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:07:58.040 DEBUG o.o.m.i.i.ItemModelActivator[:24]- Registered 'item' configuration parser 23:08:01.085 DEBUG o.o.c.i.items.ItemRegistryImpl[:137]- Item provider 'GenericItemProvider' has been aTTed. 23:08:02.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248872371' (lasModified='1403629062000') -> initialization aborted. 23:08:05.814 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:08:05.827 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:08:05.848 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:08:05.869 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:08:05.883 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:08:07.194 INFO o.o.m.c.i.ModelRepositoryImpl[:79]- Loading model 'testsite.items' 23:08:08.003 DEBUG o.o.m.i.i.GenericItemProvider[:154]- Processing binding configs for items from model 'testsite.items' 23:08:08.046 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_bar_TT_Wohnung' of type 'Number' 23:08:08.065 TRACE o.o.m.i.i.GenericItemProvider[:327]- Given binding config reader is null > query cache to find appropriate reader! 23:08:08.080 TRACE o.o.m.i.i.GenericItemProvider[:347]- Couldn't find config reader for binding type 'tinkerforge' > parsing binding configuration of Iten 'Temperature_bar_TT_Wohnung (Type=NumberItem, State=Uninitialized)' aborted! 23:08:08.099 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_bar_TT_Wohnung_Max' of type 'Number' 23:08:08.105 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_bar_TT_Wohnung_Min' of type 'Number' 23:08:08.114 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_TT_Wohnung' of type 'Number' 23:08:08.128 TRACE o.o.m.i.i.GenericItemProvider[:327]- Given binding config reader is null > query cache to find appropriate reader! 23:08:08.140 TRACE o.o.m.i.i.GenericItemProvider[:347]- Couldn't find config reader for binding type 'tinkerforge' > parsing binding configuration of Iten 'Temperature_TT_Wohnung (Type=NumberItem, State=Uninitialized)' aborted! 23:08:08.159 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_TT_Wohnung_Max' of type 'Number' 23:08:08.165 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_TT_Wohnung_Min' of type 'Number' 23:08:08.179 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Barometer' of type 'Number' 23:08:08.185 TRACE o.o.m.i.i.GenericItemProvider[:327]- Given binding config reader is null > query cache to find appropriate reader! 23:08:08.201 TRACE o.o.m.i.i.GenericItemProvider[:347]- Couldn't find config reader for binding type 'tinkerforge' > parsing binding configuration of Iten 'Barometer (Type=NumberItem, State=Uninitialized)' aborted! 23:08:08.213 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'outa0' of type 'Switch' 23:08:08.231 TRACE o.o.m.i.i.GenericItemProvider[:327]- Given binding config reader is null > query cache to find appropriate reader! 23:08:08.249 TRACE o.o.m.i.i.GenericItemProvider[:347]- Couldn't find config reader for binding type 'tinkerforge' > parsing binding configuration of Iten 'outa0 (Type=SwitchItem, State=Uninitialized)' aborted! 23:08:08.256 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'lcd_light' of type 'Switch' 23:08:08.266 TRACE o.o.m.i.i.GenericItemProvider[:327]- Given binding config reader is null > query cache to find appropriate reader! 23:08:08.279 TRACE o.o.m.i.i.GenericItemProvider[:347]- Couldn't find config reader for binding type 'tinkerforge' > parsing binding configuration of Iten 'lcd_light (Type=SwitchItem, State=Uninitialized)' aborted! 23:08:08.301 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Date' of type 'DateTime' 23:08:08.316 TRACE o.o.m.i.i.GenericItemProvider[:327]- Given binding config reader is null > query cache to find appropriate reader! 23:08:08.322 TRACE o.o.m.i.i.GenericItemProvider[:347]- Couldn't find config reader for binding type 'ntp' > parsing binding configuration of Iten 'Date (Type=DateTimeItem, State=Uninitialized)' aborted! 23:08:08.336 DEBUG o.o.m.i.i.GenericItemProvider[:133]- Read items from model 'testsite.items' 23:08:08.354 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_bar_TT_Wohnung' of type 'Number' 23:08:08.366 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_bar_TT_Wohnung_Max' of type 'Number' 23:08:08.379 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_bar_TT_Wohnung_Min' of type 'Number' 23:08:08.399 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_TT_Wohnung' of type 'Number' 23:08:08.405 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_TT_Wohnung_Max' of type 'Number' 23:08:08.420 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_TT_Wohnung_Min' of type 'Number' 23:08:08.429 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Barometer' of type 'Number' 23:08:08.449 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'outa0' of type 'Switch' 23:08:08.455 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'lcd_light' of type 'Switch' 23:08:08.467 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Date' of type 'DateTime' 23:08:12.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248882361' (lasModified='1403629062000') -> initialization aborted. 23:08:18.546 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:08:18.563 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:08:18.585 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:08:18.602 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:08:18.617 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:08:20.367 DEBUG o.o.m.s.i.SitemapModelActivator[:23]- Registered 'sitemap' configuration parser 23:08:21.680 DEBUG o.o.i.r.internal.RESTActivator[:33]- REST API has been started. 23:08:22.332 INFO o.o.i.s.i.DiscoveryServiceImpl[:72]- mDNS service has been started 23:08:22.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248892363' (lasModified='1403629062000') -> initialization aborted. 23:08:24.395 ERROR o.a.cpr.AtmosphereFramework[:2208]- java.lang.ClassCastException: org.eclipse.osgi.framework.internal.core.BundleURLConnection cannot be cast to java.net.JarURLConnection at org.atmosphere.util.annotation.AnnotationDetector.detect(AnnotationDetector.java:324) at org.atmosphere.cpr.DefaultAnnotationProcessor.scanForAnnotation(DefaultAnnotationProcessor.java:148) at org.atmosphere.cpr.DefaultAnnotationProcessor.configure(DefaultAnnotationProcessor.java:134) at org.atmosphere.cpr.AtmosphereFramework.autoConfigureService(AtmosphereFramework.java:2170) at org.atmosphere.cpr.AtmosphereFramework.init(AtmosphereFramework.java:648) at org.atmosphere.cpr.AtmosphereFramework.init(AtmosphereFramework.java:561) 23:08:28.636 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:08:28.652 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:08:32.349 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248902358' (lasModified='1403629062000') -> initialization aborted. 23:08:33.504 INFO o.o.m.c.i.ModelRepositoryImpl[:79]- Loading model 'testsite.sitemap' 23:08:35.608 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:08:35.615 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:08:35.636 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:08:42.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248912368' (lasModified='1403629062000') -> initialization aborted. 23:08:45.648 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:08:45.663 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:08:45.683 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:08:45.700 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:08:45.722 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:08:52.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248922358' (lasModified='1403629062000') -> initialization aborted. 23:08:54.547 INFO o.o.io.rest.RESTApplication[:143]- Started REST API at /rest 23:08:54.585 DEBUG o.o.i.s.i.DiscoveryServiceImpl[:43]- Registering new service _openhab-server._tcp.local. at port 8080 23:08:55.731 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:08:55.742 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:08:55.750 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:08:55.758 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:08:55.768 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:08:57.321 DEBUG o.o.i.s.i.DiscoveryServiceImpl[:43]- Registering new service _openhab-server-ssl._tcp.local. at port 8443 23:09:00.138 DEBUG o.o.u.i.proxy.ProxyServlet[:97]- Starting up proxy servlet at /proxy 23:09:00.288 DEBUG o.o.u.i.chart.ChartServlet[:139]- Starting up chart servlet at /chart 23:09:00.390 DEBUG o.o.u.i.c.DefaultChartProvider[:107]- Starting up default chart provider. 23:09:01.423 INFO o.o.u.w.i.s.WebAppServlet[:79]- Started Classic UI at /openhab.app 23:09:01.451 DEBUG o.o.u.w.i.servlet.CmdServlet[:57]- Starting up CMD servlet at /CMD 23:09:02.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248932358' (lasModified='1403629062000') -> initialization aborted. 23:09:05.776 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:09:06.760 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:09:06.779 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:09:06.790 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:09:06.807 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:09:12.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248942358' (lasModified='1403629062000') -> initialization aborted. 23:09:16.207 DEBUG o.o.m.r.i.RuleModelActivator[:42]- Registered 'rules' configuration parser 23:09:16.523 DEBUG o.o.m.r.i.engine.RuleEngine[:78]- Started rule engine 23:09:16.825 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:09:19.810 INFO o.o.m.c.i.ModelRepositoryImpl[:79]- Loading model 'testsite.rules' 23:09:22.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248952358' (lasModified='1403629062000') -> initialization aborted. 23:09:24.109 DEBUG o.o.m.r.i.e.RuleTriggerManager[:438]- Scheduled rule Update max and min temperatures from Temperature with cron expression 0 0 0 * * ? 23:09:24.236 DEBUG o.o.m.r.i.e.RuleTriggerManager[:438]- Scheduled rule Update max and min temperatures from Barometer with cron expression 0 0 0 * * ? 23:09:24.318 DEBUG o.o.m.r.i.engine.RuleEngine[:257]- Executing startup rule 'Update max and min temperatures from Temperature' 23:09:30.266 DEBUG o.o.m.s.s.ScriptExtensionClassNameProvider[:63]- Script actions have changed: AudioActionService, TransformationActionService, PingActionService, HTTPActionService, ExecActionService, 23:09:32.352 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248962358' (lasModified='1403629062000') -> initialization aborted. 23:09:38.259 DEBUG o.o.b.m.i.ModbusActivator[:30]- Modbus binding has been started. 23:09:41.567 WARN o.o.c.p.e.PersistenceExtensions[:404]- There is no queryable persistence service registered with the name 'rrd4j' 23:09:41.597 ERROR o.o.m.r.i.engine.RuleEngine[:267]- Error during the execution of startup rule 'Update max and min temperatures from Temperature': cannot invoke method public abstract org.openhab.core.types.State org.openhab.core.persistence.HistoricItem.getState() on null 23:09:41.624 DEBUG o.o.m.r.i.engine.RuleEngine[:257]- Executing startup rule 'Update max and min temperatures from Barometer' 23:09:42.349 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248972386' (lasModified='1403629062000') -> initialization aborted. 23:09:44.001 WARN o.o.c.p.e.PersistenceExtensions[:404]- There is no queryable persistence service registered with the name 'rrd4j' 23:09:44.023 ERROR o.o.m.r.i.engine.RuleEngine[:267]- Error during the execution of startup rule 'Update max and min temperatures from Barometer': cannot invoke method public abstract org.openhab.core.types.State org.openhab.core.persistence.HistoricItem.getState() on null 23:09:44.039 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:09:44.051 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:09:44.067 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:09:44.083 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:09:44.219 TRACE o.o.c.s.AbstractActiveService[:61]- Modbus Polling Service won't be started because it isn't yet properly configured. 23:09:44.634 DEBUG o.o.b.f.i.FritzboxActivator[:30]- Fritzbox binding has been started. 23:09:44.929 TRACE o.o.c.s.AbstractActiveService[:61]- FritzBox refresh Service won't be started because it isn't yet properly configured. 23:09:44.942 INFO o.o.b.f.i.FritzboxBinding[:96]- Fritzbox conditional deActivate: false 23:09:44.954 INFO o.o.c.s.AbstractActiveService[:169]- FritzBox refresh Service has been started 23:09:45.180 DEBUG o.o.b.f.i.FritzboxBinding[:208]- Scheduled a daily reconnection to FritzBox on 192.168.179.1:1012 23:09:45.601 DEBUG o.o.b.a.i.AsteriskActivator[:30]- Asterisk binding has been started. 23:09:46.568 DEBUG o.o.b.t.i.TinkerforgeActivator[:34]- Tinkerforge binding has been started. 23:09:46.612 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_bar_TT_Wohnung' of type 'Number' 23:09:46.620 DEBUG o.o.m.i.i.GenericItemProvider[:334]- Start processing binding configuration of Item 'Temperature_bar_TT_Wohnung (Type=NumberItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 23:09:46.633 DEBUG o.o.b.t.i.TinkerforgeGenericBindingProvider[:81]- token: uid=bNd 23:09:46.645 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Temperature_TT_Wohnung' of type 'Number' 23:09:46.653 DEBUG o.o.m.i.i.GenericItemProvider[:334]- Start processing binding configuration of Item 'Temperature_TT_Wohnung (Type=NumberItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 23:09:46.660 DEBUG o.o.b.t.i.TinkerforgeGenericBindingProvider[:81]- token: uid=aMN 23:09:46.667 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Barometer' of type 'Number' 23:09:46.674 DEBUG o.o.m.i.i.GenericItemProvider[:334]- Start processing binding configuration of Item 'Barometer (Type=NumberItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 23:09:46.683 DEBUG o.o.b.t.i.TinkerforgeGenericBindingProvider[:81]- token: uid=bNd 23:09:46.691 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'outa0' of type 'Switch' 23:09:46.698 DEBUG o.o.m.i.i.GenericItemProvider[:334]- Start processing binding configuration of Item 'outa0 (Type=SwitchItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 23:09:46.705 DEBUG o.o.b.t.i.TinkerforgeGenericBindingProvider[:81]- token: uid=aE9 23:09:46.712 DEBUG o.o.b.t.i.TinkerforgeGenericBindingProvider[:81]- token: subid=outa0 23:09:46.720 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'lcd_light' of type 'Switch' 23:09:46.727 DEBUG o.o.m.i.i.GenericItemProvider[:334]- Start processing binding configuration of Item 'lcd_light (Type=SwitchItem, State=Uninitialized)' with 'TinkerforgeGenericBindingProvider' reader. 23:09:46.734 DEBUG o.o.b.t.i.TinkerforgeGenericBindingProvider[:81]- token: uid=d3Q 23:09:46.741 DEBUG o.o.b.t.i.TinkerforgeGenericBindingProvider[:81]- token: subid=backlight 23:09:47.221 TRACE o.o.c.s.AbstractActiveService[:61]- Tinkerforge Refresh Service won't be started because it isn't yet properly configured. 23:09:47.228 TRACE o.o.c.s.AbstractActiveService[:61]- Tinkerforge Refresh Service won't be started because it isn't yet properly configured. 23:09:48.391 DEBUG o.o.b.t.internal.TCPActivator[:35]- TCP/UDP binding has been started. 23:09:48.846 TRACE o.o.c.s.AbstractActiveService[:61]- TCP Refresh Service won't be started because it isn't yet properly configured. 23:09:49.441 TRACE o.o.c.s.AbstractActiveService[:61]- UDP Refresh Service won't be started because it isn't yet properly configured. 23:09:49.793 DEBUG o.o.b.t.i.TinkerforgeBinding[:169]- TFINIT connectModel called 23:09:49.870 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value bar_TT.callbackPeriod : 10000 23:09:49.890 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value bar_TT.subid : temperature 23:09:49.897 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value bar_TT.threshold : 1000 23:09:49.909 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value bar_TT.type : barometer_temperature 23:09:49.929 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value bar_TT.uid : bNd 23:09:50.011 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value hosts : 192.168.4.7 23:09:50.017 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value io16.debouncePeriod : 100 23:09:50.029 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value io16.type : bricklet_io16 23:09:50.049 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value io16.uid : aE9 23:09:50.109 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value lcd_TT.type : bricklet_LCD20x4 23:09:50.116 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value lcd_TT.uid : d3Q 23:09:50.123 DEBUG o.o.a.m.internal.MailActivator[:34]- Mail action has been started. 23:09:50.178 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value outa0.defaultState : false 23:09:50.195 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value outa0.subid : outa0 23:09:50.211 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value outa0.type : io_actuator 23:09:50.218 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value outa0.uid : aE9 23:09:50.283 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value service.pid : org.openhab.tinkerforge 23:09:50.299 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value temp_TT.callbackPeriod : 10000 23:09:50.306 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value temp_TT.threshold : 1000 23:09:50.323 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value temp_TT.type : bricklet_temperature 23:09:50.340 DEBUG o.o.b.t.i.c.ConfigurationHandler[:137]- CONFIG key:value temp_TT.uid : aMN 23:09:50.420 DEBUG o.o.b.t.i.c.ConfigurationHandler[:259]- CONFIG setting io_actuator config 23:09:50.482 DEBUG o.o.b.t.i.c.ConfigurationHandler[:457]- configuring feature: defaultState for uid aE9 23:09:50.488 DEBUG o.o.b.t.i.c.ConfigurationHandler[:469]- CONFIG found String value 23:09:50.500 DEBUG o.o.b.t.i.c.ConfigurationHandler[:394]- CONFIG setting no tfConfig device_type bricklet_LCD20x4 23:09:50.521 DEBUG o.o.b.t.i.c.ConfigurationHandler[:394]- CONFIG setting no tfConfig device_type barometer_temperature 23:09:51.043 DEBUG o.o.a.x.internal.XMPPActivator[:34]- XMPP action has been started. 23:09:51.374 DEBUG o.o.b.h.internal.HueActivator[:30]- Hue binding has been started. 23:09:52.156 DEBUG o.o.b.s.i.SamsungTvActivator[:34]- Samsung TV binding has been started. 23:09:52.386 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248982360' (lasModified='1403629062000') -> initialization aborted. 23:09:52.831 DEBUG o.o.b.f.i.FritzahaActivator[:33]- Fritz AVM Home Automation binding has been started. 23:09:53.463 TRACE o.o.c.s.AbstractActiveService[:61]- FritzAHA Refresh Service won't be started because it isn't yet properly configured. 23:09:53.585 DEBUG o.o.p.r.i.RRD4jActivator[:31]- RRD4j persistence bundle has been started. 23:09:54.102 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:09:54.122 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:09:54.130 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:09:54.150 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:09:54.161 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:09:54.176 DEBUG o.o.c.p.i.PersistenceManager[:146]- Initializing rrd4j persistence service. 23:09:54.644 DEBUG o.o.c.p.i.PersistenceManager[:428]- Scheduled strategy rrd4j.everyMinute with cron expression 0 * * * * ? 23:09:54.821 DEBUG o.o.p.r.i.c.RRD4jChartServlet[:113]- Starting up rrd chart servlet at /rrdchart.png 23:09:54.937 DEBUG o.o.b.s.internal.SnmpActivator[:35]- SNMP binding has been started. 23:09:55.729 TRACE o.o.c.s.AbstractActiveService[:61]- SNMP Refresh Service won't be started because it isn't yet properly configured. 23:09:55.737 DEBUG o.o.b.s.internal.SnmpBinding[:90]- SNMP binding activated 23:09:55.764 INFO o.o.c.s.AbstractActiveService[:169]- SNMP Refresh Service has been started 23:09:55.937 DEBUG o.o.b.h.i.b.HomematicActivator[:33]- Homematic binding has been started. 23:09:56.829 TRACE o.o.c.s.AbstractActiveService[:61]- CCU keep alive thread won't be started because it isn't yet properly configured. 23:09:57.077 DEBUG o.o.b.k.internal.KNXActivator[:31]- KNX binding has been started. 23:09:58.484 DEBUG o.o.p.l.i.LoggingPersistenceActivator[:31]- Logging persistence bundle has been started. 23:09:58.993 DEBUG o.o.c.p.i.PersistenceManager[:146]- Initializing logging persistence service. 23:09:59.048 DEBUG o.o.b.w.internal.WolActivator[:31]- WoL binding has been started. 23:09:59.760 DEBUG o.o.b.c.internal.CupsActivator[:31]- Cups binding has been started. 23:10:00.175 TRACE o.o.c.p.i.PersistItemsJob[:56]- Storing item 'Temperature_TT_Wohnung' with persistence service 'rrd4j' took 31ms 23:10:00.203 TRACE o.o.c.p.i.PersistItemsJob[:56]- Storing item 'Temperature_TT_Wohnung_Min' with persistence service 'rrd4j' took 11ms 23:10:00.227 TRACE o.o.c.p.i.PersistItemsJob[:56]- Storing item 'Temperature_bar_TT_Wohnung_Min' with persistence service 'rrd4j' took 11ms 23:10:00.258 TRACE o.o.c.p.i.PersistItemsJob[:56]- Storing item 'Temperature_bar_TT_Wohnung_Max' with persistence service 'rrd4j' took 6ms 23:10:00.275 TRACE o.o.c.p.i.PersistItemsJob[:56]- Storing item 'Temperature_TT_Wohnung_Max' with persistence service 'rrd4j' took 6ms 23:10:00.305 TRACE o.o.c.p.i.PersistItemsJob[:56]- Storing item 'Temperature_bar_TT_Wohnung' with persistence service 'rrd4j' took 6ms 23:10:00.671 TRACE o.o.c.s.AbstractActiveService[:61]- Cups Refresh Service won't be started because it isn't yet properly configured. 23:10:00.702 DEBUG o.o.b.h.internal.HttpActivator[:34]- HTTP binding has been started. 23:10:01.504 TRACE o.o.c.s.AbstractActiveService[:61]- HTTP Refresh Service won't be started because it isn't yet properly configured. 23:10:01.517 INFO o.o.c.s.AbstractActiveService[:169]- HTTP Refresh Service has been started 23:10:01.545 DEBUG o.o.p.e.i.ExecPersistenceServiceActivator[:33]- Exec persistence bundle has been started. 23:10:02.083 DEBUG o.o.c.p.i.PersistenceManager[:146]- Initializing exec persistence service. 23:10:02.091 DEBUG o.o.m.c.i.ModelRepositoryImpl[:63]- Configuration model 'exec.persist' can not be found 23:10:02.140 DEBUG o.o.b.e.internal.ExecActivator[:34]- Exec binding has been started. 23:10:02.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404248992392' (lasModified='1403629062000') -> initialization aborted. 23:10:02.979 TRACE o.o.c.s.AbstractActiveService[:61]- Exec Refresh Service won't be started because it isn't yet properly configured. 23:10:02.995 INFO o.o.c.s.AbstractActiveService[:169]- Exec Refresh Service has been started 23:10:03.020 DEBUG o.o.b.n.internal.NtpActivator[:31]- NTP binding has been started. 23:10:03.656 TRACE o.o.m.i.i.GenericItemProvider[:403]- Created item 'Date' of type 'DateTime' 23:10:04.015 DEBUG o.o.m.i.i.GenericItemProvider[:334]- Start processing binding configuration of Item 'Date (Type=DateTimeItem, State=Uninitialized)' with 'NtpGenericBindingProvider' reader. 23:10:04.184 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:10:04.195 TRACE o.o.c.s.AbstractActiveService[:61]- NTP Refresh Service won't be started because it isn't yet properly configured. 23:10:04.203 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:10:04.216 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:10:04.219 TRACE o.o.c.s.AbstractActiveService[:61]- NTP Refresh Service won't be started because it isn't yet properly configured. 23:10:04.231 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:10:04.259 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:10:04.274 DEBUG o.o.b.c.i.ConfigAdminActivator[:31]- ConfigAdmin binding has been started. 23:10:06.149 DEBUG o.o.b.s.internal.SnmpBinding[:147]- SNMP binding is listening on 0.0.0.0/162 23:10:06.168 INFO o.o.c.s.AbstractActiveService[:169]- NTP Refresh Service has been started 23:10:12.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404249002358' (lasModified='1403629062000') -> initialization aborted. 23:10:14.272 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:10:14.280 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:10:14.289 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:10:14.296 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:10:14.306 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:10:22.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404249012354' (lasModified='1403629062000') -> initialization aborted. 23:10:24.315 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'rules' 23:10:24.323 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'sitemaps' 23:10:24.332 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'scripts' 23:10:24.339 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'persistence' 23:10:24.349 DEBUG o.o.m.c.i.f.FolderObserver[:98]- Refreshing folder 'items' 23:10:32.348 TRACE o.o.c.core.ConfigDispatcher[:160]- main configuration file '/opt/openhab/configurations/openhab.cfg' hasn't been changed since '1404249022354' (lasModified='1403629062000') -> initialization aborted.