HUE Issue after Upgrade from OH 3.3 to 3.4

Daniel_Kaiser · February 25, 2023, 4:51pm

Hi community,

I’ve upgraded my OH 3.3 to 3.4.2-1. OH is running on a Proxmox LXC Ubuntu 22.04 LTS and my HUE is connected over IP running on an Raspberry Pi 3 with Conbee II stick.

After the upgrade everything works like a charm, except the HUE integration. Sometimes the items have a massive delay after changes/triggers. In the log, I can see the following errortext - repeated for every Item:

2023-02-25 17:40:35.035 [ERROR] [core.thing.internal.ThingManagerImpl] - Exception occurred during notification about bridge status change on thing ‘hue:0100:00212e06a9cc:9’: null
java.lang.NullPointerException: null
at org.openhab.binding.hue.internal.handler.HueLightHandler.initializeCapabilities(HueLightHandler.java:197) ~[?:?]
at org.openhab.binding.hue.internal.handler.HueLightHandler.initializeThing(HueLightHandler.java:148) ~[?:?]
at org.openhab.binding.hue.internal.handler.HueLightHandler.bridgeStatusChanged(HueLightHandler.java:129) ~[?:?]
at org.openhab.core.thing.internal.ThingManagerImpl.lambda$13(ThingManagerImpl.java:1047) ~[?:?]
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) [?:?]

Hope there is a solution …

Thanks Daniel

Lolodomo · February 25, 2023, 8:16pm

Please enable the TRACE logs of the binding temporarily, just to see the data returned by the API just before your NPE: log:set TRACE org.openhab.binding.hue
It looks like the data you receive is not what is expected by the binding.
You can also open an issue in Github.

Daniel_Kaiser · February 26, 2023, 7:59am

Hi,

here are the logs:

events.log

2023-02-26 08:44:26.157 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:bridge:00212e06a9cc' changed from ONLINE to OFFLINE: Verbindung zur Hue Bridge unterbrochen.
2023-02-26 08:44:26.158 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:10' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.159 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:11' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.159 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:12' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.161 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:13' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.161 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:14' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.161 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:15' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.161 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:16' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.161 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0220:00212e06a9cc:2' changed from OFFLINE: Die Hue Bridge meldet, dass die Lampe nicht erreichbar ist. to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.161 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0220:00212e06a9cc:4' changed from OFFLINE: Die Hue Bridge meldet, dass die Lampe nicht erreichbar ist. to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.161 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0010:00212e06a9cc:5' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.161 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:6' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.162 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:7' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.162 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0000:00212e06a9cc:8' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2023-02-26 08:44:26.162 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hue:0100:00212e06a9cc:9' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

openhab.log

2023-02-26 08:44:21.154 [TRACE] [ng.hue.internal.connection.HueBridge] - Hue request: GET - URL = 'http://192.168.3.150:80/api/77D8400921/sensors'
2023-02-26 08:44:26.154 [DEBUG] [ng.hue.internal.connection.HueBridge] - TimeoutException occurred during execution: Total timeout 5000 ms elapsed
java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
	at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:334) ~[?:?]
	at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:314) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts.onTimeoutExpired(CyclicTimeouts.java:110) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts.access$100(CyclicTimeouts.java:50) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts$Timeouts.onTimeoutExpired(CyclicTimeouts.java:196) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeout$Wakeup.run(CyclicTimeout.java:298) ~[?:?]
	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) [?:?]
2023-02-26 08:44:26.156 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.3.150 lost: Total timeout 5000 ms elapsed
org.openhab.core.i18n.CommunicationException: Total timeout 5000 ms elapsed
	at org.openhab.binding.hue.internal.connection.HueBridge.doNetwork(HueBridge.java:1118) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.doNetwork(HueBridge.java:1084) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.get(HueBridge.java:1067) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.getSensors(HueBridge.java:279) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$1.doConnectedRun(HueBridgeHandler.java:203) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$PollingRunnable.run(HueBridgeHandler.java:137) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	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) [?:?]
