When items stop updating are you actively doing development?
I have seen items stop updating when developing new rules and site maps. If I encounter an error the updates will stop. I have to restart openhab to fix. Everything g else works except the items updating.
Sorry not familiar with the question you asked. The polling and bundle one. We will have to see hopefully someone can answer. To help them though maybe post a specific snippet from rules file or wherever this polling is being done.
The events.log stops logging item updates for today 2018-07-15 around 13:16, but the openhab.log doesn’t state anything around 13:16. So here is one excerpt around that time.
2018-07-15 11:23:44.194 [INFO ] [basic.internal.servlet.WebAppServlet] - Stopped Basic UI
2018-07-15 11:23:56.409 [INFO ] [ternal.scheduler.WeatherJobScheduler] - Deleting weatherJob-home
2018-07-15 11:23:56.516 [INFO ] [b.core.service.AbstractActiveService] - CalDav Loader has been shut down
2018-07-15 11:23:56.542 [INFO ] [io.openhabcloud.internal.CloudClient] - Shutting down openHAB Cloud service connection
2018-07-15 11:23:56.595 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2018-07-15 11:23:57.025 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception
java.lang.NullPointerException: null
at org.openhab.binding.tado.handler.BaseHomeThingHandler.getApi(BaseHomeThingHandler.java:37) ~[?:?]
at org.openhab.binding.tado.handler.TadoMobileDeviceHandler.getMobileDevice(TadoMobileDeviceHandler.java:114) ~[?:?]
at org.openhab.binding.tado.handler.TadoMobileDeviceHandler.updateState(TadoMobileDeviceHandler.java:102) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
at java.lang.Thread.run(Thread.java:748) [?:?]
2018-07-15 11:23:57.097 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception
java.lang.NullPointerException: null
at org.openhab.binding.tado.handler.BaseHomeThingHandler.getApi(BaseHomeThingHandler.java:37) ~[?:?]
at org.openhab.binding.tado.handler.TadoMobileDeviceHandler.getMobileDevice(TadoMobileDeviceHandler.java:114) ~[?:?]
at org.openhab.binding.tado.handler.TadoMobileDeviceHandler.updateState(TadoMobileDeviceHandler.java:102) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
at java.lang.Thread.run(Thread.java:748) [?:?]
2018-07-15 11:23:58.286 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Stopped Paper UI
2018-07-15 11:23:58.307 [INFO ] [ttp.internal.SecureHttpClientFactory] - jetty shared http client stopped
2018-07-15 11:23:58.320 [INFO ] [panel.internal.HABPanelDashboardTile] - Stopped HABPanel
2018-07-15 11:23:58.334 [INFO ] [er.internal.HomeBuilderDashboardTile] - Stopped Home Builder
2018-07-15 11:23:58.359 [INFO ] [.dashboard.internal.DashboardService] - Stopped Dashboard
2018-07-15 11:24:13.347 [INFO ] [.internal.GenericScriptEngineFactory] - Activated scripting support for ECMAScript
2018-07-15 11:24:14.211 [INFO ] [.io.caldav.internal.CalDavLoaderImpl] - CalDAV IO is properly configured.
2018-07-15 11:24:14.218 [INFO ] [b.core.service.AbstractActiveService] - CalDav Loader has been started
2018-07-15 11:24:14.503 [INFO ] [.io.caldav.internal.CalDavLoaderImpl] - reload job scheduled for: urlaub
2018-07-15 11:24:14.742 [INFO ] [er.internal.HomeBuilderDashboardTile] - Started Home Builder at /homebuilder
2018-07-15 11:24:16.503 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Berlin'.
2018-07-15 11:24:16.514 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to 'xxx,xxx'.
2018-07-15 11:24:16.523 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'de_DE'.
2018-07-15 11:24:16.526 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Measurement system set to 'SIUnits'.
2018-07-15 11:24:18.649 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.1.18:8080
2018-07-15 11:24:18.657 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.1.18:8443
2018-07-15 11:24:19.711 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxx, base URL = http://localhost:8080)
2018-07-15 11:24:33.379 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather.items'
2018-07-15 11:24:33.518 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'speedtest.items'
2018-07-15 11:24:33.683 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'systeminfo.items'
2018-07-15 11:24:33.875 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'logging.items'
2018-07-15 11:24:34.088 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.items'
2018-07-15 11:24:34.857 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'network.items'
2018-07-15 11:24:35.651 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'mapdb.persist'
2018-07-15 11:24:35.779 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'influxdb.persist'
2018-07-15 11:24:38.830 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2018-07-15 11:24:51.212 [INFO ] [el.core.internal.ModelRepositoryImpl] - Validation issues found in configuration model 'default.rules', using it anyway:
The value of the local variable room is not used
2018-07-15 11:24:51.220 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.rules'
2018-07-15 11:24:53.361 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'tplink.rules'
2018-07-15 11:24:55.174 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'speedtest.rules'
2018-07-15 11:24:56.278 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'xxx.sitemap'
2018-07-15 11:24:56.822 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.things'
2018-07-15 11:24:58.046 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'astro:sun:local' to inbox.
2018-07-15 11:24:58.062 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'astro:moon:local' to inbox.
2018-07-15 11:24:59.123 [INFO ] [thome.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:sun:local
2018-07-15 11:24:59.748 [WARN ] [org.pcap4j.util.PropertiesLoader ] - [org/pcap4j/pcap4j.properties] Could not get value by org.pcap4j.af.inet, use default value: null
2018-07-15 11:24:59.751 [WARN ] [org.pcap4j.util.PropertiesLoader ] - [org/pcap4j/pcap4j.properties] Could not get value by org.pcap4j.af.inet6, use default value: null
2018-07-15 11:24:59.753 [WARN ] [org.pcap4j.util.PropertiesLoader ] - [org/pcap4j/pcap4j.properties] Could not get value by org.pcap4j.af.packet, use default value: null
2018-07-15 11:24:59.755 [WARN ] [org.pcap4j.util.PropertiesLoader ] - [org/pcap4j/pcap4j.properties] Could not get value by org.pcap4j.af.link, use default value: null
2018-07-15 11:24:59.977 [WARN ] [org.pcap4j.util.PropertiesLoader ] - [org/pcap4j/pcap4j.properties] Could not get value by org.pcap4j.dlt.raw, use default value: null
2018-07-15 11:25:00.601 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2018-07-15 11:25:01.697 [INFO ] [thome.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:moon:local
2018-07-15 11:25:20.359 [ERROR] [ntime.internal.engine.RuleEngineImpl] - Rule 'Tado offline': An error occurred during the script execution: null
2018-07-15 11:25:32.773 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2018-07-15 11:25:32.933 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2018-07-15 11:25:33.213 [INFO ] [ttp.internal.SecureHttpClientFactory] - creating httpClient for endpoint null
2018-07-15 11:25:33.240 [INFO ] [ttp.internal.SecureHttpClientFactory] - jetty shared http client created
2018-07-15 11:25:34.257 [INFO ] [eather.internal.common.WeatherConfig] - LocationConfig[providerName=YAHOO,language=de,updateInterval=10,latitude=<null>,longitude=<null>,woeid=xxx,locationId=home,name=Zuhause]
2018-07-15 11:25:35.884 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2018-07-15 11:25:35.993 [INFO ] [ternal.communicator.client.RpcClient] - Interface 'WIRED' on gateway 'xxx' not available, disabling support
2018-07-15 11:25:36.005 [INFO ] [ternal.communicator.client.RpcClient] - Interface 'HMIP' on gateway 'xxx' not available, disabling support
2018-07-15 11:25:36.015 [INFO ] [ternal.communicator.client.RpcClient] - Interface 'CUXD' on gateway 'xxx' not available, disabling support
2018-07-15 11:25:36.021 [INFO ] [ternal.communicator.client.RpcClient] - Interface 'GROUP' on gateway 'xxx' not available, disabling support
2018-07-15 11:25:36.135 [INFO ] [ommunicator.AbstractHomematicGateway] - HmGatewayInfo[id=HOMEGEAR,type=Homegear,firmware=0.7.23-1891,address=xxx,wired=false,hmip=false,cuxd=false,group=false]
2018-07-15 11:25:36.727 [INFO ] [ng.homematic.internal.misc.MiscUtils] - Datapoint name 'BackupHomegear.sh' contains invalid characters, new Datapoint name 'BackupHomegear_sh'
2018-07-15 11:25:36.733 [INFO ] [ng.homematic.internal.misc.MiscUtils] - Datapoint name 'ReadMe.txt' contains invalid characters, new Datapoint name 'ReadMe_txt'
2018-07-15 11:25:36.738 [INFO ] [ng.homematic.internal.misc.MiscUtils] - Datapoint name 'RestoreHomegear.sh' contains invalid characters, new Datapoint name 'RestoreHomegear_sh'
2018-07-15 11:25:36.742 [INFO ] [ng.homematic.internal.misc.MiscUtils] - Datapoint name 'Test.php' contains invalid characters, new Datapoint name 'Test_php'
2018-07-15 11:25:36.747 [INFO ] [ng.homematic.internal.misc.MiscUtils] - Datapoint name 'Connect.php' contains invalid characters, new Datapoint name 'Connect_php'
2018-07-15 11:25:37.474 [INFO ] [ternal.scheduler.WeatherJobScheduler] - Starting and scheduling weatherJob-home with interval of 10 minutes
2018-07-15 11:25:45.736 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception
java.lang.IllegalStateException: Cannot read raw response body of a converted body.
at retrofit2.OkHttpCall$NoContentResponseBody.source(OkHttpCall.java:267) ~[?:?]
at okhttp3.ResponseBody.close(ResponseBody.java:187) ~[?:?]
at okhttp3.Response.close(Response.java:274) ~[?:?]
at org.openhab.binding.tado.internal.api.TadoApiClient.removeOverlay(TadoApiClient.java:102) ~[?:?]
at org.openhab.binding.tado.handler.TadoZoneHandler.removeOverlay(TadoZoneHandler.java:103) ~[?:?]
at org.openhab.binding.tado.internal.TadoHvacChange.apply(TadoHvacChange.java:116) ~[?:?]
at org.openhab.binding.tado.handler.TadoZoneHandler.lambda$0(TadoZoneHandler.java:270) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
at java.lang.Thread.run(Thread.java:748) [?:?]
2018-07-15 11:25:45.791 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception
java.lang.IllegalStateException: Cannot read raw response body of a converted body.
at retrofit2.OkHttpCall$NoContentResponseBody.source(OkHttpCall.java:267) ~[?:?]
at okhttp3.ResponseBody.close(ResponseBody.java:187) ~[?:?]
at okhttp3.Response.close(Response.java:274) ~[?:?]
at org.openhab.binding.tado.internal.api.TadoApiClient.removeOverlay(TadoApiClient.java:102) ~[?:?]
at org.openhab.binding.tado.handler.TadoZoneHandler.removeOverlay(TadoZoneHandler.java:103) ~[?:?]
at org.openhab.binding.tado.internal.TadoHvacChange.apply(TadoHvacChange.java:116) ~[?:?]
at org.openhab.binding.tado.handler.TadoZoneHandler.lambda$0(TadoZoneHandler.java:270) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
at java.lang.Thread.run(Thread.java:748) [?:?]
2018-07-15 11:26:16.748 [WARN ] [converter.ZigBeeConverterTemperature] - {}: Failed to bind temperature measurement cluster
2018-07-15 11:32:13.636 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14631715 failed: null
2018-07-15 11:32:19.472 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14631724 failed: null
2018-07-15 11:33:12.416 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14631946 failed: null
2018-07-15 11:33:15.960 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14631968 failed: null
2018-07-15 11:33:17.590 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14631982 failed: null
2018-07-15 11:33:19.754 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14631990 failed: null
2018-07-15 11:33:22.487 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14632002 failed: null
2018-07-15 11:33:41.740 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14632021 failed: null
2018-07-15 11:33:51.205 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14632065 failed: null
2018-07-15 11:33:55.039 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14632104 failed: null
2018-07-15 11:33:56.834 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14632117 failed: null
2018-07-15 11:34:47.315 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14632293 failed: null
2018-07-15 11:34:54.214 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14632302 failed: null
2018-07-15 11:34:55.948 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14632326 failed: null
2018-07-15 11:35:01.683 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14632334 failed: null
2018-07-15 11:36:08.942 [INFO ] [core.karaf.internal.FeatureInstaller] - Uninstalled 'openhab-binding-tado'
2018-07-15 11:36:32.515 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception
java.lang.NullPointerException: null
at org.openhab.binding.tado.handler.BaseHomeThingHandler.getApi(BaseHomeThingHandler.java:37) ~[?:?]
at org.openhab.binding.tado.handler.TadoMobileDeviceHandler.getMobileDevice(TadoMobileDeviceHandler.java:114) ~[?:?]
at org.openhab.binding.tado.handler.TadoMobileDeviceHandler.updateState(TadoMobileDeviceHandler.java:102) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
at java.lang.Thread.run(Thread.java:748) [?:?]
2018-07-15 11:36:32.571 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception
java.lang.NullPointerException: null
at org.openhab.binding.tado.handler.BaseHomeThingHandler.getApi(BaseHomeThingHandler.java:37) ~[?:?]
at org.openhab.binding.tado.handler.TadoMobileDeviceHandler.getMobileDevice(TadoMobileDeviceHandler.java:114) ~[?:?]
at org.openhab.binding.tado.handler.TadoMobileDeviceHandler.updateState(TadoMobileDeviceHandler.java:102) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
at java.lang.Thread.run(Thread.java:748) [?:?]
2018-07-15 11:36:33.199 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception
java.lang.NullPointerException: null
at org.openhab.binding.tado.handler.BaseHomeThingHandler.getApi(BaseHomeThingHandler.java:37) [250:org.openhab.binding.tado:2.3.0]
at org.openhab.binding.tado.handler.TadoZoneHandler.getZoneState(TadoZoneHandler.java:85) [250:org.openhab.binding.tado:2.3.0]
at org.openhab.binding.tado.handler.TadoZoneHandler.updateZoneState(TadoZoneHandler.java:216) [250:org.openhab.binding.tado:2.3.0]
at org.openhab.binding.tado.handler.TadoZoneHandler.access$0(TadoZoneHandler.java:209) [250:org.openhab.binding.tado:2.3.0]
at org.openhab.binding.tado.handler.TadoZoneHandler$1.run(TadoZoneHandler.java:249) [250:org.openhab.binding.tado:2.3.0]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
at java.lang.Thread.run(Thread.java:748) [?:?]
2018-07-15 11:36:33.245 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception
java.lang.NullPointerException: null
at org.openhab.binding.tado.handler.BaseHomeThingHandler.getApi(BaseHomeThingHandler.java:37) [250:org.openhab.binding.tado:2.3.0]
at org.openhab.binding.tado.handler.TadoZoneHandler.getZoneState(TadoZoneHandler.java:85) [250:org.openhab.binding.tado:2.3.0]
at org.openhab.binding.tado.handler.TadoZoneHandler.updateZoneState(TadoZoneHandler.java:216) [250:org.openhab.binding.tado:2.3.0]
at org.openhab.binding.tado.handler.TadoZoneHandler.access$0(TadoZoneHandler.java:209) [250:org.openhab.binding.tado:2.3.0]
at org.openhab.binding.tado.handler.TadoZoneHandler$1.run(TadoZoneHandler.java:249) [250:org.openhab.binding.tado:2.3.0]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
at java.lang.Thread.run(Thread.java:748) [?:?]
2018-07-15 13:46:45.767 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659322 failed: null
2018-07-15 13:46:58.130 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659379 failed: null
2018-07-15 13:47:00.568 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659412 failed: null
2018-07-15 13:47:04.162 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659428 failed: null
2018-07-15 13:47:06.507 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659443 failed: null
2018-07-15 13:47:08.790 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659458 failed: null
2018-07-15 13:47:13.439 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659473 failed: null
2018-07-15 13:47:18.040 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659489 failed: null
2018-07-15 13:47:19.412 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659516 failed: null
2018-07-15 13:47:22.690 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659525 failed: null
2018-07-15 13:47:27.334 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659538 failed: null
2018-07-15 13:47:55.496 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 14659554 failed: null
If I understand you correctly then I think that the Items themselves stop updating.
events.log upfront:
2018-07-15 13:15:00.981 [vent.ItemStateChangedEvent] - CPU_Load changed from 8.5 to 31.4
2018-07-15 13:15:01.024 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 60.1
2018-07-15 13:15:02.079 [vent.ItemStateChangedEvent] - CPU_Load changed from 31.4 to 7.2
2018-07-15 13:15:02.138 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 60.1 to 59.1
2018-07-15 13:15:02.543 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from OFF to ON
2018-07-15 13:15:02.554 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 260.91 to 260.19
2018-07-15 13:15:03.049 [vent.ItemStateChangedEvent] - LivingRoom_Illuminance changed from 205.64 to 206.39
2018-07-15 13:15:03.098 [vent.ItemStateChangedEvent] - LivingRoom_Media_Power changed from 3.893677 to 3.83947
2018-07-15 13:15:03.105 [vent.ItemStateChangedEvent] - LivingRoom_Media_Current changed from 0.161066 to 0.16125
2018-07-15 13:15:03.115 [vent.ItemStateChangedEvent] - LivingRoom_Media_Voltage changed from 220.563842 to 220.631241
2018-07-15 13:15:03.164 [vent.ItemStateChangedEvent] - Bathroom_WashingMachine_Current changed from 0.03167 to 0.032109
2018-07-15 13:15:03.166 [vent.ItemStateChangedEvent] - Bathroom_WashingMachine_Voltage changed from 226.06497 to 226.14567
2018-07-15 13:15:03.226 [vent.ItemStateChangedEvent] - Kitchen_DishWasher_RSSI changed from -79 to -76
2018-07-15 13:15:03.231 [vent.ItemStateChangedEvent] - Kitchen_DishWasher_Current changed from 0.037469 to 0.037371
2018-07-15 13:15:03.235 [vent.ItemStateChangedEvent] - Kitchen_DishWasher_Voltage changed from 222.1583 to 222.138618
2018-07-15 13:15:03.266 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.2 to 16.4
2018-07-15 13:15:03.314 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 59.1 to 59.6
2018-07-15 13:15:04.348 [vent.ItemStateChangedEvent] - CPU_Load changed from 16.4 to 6.8
2018-07-15 13:15:04.422 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 59.6 to 58.0
2018-07-15 13:15:05.472 [vent.ItemStateChangedEvent] - CPU_Load changed from 6.8 to 7.3
2018-07-15 13:15:06.135 [vent.ItemStateChangedEvent] - Kitchen_Occupancy changed from OFF to ON
2018-07-15 13:15:06.140 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 180.57 to 174.47
2018-07-15 13:15:06.603 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.3 to 7.7
2018-07-15 13:15:07.520 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 260.19 to 260.26
2018-07-15 13:15:07.703 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:07.725 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.7 to 10.2
2018-07-15 13:15:07.738 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:07.776 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:07.787 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:07.793 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 58.5
2018-07-15 13:15:08.073 [vent.ItemStateChangedEvent] - LivingRoom_Illuminance changed from 206.39 to 207.33
2018-07-15 13:15:08.828 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:08.856 [vent.ItemStateChangedEvent] - CPU_Load changed from 10.2 to 7.4
2018-07-15 13:15:08.871 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:08.908 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:08.911 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:08.913 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.5 to 58.0
2018-07-15 13:15:09.936 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.4 to 7.7
2018-07-15 13:15:09.972 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 59.1
2018-07-15 13:15:10.662 [vent.ItemStateChangedEvent] - LivingRoom_Occupancy changed from ON to OFF
2018-07-15 13:15:10.672 [vent.ItemStateChangedEvent] - LivingRoom_Illuminance changed from 207.33 to 207.41
2018-07-15 13:15:11.035 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.7 to 30.1
2018-07-15 13:15:11.105 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 59.1 to 60.1
2018-07-15 13:15:11.145 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 174.47 to 179.44
2018-07-15 13:15:12.177 [vent.ItemStateChangedEvent] - CPU_Load changed from 30.1 to 7.3
2018-07-15 13:15:12.228 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 60.1 to 58.0
2018-07-15 13:15:12.448 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 260.26 to 260.28
2018-07-15 13:15:13.293 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:13.314 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.3 to 8.0
2018-07-15 13:15:13.323 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:13.337 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:13.340 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:14.373 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:14.398 [vent.ItemStateChangedEvent] - CPU_Load changed from 8.0 to 7.9
2018-07-15 13:15:14.411 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:14.442 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:14.450 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:15.496 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:15.524 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.9 to 6.2
2018-07-15 13:15:15.541 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:15.562 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:15.569 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:16.053 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 179.44 to 179.13
2018-07-15 13:15:16.606 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:16.631 [vent.ItemStateChangedEvent] - CPU_Load changed from 6.2 to 9.5
2018-07-15 13:15:16.638 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:16.693 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from ON to OFF
2018-07-15 13:15:16.695 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:16.697 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 260.28 to 260.22
2018-07-15 13:15:16.700 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:17.725 [vent.ItemStateChangedEvent] - CPU_Load changed from 9.5 to 7.9
2018-07-15 13:15:17.778 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 57.5
2018-07-15 13:15:18.809 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:18.826 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.9 to 6.0
2018-07-15 13:15:18.848 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:18.887 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:18.897 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:18.899 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 57.5 to 58.0
2018-07-15 13:15:19.931 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:19.980 [vent.ItemStateChangedEvent] - CPU_Load changed from 6.0 to 8.1
2018-07-15 13:15:20.001 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:20.015 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:20.018 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:20.964 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 179.13 to 178.97
2018-07-15 13:15:21.028 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:21.041 [vent.ItemStateChangedEvent] - CPU_Load changed from 8.1 to 30.2
2018-07-15 13:15:21.060 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:21.075 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:21.083 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:21.087 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 59.1
2018-07-15 13:15:22.108 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:22.122 [vent.ItemStateChangedEvent] - CPU_Load changed from 30.2 to 5.2
2018-07-15 13:15:22.152 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:22.176 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:22.194 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:22.197 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 59.1 to 58.5
2018-07-15 13:15:23.251 [vent.ItemStateChangedEvent] - CPU_Load changed from 5.2 to 8.8
2018-07-15 13:15:24.366 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:24.389 [vent.ItemStateChangedEvent] - CPU_Load changed from 8.8 to 5.6
2018-07-15 13:15:24.406 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:24.433 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:24.440 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:24.858 [ome.event.ItemCommandEvent] - Item 'Holiday' received command OFF
2018-07-15 13:15:25.460 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:25.473 [vent.ItemStateChangedEvent] - CPU_Load changed from 5.6 to 15.7
2018-07-15 13:15:25.489 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:25.505 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:25.520 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from OFF to ON
2018-07-15 13:15:25.526 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:25.530 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 260.22 to 260.09
2018-07-15 13:15:25.532 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.5 to 59.1
2018-07-15 13:15:25.951 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 178.97 to 178.27
2018-07-15 13:15:26.555 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:26.577 [vent.ItemStateChangedEvent] - CPU_Load changed from 15.7 to 8.4
2018-07-15 13:15:26.592 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:26.636 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:26.642 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:26.647 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 59.1 to 58.0
2018-07-15 13:15:27.688 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:27.722 [vent.ItemStateChangedEvent] - CPU_Load changed from 8.4 to 8.6
2018-07-15 13:15:27.736 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:27.757 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:27.767 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:27.773 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 58.5
2018-07-15 13:15:28.835 [vent.ItemStateChangedEvent] - CPU_Load changed from 8.6 to 7.1
2018-07-15 13:15:28.868 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.5 to 58.0
2018-07-15 13:15:29.909 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:29.930 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.1 to 6.2
2018-07-15 13:15:29.943 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:29.969 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:29.978 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:30.459 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 260.09 to 260.07
2018-07-15 13:15:30.953 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 178.27 to 178.03
2018-07-15 13:15:31.006 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:31.016 [vent.ItemStateChangedEvent] - CPU_Load changed from 6.2 to 29.6
2018-07-15 13:15:31.027 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:31.053 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:31.059 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:31.066 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 60.1
2018-07-15 13:15:35.397 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 260.07 to 260.03
2018-07-15 13:15:35.953 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 178.03 to 178.35
2018-07-15 13:15:37.043 [vent.ItemStateChangedEvent] - CPU_Load changed from 29.6 to 3.8
2018-07-15 13:15:37.115 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 60.1 to 57.5
2018-07-15 13:15:37.741 [vent.ItemStateChangedEvent] - NetgearRouter_ResponseTime changed from 17.0 to 9.0
2018-07-15 13:15:37.781 [vent.ItemStateChangedEvent] - NetgearRouter_LastSeen changed from 2018-07-15T13:14:32.834+0200 to 2018-07-15T13:15:37.720+0200
2018-07-15 13:15:37.856 [vent.ItemStateChangedEvent] - NetgearPowerPlug_LastSeen changed from 2018-07-15T13:14:32.897+0200 to 2018-07-15T13:15:37.785+0200
2018-07-15 13:15:37.921 [vent.ItemStateChangedEvent] - LivingRoom_Media_Power changed from 3.83947 to 3.901921
2018-07-15 13:15:37.925 [vent.ItemStateChangedEvent] - LivingRoom_Media_Current changed from 0.16125 to 0.161619
2018-07-15 13:15:37.933 [vent.ItemStateChangedEvent] - LivingRoom_Media_Voltage changed from 220.631241 to 220.719011
2018-07-15 13:15:38.007 [vent.ItemStateChangedEvent] - Bathroom_WashingMachine_Current changed from 0.032109 to 0.032145
2018-07-15 13:15:38.013 [vent.ItemStateChangedEvent] - Bathroom_WashingMachine_Voltage changed from 226.14567 to 226.227608
2018-07-15 13:15:38.069 [vent.ItemStateChangedEvent] - Kitchen_DishWasher_RSSI changed from -76 to -65
2018-07-15 13:15:38.076 [vent.ItemStateChangedEvent] - Kitchen_DishWasher_Current changed from 0.037371 to 0.037447
2018-07-15 13:15:38.078 [vent.ItemStateChangedEvent] - Kitchen_DishWasher_Voltage changed from 222.138618 to 222.280152
2018-07-15 13:15:38.140 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:38.153 [vent.ItemStateChangedEvent] - CPU_Load changed from 3.8 to 24.1
2018-07-15 13:15:38.168 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:38.185 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:38.197 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:38.199 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 57.5 to 59.1
2018-07-15 13:15:39.235 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:39.255 [vent.ItemStateChangedEvent] - CPU_Load changed from 24.1 to 6.5
2018-07-15 13:15:39.267 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:39.318 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:39.338 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:39.340 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 59.1 to 58.0
2018-07-15 13:15:39.517 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from ON to OFF
2018-07-15 13:15:39.522 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 260.03 to 260.01
2018-07-15 13:15:40.362 [vent.ItemStateChangedEvent] - CPU_Load changed from 6.5 to 15.1
2018-07-15 13:15:40.415 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 59.1
2018-07-15 13:15:40.979 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 178.35 to 178.03
2018-07-15 13:15:41.444 [vent.ItemStateChangedEvent] - CPU_Load changed from 15.1 to 23.4
2018-07-15 13:15:41.455 [vent.ItemStateChangedEvent] - Storage_Available changed from 25997 to 25996
2018-07-15 13:15:42.545 [vent.ItemStateChangedEvent] - CPU_Load changed from 23.4 to 7.6
2018-07-15 13:15:42.579 [vent.ItemStateChangedEvent] - Storage_Available changed from 25996 to 25997
2018-07-15 13:15:42.626 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 59.1 to 58.5
2018-07-15 13:15:43.665 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:43.680 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.6 to 6.9
2018-07-15 13:15:43.688 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:43.724 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:43.728 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:44.767 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:44.809 [vent.ItemStateChangedEvent] - CPU_Load changed from 6.9 to 4.7
2018-07-15 13:15:44.821 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:44.855 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:44.858 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:44.861 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.5 to 58.0
2018-07-15 13:15:45.299 [vent.ItemStateChangedEvent] - Corridor_Temperature_LivingRoom changed from 22.49 to 22.35
2018-07-15 13:15:45.308 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from OFF to ON
2018-07-15 13:15:45.316 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 260.01 to 259.92
2018-07-15 13:15:45.893 [vent.ItemStateChangedEvent] - CPU_Load changed from 4.7 to 9.4
2018-07-15 13:15:45.988 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 178.03 to 176.17
2018-07-15 13:15:47.010 [vent.ItemStateChangedEvent] - CPU_Load changed from 9.4 to 8.5
2018-07-15 13:15:48.125 [vent.ItemStateChangedEvent] - CPU_Load changed from 8.5 to 8.6
2018-07-15 13:15:49.246 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 480
2018-07-15 13:15:49.263 [vent.ItemStateChangedEvent] - CPU_Load changed from 8.6 to 8.0
2018-07-15 13:15:49.276 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.2
2018-07-15 13:15:49.315 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.8
2018-07-15 13:15:49.325 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:49.328 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 57.5
2018-07-15 13:15:50.294 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 259.92 to 259.81
2018-07-15 13:15:50.364 [vent.ItemStateChangedEvent] - Memory_Available changed from 480 to 482
2018-07-15 13:15:50.376 [vent.ItemStateChangedEvent] - CPU_Load changed from 8.0 to 16.3
2018-07-15 13:15:50.386 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.2 to 49.4
2018-07-15 13:15:50.419 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.8 to 50.6
2018-07-15 13:15:50.427 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:50.431 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 57.5 to 59.1
2018-07-15 13:15:55.272 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 259.81 to 259.75
2018-07-15 13:15:55.727 [vent.ItemStateChangedEvent] - CPU_Threads changed from 446 to 452
2018-07-15 13:15:55.766 [vent.ItemStateChangedEvent] - Network_PacketsRecevied changed from 24691859 to 24692272
2018-07-15 13:15:55.787 [vent.ItemStateChangedEvent] - Network_DataRecevied changed from 15587 to 15588
2018-07-15 13:15:55.809 [vent.ItemStateChangedEvent] - Network_PacketsSent changed from 22639702 to 22639965
2018-07-15 13:15:55.818 [vent.ItemStateChangedEvent] - CPU_Load1 changed from 1.0 to 1.1
2018-07-15 13:15:55.821 [vent.ItemStateChangedEvent] - CPU_Uptime changed from 40265.5 to 40266.5
2018-07-15 13:15:55.903 [vent.ItemStateChangedEvent] - Memory_Available changed from 482 to 481
2018-07-15 13:15:55.915 [vent.ItemStateChangedEvent] - CPU_Load changed from 16.3 to 7.4
2018-07-15 13:15:55.927 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.4 to 49.3
2018-07-15 13:15:55.937 [vent.ItemStateChangedEvent] - Storage_Available changed from 25997 to 25996
2018-07-15 13:15:55.963 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.6 to 50.7
2018-07-15 13:15:55.972 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 59.1 to 58.0
2018-07-15 13:15:55.979 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 176.17 to 175.75
2018-07-15 13:15:57.026 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.4 to 6.7
2018-07-15 13:15:57.090 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 56.9
2018-07-15 13:15:58.135 [vent.ItemStateChangedEvent] - Memory_Available changed from 481 to 479
2018-07-15 13:15:58.153 [vent.ItemStateChangedEvent] - CPU_Load changed from 6.7 to 7.5
2018-07-15 13:15:58.184 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.3 to 49.1
2018-07-15 13:15:58.225 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.7 to 50.9
2018-07-15 13:15:58.229 [vent.ItemStateChangedEvent] - Memory_Used changed from 494 to 496
2018-07-15 13:15:58.231 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 56.9 to 58.0
2018-07-15 13:15:59.264 [vent.ItemStateChangedEvent] - Memory_Available changed from 479 to 481
2018-07-15 13:15:59.286 [vent.ItemStateChangedEvent] - CPU_Load changed from 7.5 to 9.9
2018-07-15 13:15:59.296 [vent.ItemStateChangedEvent] - Memory_Available_Percent changed from 49.1 to 49.3
2018-07-15 13:15:59.326 [vent.ItemStateChangedEvent] - Memory_Used_Percent changed from 50.9 to 50.7
2018-07-15 13:15:59.333 [vent.ItemStateChangedEvent] - Memory_Used changed from 496 to 494
2018-07-15 13:15:59.336 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 58.0 to 56.9
and afterwards:
2018-07-15 13:16:20.276 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 259.47 to 259.40
2018-07-15 13:16:21.048 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 174.65 to 176.74
2018-07-15 13:16:24.900 [ome.event.ItemCommandEvent] - Item 'Holiday' received command OFF
2018-07-15 13:16:25.263 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 259.40 to 259.33
2018-07-15 13:16:26.052 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 176.74 to 176.99
2018-07-15 13:16:29.903 [vent.ItemStateChangedEvent] - Kitchen_Occupancy changed from ON to OFF
2018-07-15 13:16:30.295 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 259.33 to 259.29
2018-07-15 13:16:31.487 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from ON to OFF
2018-07-15 13:16:31.498 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 259.29 to 259.27
2018-07-15 13:16:32.675 [vent.ItemStateChangedEvent] - Kitchen_Occupancy changed from OFF to ON
2018-07-15 13:16:32.693 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 176.99 to 176.25
2018-07-15 13:16:34.241 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from OFF to ON
2018-07-15 13:16:34.256 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 259.27 to 259.19
2018-07-15 13:16:36.409 [vent.ItemStateChangedEvent] - Bathroom_Illuminance changed from 92.31 to 91.16
2018-07-15 13:16:37.297 [vent.ItemStateChangedEvent] - Corridor_Illuminance_LivingRoom changed from 116.29 to 114.27
2018-07-15 13:16:37.672 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 176.25 to 174.04
2018-07-15 13:16:39.183 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 259.19 to 258.97
2018-07-15 13:16:42.643 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 174.04 to 173.69
2018-07-15 13:16:44.148 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.97 to 259.01
2018-07-15 13:16:47.615 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 173.69 to 175.75
2018-07-15 13:16:49.087 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 259.01 to 258.96
2018-07-15 13:16:52.636 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 175.75 to 179.21
2018-07-15 13:16:54.057 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.96 to 258.35
2018-07-15 13:16:57.645 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 179.21 to 179.36
2018-07-15 13:16:59.054 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.35 to 258.20
2018-07-15 13:17:03.304 [vent.ItemStateChangedEvent] - Kitchen_Occupancy changed from ON to OFF
2018-07-15 13:17:03.320 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 179.36 to 179.44
2018-07-15 13:17:04.034 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.20 to 258.15
2018-07-15 13:17:09.022 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.15 to 258.13
2018-07-15 13:17:12.835 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from ON to OFF
2018-07-15 13:17:12.847 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.13 to 258.10
2018-07-15 13:17:17.182 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from OFF to ON
2018-07-15 13:17:17.193 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.10 to 258.06
2018-07-15 13:17:24.952 [ome.event.ItemCommandEvent] - Item 'Holiday' received command OFF
2018-07-15 13:17:32.089 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.06 to 258.16
2018-07-15 13:17:35.117 [vent.ItemStateChangedEvent] - Kitchen_Occupancy changed from OFF to ON
2018-07-15 13:17:35.133 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 179.44 to 179.36
2018-07-15 13:17:37.083 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.16 to 258.11
2018-07-15 13:17:38.623 [vent.ItemStateChangedEvent] - Corridor_Occupancy_LivingRoom changed from OFF to ON
2018-07-15 13:17:38.636 [vent.ItemStateChangedEvent] - Corridor_Illuminance_LivingRoom changed from 114.27 to 98.18
2018-07-15 13:17:38.687 [ome.event.ItemCommandEvent] - Item 'Corridor_Light_Door_Dimmer' received command 100
2018-07-15 13:17:38.698 [vent.ItemStateChangedEvent] - Corridor_Light_Door_Dimmer changed from 0 to 100
2018-07-15 13:17:38.705 [ome.event.ItemCommandEvent] - Item 'Corridor_Light_LivingRoom_Dimmer' received command 100
2018-07-15 13:17:38.724 [vent.ItemStateChangedEvent] - Corridor_Light_LivingRoom_Dimmer changed from 0 to 100
2018-07-15 13:17:40.166 [vent.ItemStateChangedEvent] - Kitchen_Illuminance changed from 179.36 to 179.59
2018-07-15 13:17:42.083 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.11 to 258.01
2018-07-15 13:17:43.640 [vent.ItemStateChangedEvent] - DiningRoom_Occupancy changed from ON to OFF
2018-07-15 13:17:43.650 [vent.ItemStateChangedEvent] - Corridor_Illuminance_LivingRoom changed from 98.18 to 152.23
2018-07-15 13:17:43.670 [vent.ItemStateChangedEvent] - DiningRoom_Illuminance changed from 258.01 to 258.11
2018-07-15 13:17:44.997 [vent.ItemStateChangedEvent] - Kitchen_Occupancy changed from ON to OFF
2018-07-15 13:17:48.565 [vent.ItemStateChangedEvent] - Corridor_Illuminance_LivingRoom changed from 152.23 to 151.94
2018-07-15 13:17:50.886 [vent.ItemStateChangedEvent] - Bathroom_Occupancy changed from OFF to ON
2018-07-15 13:17:50.905 [vent.ItemStateChangedEvent] - Bathroom_Illuminance changed from 91.16 to 96.66
2018-07-15 13:17:50.963 [ome.event.ItemCommandEvent] - Item 'Bathroom_Light_Shower_Dimmer' received command 100
2018-07-15 13:17:50.977 [vent.ItemStateChangedEvent] - Bathroom_Light_Shower_Dimmer changed from 0 to 100
2018-07-15 13:17:50.983 [ome.event.ItemCommandEvent] - Item 'Bathroom_Light_Window_Dimmer' received command 100
2018-07-15 13:17:51.000 [vent.ItemStateChangedEvent] - Bathroom_Light_Window_Dimmer changed from 0 to 100
2018-07-15 13:17:53.487 [vent.ItemStateChangedEvent] - Corridor_Illuminance_LivingRoom changed from 151.94 to 158.51
2018-07-15 13:17:55.905 [vent.ItemStateChangedEvent] - Bathroom_Illuminance changed from 96.66 to 165.93
2018-07-15 13:17:58.453 [vent.ItemStateChangedEvent] - Corridor_Illuminance_LivingRoom changed from 158.51 to 160.46
As you can see, there are no occurances from Systeminfo any more. Answers that your question?
Yes, and it is important because the cause and therefore the solution would be different if it were just your Rules. Unfortunately, I know how to help if it were just your Rules. Since it is the Items I don’t have anything to offer.
The strange thing is that only some of the Items stop updating. I will try to uninstall the tado° binding and see if Items will stop updating as well.
Are all non updating items related to the same binding? Then probably the installed version of the binding might have a problem. You should check in the OH-Log for Java exceptions related to that binding.
I had such problems with the Somfy Tahoma binding (Version 20180611). It worked a couple of days and suddenly it stopped without obvious reason. I could find Java exceptions in the log with timestamp close to the stopp of item updates. I also experienced the same with the dev version of the Z-Wave binding a couple of days ago. In each case, the binding developers were able to provide an new version and now they run stable.
I am not sure if your problem is similar but it might be. OH keeps on running, some bindings still update while others stopp working? Then this might be the cause.
I experience a similar problem where item of several bindings are not reacting or are not updated suddenly after several weeks of normal operation. This seems not to be a problem of one particular binding but OH itself.
I’ll make a guess that something hogs the limited execution threads available for quartz to run timed jobs, which some bindings rely on to e.g. periodic poll or recalculate.
I don’t know how you’d go about tracking that down. Start with a critical look at any rules timers you may have.
Thanks, will try next time the issue appears. However, there seems to be a lot of information given with “threads --monitors --locks” … curios to see how to identify the problem …
It does give a lot of information. I can’t remember how a problem actual looks like (I haven’t any problems either at the moment). However I think you can look for the names that start with org.openhab.binding. These are threads in bindings. It can be a valid thread, like serial bindings waiting for input. But it can also be blocked thread in a binding. It’s very likely blocking threads occur before the whole system blocks. Because it will block only when all threads are exhausted. If you have the problem again and you can’t find the exact threads feel free to post the output here.
Problem is back earlier than expected. Nuki lock does not react on commands while status is been read correctly. This is the extract from “threads --monitors --locks”:
"ESH-httpClient-common-104" Id=104 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@18caf4f
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:656)
at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:49)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:720)
at java.lang.Thread.run(Unknown Source)
"ESH-httpClient-common-105" Id=105 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@18caf4f
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:656)
at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:49)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:720)
at java.lang.Thread.run(Unknown Source)
"ESH-httpClient-common-106" Id=106 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@18caf4f
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:656)
at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:49)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:720)
at java.lang.Thread.run(Unknown Source)
"ESH-httpClient-common-107" Id=107 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@18caf4f
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:656)
at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:49)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:720)
at java.lang.Thread.run(Unknown Source)
"ESH-OSGiEventManager" Id=111 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@da9705
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at java.util.concurrent.LinkedBlockingQueue.poll(Unknown Source)
at org.eclipse.smarthome.core.internal.events.ThreadedEventHandler.lambda$0(ThreadedEventHandler.java:61)
at org.eclipse.smarthome.core.internal.events.ThreadedEventHandler$$Lambda$384/6095163.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
and also
"ESH-httpClient-common-104" Id=104 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@18caf4f
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:656)
at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:49)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:720)
at java.lang.Thread.run(Unknown Source)
"ESH-httpClient-common-105" Id=105 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@18caf4f
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:656)
at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:49)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:720)
at java.lang.Thread.run(Unknown Source)
"ESH-httpClient-common-106" Id=106 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@18caf4f
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:656)
at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:49)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:720)
at java.lang.Thread.run(Unknown Source)
"ESH-httpClient-common-107" Id=107 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@18caf4f
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:656)
at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:49)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:720)
at java.lang.Thread.run(Unknown Source)
"ESH-OSGiEventManager" Id=111 in TIMED_WAITING on lock=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@da9705
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
at java.util.concurrent.LinkedBlockingQueue.poll(Unknown Source)
at org.eclipse.smarthome.core.internal.events.ThreadedEventHandler.lambda$0(ThreadedEventHandler.java:61)
at org.eclipse.smarthome.core.internal.events.ThreadedEventHandler$$Lambda$384/6095163.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
and later …
"ESH-thingHandler-5" Id=202 in BLOCKED on lock=org.openhab.binding.nuki.internal.dataexchange.NukiHttpClient@1ad8ead
owned by ESH-thingHandler-1 Id=194
at org.openhab.binding.nuki.internal.dataexchange.NukiHttpClient.getBridgeLockAction(NukiHttpClient.java:165)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler.handleCommandDecimalType(NukiSmartLockHandler.java:208)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler.lambda$3(NukiSmartLockHandler.java:75)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler$$Lambda$1016/14610683.run(Unknown Source)
at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
at java.util.concurrent.FutureTask.run(Unknown Source)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(Unknown Source)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Locked synchronizers: count = 1
- java.util.concurrent.ThreadPoolExecutor$Worker@1732856
/tmp/oh_block.txt lines 587-622/1655 37%
- java.util.concurrent.ThreadPoolExecutor$Worker@37b3b7
"ESH-thingHandler-3" Id=196 in BLOCKED on lock=org.openhab.binding.nuki.internal.dataexchange.NukiHttpClient@1ad8ead
owned by ESH-thingHandler-1 Id=194
at org.openhab.binding.nuki.internal.dataexchange.NukiHttpClient.getBridgeLockAction(NukiHttpClient.java:165)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler.handleCommandDecimalType(NukiSmartLockHandler.java:208)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler.lambda$3(NukiSmartLockHandler.java:75)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler$$Lambda$1016/14610683.run(Unknown Source)
at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
at java.util.concurrent.FutureTask.run(Unknown Source)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(Unknown Source)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Locked synchronizers: count = 1
- java.util.concurrent.ThreadPoolExecutor$Worker@12a8178
"ESH-thingHandler-5" Id=202 in BLOCKED on lock=org.openhab.binding.nuki.internal.dataexchange.NukiHttpClient@1ad8ead
owned by ESH-thingHandler-1 Id=194
at org.openhab.binding.nuki.internal.dataexchange.NukiHttpClient.getBridgeLockAction(NukiHttpClient.java:165)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler.handleCommandDecimalType(NukiSmartLockHandler.java:208)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler.lambda$3(NukiSmartLockHandler.java:75)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler$$Lambda$1016/14610683.run(Unknown Source)
at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
at java.util.concurrent.FutureTask.run(Unknown Source)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(Unknown Source)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Locked synchronizers: count = 1
- java.util.concurrent.ThreadPoolExecutor$Worker@1732856
some time later there is the following error message in the log:
00:35:09.266 [ERROR] [jersey.server.ServerRuntime$Responder] - An I/O error has occurred while writing a response message entity to the container output stream.
org.glassfish.jersey.server.internal.process.MappableException: org.eclipse.jetty.io.EofException
at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:92) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[171:org.glassfish.jersey.core.jersey-common:2.22.2]
at org.glassfish.jersey.message.internal.MessageBodyFactory.writeTo(MessageBodyFactory.java:1130) ~[171:org.glassfish.jersey.core.jersey-common:2.22.2]
at org.glassfish.jersey.server.ServerRuntime$Responder.writeResponse(ServerRuntime.java:711) [172:org.glassfish.jersey.core.jersey-server:2.22.2]
at org.glassfish.jersey.server.ServerRuntime$Responder.processResponse(ServerRuntime.java:444) [172:org.glassfish.jersey.core.jersey-server:2.22.2]
at org.glassfish.jersey.server.ServerRuntime$Responder.process(ServerRuntime.java:434) [172:org.glassfish.jersey.core.jersey-server:2.22.2]
at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:329) [172:org.glassfish.jersey.core.jersey-server:2.22.2]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [171:org.glassfish.jersey.core.jersey-common:2.22.2]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [171:org.glassfish.jersey.core.jersey-common:2.22.2]
at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [171:org.glassfish.jersey.core.jersey-common:2.22.2]
at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [171:org.glassfish.jersey.core.jersey-common:2.22.2]
at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [171:org.glassfish.jersey.core.jersey-common:2.22.2]
at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [171:org.glassfish.jersey.core.jersey-common:2.22.2]
at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [172:org.glassfish.jersey.core.jersey-server:2.22.2]
at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [172:org.glassfish.jersey.core.jersey-server:2.22.2]
at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [169:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [169:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [169:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [169:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [169:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [20:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253]
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) [85:org.eclipse.jetty.servlet:9.4.12.v20180830]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [85:org.eclipse.jetty.servlet:9.4.12.v20180830]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [186:org.ops4j.pax.web.pax-web-jetty:7.2.5]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [82:org.eclipse.jetty.security:9.4.12.v20180830]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1340) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [186:org.ops4j.pax.web.pax-web-jetty:7.2.5]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [85:org.eclipse.jetty.servlet:9.4.12.v20180830]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1242) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [186:org.ops4j.pax.web.pax-web-jetty:7.2.5]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.Server.handle(Server.java:503) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) [75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765) [87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683) [87:org.eclipse.jetty.util:9.4.12.v20180830]
at java.lang.Thread.run(Unknown Source) [?:?]
Caused by: org.eclipse.jetty.io.EofException
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:286) ~[75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:393) ~[75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.WriteFlusher.write(WriteFlusher.java:277) ~[75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.AbstractEndPoint.write(AbstractEndPoint.java:380) ~[75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:802) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.util.IteratingCallback.processing(IteratingCallback.java:241) ~[87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.util.IteratingCallback.iterate(IteratingCallback.java:224) ~[87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpConnection.send(HttpConnection.java:538) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:831) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpChannel.write(HttpChannel.java:887) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:239) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:215) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:540) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at java.io.OutputStream.write(Unknown Source) ~[?:?]
at org.glassfish.jersey.servlet.internal.ResponseWriter$NonCloseableOutputStreamWrapper.write(ResponseWriter.java:320) ~[?:?]
at org.glassfish.jersey.message.internal.CommittingOutputStream.write(CommittingOutputStream.java:218) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$UnCloseableOutputStream.write(WriterInterceptorExecutor.java:294) ~[?:?]
at org.eclipse.smarthome.io.rest.core.internal.GsonProvider.writeTo(GsonProvider.java:71) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$TerminalWriterInterceptor.invokeWriteTo(WriterInterceptorExecutor.java:265) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$TerminalWriterInterceptor.aroundWriteTo(WriterInterceptorExecutor.java:250) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[?:?]
at org.glassfish.jersey.server.internal.JsonWithPaddingInterceptor.aroundWriteTo(JsonWithPaddingInterceptor.java:106) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[?:?]
at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:86) ~[?:?]
... 53 more
Caused by: java.io.IOException: DatenĂĽbergabe unterbrochen (broken pipe)
at sun.nio.ch.FileDispatcherImpl.writev0(Native Method) ~[?:?]
at sun.nio.ch.SocketDispatcher.writev(Unknown Source) ~[?:?]
at sun.nio.ch.IOUtil.write(Unknown Source) ~[?:?]
at sun.nio.ch.SocketChannelImpl.write(Unknown Source) ~[?:?]
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:266) ~[75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:393) ~[75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.WriteFlusher.write(WriteFlusher.java:277) ~[75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.AbstractEndPoint.write(AbstractEndPoint.java:380) ~[75:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:802) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.util.IteratingCallback.processing(IteratingCallback.java:241) ~[87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.util.IteratingCallback.iterate(IteratingCallback.java:224) ~[87:org.eclipse.jetty.util:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpConnection.send(HttpConnection.java:538) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:831) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpChannel.write(HttpChannel.java:887) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:239) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:215) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:540) ~[84:org.eclipse.jetty.server:9.4.12.v20180830]
at java.io.OutputStream.write(Unknown Source) ~[?:?]
at org.glassfish.jersey.servlet.internal.ResponseWriter$NonCloseableOutputStreamWrapper.write(ResponseWriter.java:320) ~[?:?]
at org.glassfish.jersey.message.internal.CommittingOutputStream.write(CommittingOutputStream.java:218) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$UnCloseableOutputStream.write(WriterInterceptorExecutor.java:294) ~[?:?]
at org.eclipse.smarthome.io.rest.core.internal.GsonProvider.writeTo(GsonProvider.java:71) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$TerminalWriterInterceptor.invokeWriteTo(WriterInterceptorExecutor.java:265) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$TerminalWriterInterceptor.aroundWriteTo(WriterInterceptorExecutor.java:250) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[?:?]
at org.glassfish.jersey.server.internal.JsonWithPaddingInterceptor.aroundWriteTo(JsonWithPaddingInterceptor.java:106) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[?:?]
at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:86) ~[?:?]
... 53 more
I can’t identify a root cause though … does this help?
These blocked threads might be the problem. It could be no issue, but If these threads are still there after several minutes that’s very likely an indication of a problem. Based on the source code of the binding, I could not directly pinpoint if a problem, but the code could use some attention. The handle of commands is performed in scheduled threads and part of that is synchronized. In general the combination of threads and synchronization is a likely cause for deadlocks and I would expect something like that is happening here.
So I’ll ping @mkatter here, the original developer of this binding, to see if he has time to look into this (and also to make some general improvements that should improve overall stability. I can provide help with that.)
Yes, the blocked nuki threads are still there (after hours).
In addition, I experience problems with hue and vitotronic as well. Items are not reacting or not being updated. I don’t see the blocked hue binding threads though. Sometimes, I do see the following instead:
"ESH-thingHandler-5" Id=202 in BLOCKED on lock=org.openhab.binding.nuki.internal.dataexchange.NukiHttpClient@1ad8ead
at org.openhab.binding.nuki.internal.dataexchange.NukiHttpClient.getBridgeLockAction(NukiHttpClient.java:165)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler.handleCommandDecimalType(NukiSmartLockHandler.java:208)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler.lambda$3(NukiSmartLockHandler.java:75)
at org.openhab.binding.nuki.handler.NukiSmartLockHandler$$Lambda$1016/14610683.run(Unknown Source)
at org.openhab.binding.vitotronic.handler.VitotronicBridgeHandler$2.run(VitotronicBridgeHandler.java:276)
at org.openhab.binding.garadget.internal.Connection.sendCommand(Connection.java:276)
at org.openhab.binding.garadget.internal.Connection.sendCommand(Connection.java:182)
at org.openhab.binding.garadget.internal.GaradgetBinding.execute(GaradgetBinding.java:247)
at org.openhab.core.binding.AbstractActiveBinding$BindingActiveService.execute(AbstractActiveBinding.java:148)
whereas the garadget entries disappear after a while. However, I do pull data via the garadget binding every second. This may be a reason why I do see this tasks that often.
The vitotronic entry on the other side remains as well and is stuck as well. I don’t see updates on the data. Bottom line: there are more bindings impacted than just nuki.
