Hi,
I got the issue that, the deCONZ binding stops updating item values after a very short period of time, like just several hours.
Once I restart the plugin, everything is fine.
I have the trace logging enabled for this plugin for several weeks now, but there is only one thing that stuck out:
2022-12-05 20:45:24.278 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 20:45:26.281 [DEBUG] [internal.handler.DeconzBridgeHandler] - Get full state failed
java.util.concurrent.CompletionException: java.util.concurrent.TimeoutException: Total timeout 2000 ms elapsed
at java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) ~[?:?]
at java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) ~[?:?]
at java.util.concurrent.CompletableFuture$UniApply.tryFire(CompletableFuture.java:632) ~[?:?]
at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) [?:?]
at java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) [?:?]
at org.openhab.binding.deconz.internal.netutils.AsyncHttpClient$1.onComplete(AsyncHttpClient.java:109) [bundleFile:?]
at org.eclipse.jetty.client.ResponseNotifier.notifyComplete(ResponseNotifier.java:218) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.ResponseNotifier.notifyComplete(ResponseNotifier.java:210) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpReceiver.terminateResponse(HttpReceiver.java:481) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpReceiver.terminateResponse(HttpReceiver.java:461) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpReceiver.abort(HttpReceiver.java:557) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpChannel.abortResponse(HttpChannel.java:152) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpChannel.abort(HttpChannel.java:145) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpExchange.abort(HttpExchange.java:264) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpConversation.abort(HttpConversation.java:164) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpRequest.abort(HttpRequest.java:819) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:334) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:314) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.io.CyclicTimeouts.onTimeoutExpired(CyclicTimeouts.java:110) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.io.CyclicTimeouts.access$100(CyclicTimeouts.java:50) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.io.CyclicTimeouts$Timeouts.onTimeoutExpired(CyclicTimeouts.java:196) [bundleFile:9.4.46.v20220331]
at org.eclipse.jetty.io.CyclicTimeout$Wakeup.run(CyclicTimeout.java:298) [bundleFile:9.4.46.v20220331]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
at java.lang.Thread.run(Thread.java:829) [?:?]
Caused by: java.util.concurrent.TimeoutException: Total timeout 2000 ms elapsed
... 12 more
2022-12-05 20:48:58.770 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'deconz:deconz:00212E051D3D' to inbox.
2022-12-05 20:50:24.286 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 20:55:24.294 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
and before this happened i also occasionally got these messages, which seem perfectly fine to me:
2022-12-05 20:32:18.926 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1669930819656-8 received raw data: {"attr":{"id":"8","lastannounced":"2022-10-31T11:33:16Z","lastseen":"2022-12-05T19:32Z","manufacturername":"OSRAM","modelid":"Plug 01","name":"Kitchen Table","swversion":"V1.04.90","type":"On/Off plug-in unit","uniqueid":"7c:b0:3e:aa:0a:06:ff:36-03"},"e":"changed","id":"8","r":"lights","t":"event","uniqueid":"7c:b0:3e:aa:0a:06:ff:36-03"}
2022-12-05 20:32:18.929 [TRACE] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 8 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2022-12-05 20:32:32.512 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1669930819656-8 received raw data: {"attr":{"id":"6","lastannounced":null,"lastseen":"2022-12-05T19:32Z","manufacturername":"_TZ3000_ena8vgqb","modelid":"TS0201","name":"Bedroom-TempHum","swversion":null,"type":"ZHATemperature","uniqueid":"a4:c1:38:a2:6f:a0:33:5d-01-0402"},"e":"changed","id":"6","r":"sensors","t":"event","uniqueid":"a4:c1:38:a2:6f:a0:33:5d-01-0402"}
2022-12-05 20:32:32.519 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:temperaturesensor:b046f57718:a4c138a26fa0335d010402 received SensorMessage{type='', config=null, state=null, e='changed', r='SENSORS', t='event', id='6', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='a4:c1:38:a2:6f:a0:33:5d-01-0402'}
2022-12-05 20:32:32.519 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1669930819656-8 received raw data: {"e":"changed","id":"6","r":"sensors","state":{"lastupdated":"2022-12-05T19:32:32.389","temperature":1818},"t":"event","uniqueid":"a4:c1:38:a2:6f:a0:33:5d-01-0402"}
2022-12-05 20:32:32.520 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:temperaturesensor:b046f57718:a4c138a26fa0335d010402 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=1818.0, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, airquality=null, airqualityppb=null, pressure=null, presence=null, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=null, gesture=null, valve=null, windowopen='null', lastupdated='2022-12-05T19:32:32.389', xy=null}, e='changed', r='SENSORS', t='event', id='6', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='a4:c1:38:a2:6f:a0:33:5d-01-0402'}
2022-12-05 20:32:32.520 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1669930819656-8 received raw data: {"attr":{"id":"7","lastannounced":null,"lastseen":"2022-12-05T19:32Z","manufacturername":"_TZ3000_ena8vgqb","modelid":"TS0201","name":"Bedroom-TempHum","swversion":null,"type":"ZHAHumidity","uniqueid":"a4:c1:38:a2:6f:a0:33:5d-01-0405"},"e":"changed","id":"7","r":"sensors","t":"event","uniqueid":"a4:c1:38:a2:6f:a0:33:5d-01-0405"}
2022-12-05 20:32:32.520 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:humiditysensor:b046f57718:a4c138a26fa0335d010405 received SensorMessage{type='', config=null, state=null, e='changed', r='SENSORS', t='event', id='7', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='a4:c1:38:a2:6f:a0:33:5d-01-0405'}
2022-12-05 20:32:32.556 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1669930819656-8 received raw data: {"e":"changed","id":"7","r":"sensors","state":{"humidity":6327,"lastupdated":"2022-12-05T19:32:32.536"},"t":"event","uniqueid":"a4:c1:38:a2:6f:a0:33:5d-01-0405"}
2022-12-05 20:32:32.556 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:humiditysensor:b046f57718:a4c138a26fa0335d010405 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=6327.0, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, airquality=null, airqualityppb=null, pressure=null, presence=null, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=null, gesture=null, valve=null, windowopen='null', lastupdated='2022-12-05T19:32:32.536', xy=null}, e='changed', r='SENSORS', t='event', id='7', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='a4:c1:38:a2:6f:a0:33:5d-01-0405'}
2022-12-05 20:32:38.675 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1669930819656-8 received raw data: {"attr":{"id":"1","lastannounced":null,"lastseen":"2022-12-05T19:32Z","manufacturername":"dresden elektronik","modelid":"ConBee II","name":"Configuration tool 1","swversion":"0x26720700","type":"Configuration tool","uniqueid":"00:21:2e:ff:ff:05:1d:3d-01"},"e":"changed","id":"1","r":"lights","t":"event","uniqueid":"00:21:2e:ff:ff:05:1d:3d-01"}
2022-12-05 20:32:38.679 [TRACE] [nternal.netutils.WebSocketConnection] -
But after that big error message only these messages:
2022-12-05 21:55:24.338 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:00:24.341 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:05:24.345 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:10:24.346 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:15:24.351 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:20:24.352 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:25:24.358 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:30:24.369 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:35:24.376 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:40:24.378 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
2022-12-05 22:45:24.382 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:b046f57718 starts refreshing the fullStateCache
Any clue what might be going on?