2023-02-26 08:44:26.156 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection lost. Updating thing status to OFFLINE.
2023-02-26 08:44:26.157 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.157 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:10 bridge status OFFLINE
2023-02-26 08:44:26.157 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.157 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:11 bridge status OFFLINE
2023-02-26 08:44:26.158 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.158 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:12 bridge status OFFLINE
2023-02-26 08:44:26.159 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.159 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:13 bridge status OFFLINE
2023-02-26 08:44:26.159 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.159 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:14 bridge status OFFLINE
2023-02-26 08:44:26.159 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.159 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:15 bridge status OFFLINE
2023-02-26 08:44:26.159 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.159 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:16 bridge status OFFLINE
2023-02-26 08:44:26.159 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0220:00212e06a9cc:2 bridge status OFFLINE
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0220:00212e06a9cc:4 bridge status OFFLINE
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0010:00212e06a9cc:5 bridge status OFFLINE
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:6 bridge status OFFLINE
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:7 bridge status OFFLINE
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:8 bridge status OFFLINE
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged OFFLINE: @text/offline.bridge-connection-lost
2023-02-26 08:44:26.160 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0100:00212e06a9cc:9 bridge status OFFLINE
2023-02-26 08:44:26.659 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.3.150 established.
2023-02-26 08:44:26.659 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection resumed.

It looks like the bridge doesn’t respond in the 5s invervall:question:

after that the following entries occure:

2023-02-26 08:44:26.659 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.3.150 established.
2023-02-26 08:44:26.659 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection resumed.
2023-02-26 08:44:26.660 [TRACE] [ng.hue.internal.connection.HueBridge] - Hue request: GET - URL = 'http://192.168.3.150:80/api/77D8400921/sensors'
2023-02-26 08:44:31.660 [DEBUG] [ng.hue.internal.connection.HueBridge] - TimeoutException occurred during execution: Total timeout 5000 ms elapsed
java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
	at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:334) ~[?:?]
	at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:314) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts.onTimeoutExpired(CyclicTimeouts.java:110) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts.access$100(CyclicTimeouts.java:50) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts$Timeouts.onTimeoutExpired(CyclicTimeouts.java:196) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeout$Wakeup.run(CyclicTimeout.java:298) ~[?:?]
	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) [?:?]
2023-02-26 08:44:31.662 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.3.150 lost: Total timeout 5000 ms elapsed
org.openhab.core.i18n.CommunicationException: Total timeout 5000 ms elapsed
	at org.openhab.binding.hue.internal.connection.HueBridge.doNetwork(HueBridge.java:1118) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.doNetwork(HueBridge.java:1084) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.get(HueBridge.java:1067) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.getSensors(HueBridge.java:279) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$1.doConnectedRun(HueBridgeHandler.java:203) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$PollingRunnable.run(HueBridgeHandler.java:137) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	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) [?:?]
2023-02-26 08:44:31.662 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection lost. Updating thing status to OFFLINE.
2023-02-26 08:44:31.662 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.3.150 established.
2023-02-26 08:44:31.662 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection resumed.
2023-02-26 08:44:31.662 [TRACE] [ng.hue.internal.connection.HueBridge] - Hue request: GET - URL = 'http://192.168.3.150:80/api/77D8400921/lights'
2023-02-26 08:44:36.663 [DEBUG] [ng.hue.internal.connection.HueBridge] - TimeoutException occurred during execution: Total timeout 5000 ms elapsed
java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
	at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:334) ~[?:?]
	at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:314) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts.onTimeoutExpired(CyclicTimeouts.java:110) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts.access$100(CyclicTimeouts.java:50) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts$Timeouts.onTimeoutExpired(CyclicTimeouts.java:196) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeout$Wakeup.run(CyclicTimeout.java:298) ~[?:?]
	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) [?:?]
2023-02-26 08:44:36.664 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.3.150 lost: Total timeout 5000 ms elapsed
org.openhab.core.i18n.CommunicationException: Total timeout 5000 ms elapsed
	at org.openhab.binding.hue.internal.connection.HueBridge.doNetwork(HueBridge.java:1118) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.doNetwork(HueBridge.java:1084) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.get(HueBridge.java:1067) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.getTypedLights(HueBridge.java:252) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.getFullLights(HueBridge.java:231) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$2.updateLights(HueBridgeHandler.java:252) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$2.doConnectedRun(HueBridgeHandler.java:243) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$PollingRunnable.run(HueBridgeHandler.java:137) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	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) [?:?]
2023-02-26 08:44:36.665 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection lost. Updating thing status to OFFLINE.
2023-02-26 08:44:36.665 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.3.150 established.
2023-02-26 08:44:36.665 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection resumed.
2023-02-26 08:44:36.665 [TRACE] [ng.hue.internal.connection.HueBridge] - Hue request: GET - URL = 'http://192.168.3.150:80/api/77D8400921/sensors'
2023-02-26 08:44:41.665 [DEBUG] [ng.hue.internal.connection.HueBridge] - TimeoutException occurred during execution: Total timeout 5000 ms elapsed
java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
	at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:334) ~[?:?]
	at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:314) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts.onTimeoutExpired(CyclicTimeouts.java:110) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts.access$100(CyclicTimeouts.java:50) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeouts$Timeouts.onTimeoutExpired(CyclicTimeouts.java:196) ~[?:?]
	at org.eclipse.jetty.io.CyclicTimeout$Wakeup.run(CyclicTimeout.java:298) ~[?:?]
	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) [?:?]
2023-02-26 08:44:41.667 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.3.150 lost: Total timeout 5000 ms elapsed
org.openhab.core.i18n.CommunicationException: Total timeout 5000 ms elapsed
	at org.openhab.binding.hue.internal.connection.HueBridge.doNetwork(HueBridge.java:1118) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.doNetwork(HueBridge.java:1084) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.get(HueBridge.java:1067) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.connection.HueBridge.getSensors(HueBridge.java:279) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$1.doConnectedRun(HueBridgeHandler.java:203) ~[bundleFile:?]
	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$PollingRunnable.run(HueBridgeHandler.java:137) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	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) [?:?]
2023-02-26 08:44:41.667 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection lost. Updating thing status to OFFLINE.
2023-02-26 08:44:42.167 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.3.150 established.
2023-02-26 08:44:42.167 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection resumed.
2023-02-26 08:44:42.167 [TRACE] [ng.hue.internal.connection.HueBridge] - Hue request: GET - URL = 'http://192.168.3.150:80/api/77D8400921/sensors'
2023-02-26 08:44:46.831 [TRACE] [ng.hue.internal.connection.HueBridge] - Hue response: status = 200, content = '{"1":{"config":{"configured":true,"on":true,"sunriseoffset":30,"sunsetoffset":-30},"etag":"647df455d3968db43300e045025ac7b8","manufacturername":"Philips","modelid":"PHDL00","name":"Daylight","state":{"dark":false,"daylight":true,"lastupdated":"2023-02-26T06:50:32.949","status":160,"sunrise":"2023-02-26T06:04:28","sunset":"2023-02-26T16:45:23"},"swversion":"1.0","type":"Daylight","uniqueid":"00:21:2e:ff:ff:06:a9:cc-01"}}'
2023-02-26 08:44:46.831 [TRACE] [ue.internal.handler.HueBridgeHandler] - Hue sensor '1' added.
2023-02-26 08:44:46.832 [TRACE] [ng.hue.internal.connection.HueBridge] - Hue request: GET - URL = 'http://192.168.3.150:80/api/77D8400921/lights'
2023-02-26 08:44:46.832 [DEBUG] [hue.internal.handler.HueLightHandler] - bridgeStatusChanged ONLINE
2023-02-26 08:44:46.832 [DEBUG] [hue.internal.handler.HueLightHandler] - initializeThing thing hue:0000:00212e06a9cc:10 bridge status ONLINE

After that the Bridge is online, but all things remain in state ERROR:BRIDGE. Restarting the Bridge solves the issue - for about 5 Minuts. In the things setting I’ve found the Sensor-Intervall with 500ms, maybe this is the root cause, but on OH 3.3 everything works fine. For testing I’ve set this to 1s…

Best regards

AndrewFG · February 26, 2023, 11:59am

Apropos the Hue discussion, (although I am not saying that this is in fact the cause), but please bear in mind that there have been at least two Hue Hub firmware updates between the release of OH 3.3 and OH 3.4 (just a thought)

e.g. I cant give specific details, but I think something changed concerning scenes in the February firmware…

brianlay · February 26, 2023, 12:27pm

I’m a bit confused about your issue.
Are you using both a Hue gateway and a DeConz stick, so you have some Zigbee devices using the Hue gateway and other devices using the Deconz?
My system (3.4.2) uses only the Hue gateway and the polling interval of 10 and sensor interval of 500 work fine.

Daniel_Kaiser · February 26, 2023, 4:26pm

Hi togehter,

@brianlay I’m using a DeConz Conbee II stick over the HUE bridge. (Conbee II on Rasperry propagating IP:80 → OpenHAB → HUE Binding on the IP → Things → Items)
Why? In the past DeConz wasn’t able to interact directly with OpenHAB. The information can be still found in the DeConz binding: “The Zigbee binding currently does not support the Dresden Elektronik Raspbee and Conbee Zigbee dongles.”

Today I gave the DeConz binding a new chance and it looks like, everything works fine. So I’ve switched all things to the DeConz binding and everything works as expected.

Nevertheless, untill OH 3.3 the DeConz over HUE works fine!

I think we can close this.

Thanks @all

rene54321 · March 20, 2023, 8:23pm

Hi,

I have the same issue with disconnects from the hue bridge. I use an directly connection to the hue bridge but after the upgrade to the version 3.4 the bridge disconnects.

The trace shows the following before the connection is lost:

2023-03-20 21:18:22.156 [TRACE] [ng.hue.internal.connection.HueBridge] - Hue request: GET - URL = 'https://192.168.0.41:443/api/hQOE6JTx1fnx5c/sensors'

2023-03-20 21:18:22.181 [TRACE] [ng.hue.internal.connection.HueBridge] - Hue response: status = 429, content = '<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.0 Transitional//EN">

<html>

<head>

    <title>hue personal wireless lighting</title>

    <link rel="stylesheet" type="text/css" href="/index.css">

</head>

<body>

    <div class="philips-header">

      <img src="/philips-blue.png" class="philips-logo" alt="Philips" />

    </div>

    <div class="header">

      <img class="header-logo" src="/hue-logo.png" alt="hue personal wireless lighting" />

      <img src="/hue-color-line.png" class="colorline" />

    </div>

    <div class="error">Oops, there appears to be no lighting here</div>

</body>

</html>

'

2023-03-20 21:18:22.181 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Connection to Hue Bridge 192.168.0.41 lost: null

java.io.IOException: null

	at org.openhab.binding.hue.internal.connection.HueBridge.handleErrors(HueBridge.java:1016) ~[bundleFile:?]

	at org.openhab.binding.hue.internal.connection.HueBridge.getSensors(HueBridge.java:281) ~[bundleFile:?]

	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$1.doConnectedRun(HueBridgeHandler.java:203) ~[bundleFile:?]

	at org.openhab.binding.hue.internal.handler.HueBridgeHandler$PollingRunnable.run(HueBridgeHandler.java:137) [bundleFile:?]

	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]

	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]

	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]

	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) [?:?]

2023-03-20 21:18:22.182 [DEBUG] [ue.internal.handler.HueBridgeHandler] - Bridge connection lost. Updating thing status to OFFLINE.

Any help would be great.

best regards René

AndrewFG · March 21, 2023, 12:05am

From my experience, this ‘Oops’ response is an infrequent ‘feature’ of the Hue bridge. I think it happens when the bridge is overloaded, perhaps while it is doing housekeeping, or updating itself. I am not sure that OH can do much about it…

brianlay · March 21, 2023, 1:12am

Can you clarify that you are using the Hue binding connected to Hue hardware?
The original poster was using the Hue binding connected to DeConz hardware.
Which one are you using?

rene54321 · March 21, 2023, 5:58pm

Hi,

I use the binding connected to an HUE-Bridge.

best regards René

Lee_Humphreys · May 1, 2023, 8:10am

I have the same issue. Hue Bridge Firmware is up-to-date:

[DEBUG] [ng.hue.internal.connection.HueBridge] - TimeoutException occurred during execution: Total timeout 5000 ms elapsed

This happens in my logs every few minutes. Adjusting Timeout values in the Bindings makes no difference.

Lolodomo · May 1, 2023, 2:03pm

Code 429 means too many requests. You overloaded your bridge.
Try to increase refresh delay for sensors, it is a bridge configuration setting of your bridge thing.