Hue items doesn't work after a time. No issue in the logs

Add-ons Bindings
61

Today the system is messed up again.
Lamps work, i.e. commands, but the movement messages AND brightness changes don’t come at all or are delayed and then in a block.

What I notice in the log is that the message from the bridge comes immediately (light or movement). Immediately afterwards there is an SSL error to the AndroidTV, which is not online. Incorrect IP, which continues to appear although I have now entered the correct IP in the Thing.

==> /var/log/openhab/openhab.log <==
2024-12-17 18:46:00.971 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:46:00Z","data":[{"id":"8c98351f-fd56-474b-9667-b6e9e2ff4432","id_v1":"/sensors/41","motion":{"motion":true,"motion_report":{"changed":"2024-12-17T17:46:00.922Z","motion":true},"motion_valid":true},"owner":{"rid":"76ba713a-f91d-48fb-8dfa-e1ee48ac9601","rtype":"device"},"type":"motion"}],"id":"295946a3-f665-4ae9-8a0e-25ecfbe63199","type":"update"}]
2024-12-17 18:46:01.588 [INFO ] [l.googletv.GoogleTVConnectionManager] - 38E1F45B0AD3 - Error opening SSL connection to 172.16.50.182:6467 No route to host
2024-12-17 18:46:01.588 [INFO ] [l.googletv.GoogleTVConnectionManager] - 38E1F45B0AD3 - Error opening SSL connection to 172.16.50.182:6467 No route to host
2024-12-17 18:46:01.981 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:46:01Z","data":[{"id":"aab07fb5-b0c1-400b-828f-a9e0a05394f9","motion":{"motion_report":{"changed":"2024-12-17T17:46:01.003Z","motion":true}},"owner":{"rid":"bb9b4bb4-b0c8-4b6a-8dc0-85add8f77964","rtype":"bridge_home"},"type":"grouped_motion"}],"id":"fe825bc6-71bc-436b-87e4-7bd6635aa6ef","type":"update"},{"creationtime":"2024-12-17T17:46:01Z","data":[{"id":"82276b4d-0c7e-4049-b409-4dd0d00ca4cf","id_v1":"/sensors/42","light":{"light_level":2272,"light_level_report":{"changed":"2024-12-17T17:46:01.123Z","light_level":2272},"light_level_valid":true},"owner":{"rid":"76ba713a-f91d-48fb-8dfa-e1ee48ac9601","rtype":"device"},"type":"light_level"}],"id":"1e6c35f2-8764-4342-b3db-ecb23264b5ab","type":"update"}]
2024-12-17 18:46:04.660 [INFO ] [l.googletv.GoogleTVConnectionManager] - 38E1F45B0AD3 - Error opening SSL connection to 172.16.50.182:6467 No route to host
2024-12-17 18:46:04.660 [INFO ] [l.googletv.GoogleTVConnectionManager] - 38E1F45B0AD3 - Error opening SSL connection to 172.16.50.182:6467 No route to host
2024-12-17 18:46:04.660 [INFO ] [l.googletv.GoogleTVConnectionManager] - 38E1F45B0AD3 - Error opening SSL connection to 172.16.50.182:6467 No route to host
2024-12-17 18:46:05.942 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:46:05Z","data":[{"id":"49de8c61-42aa-4fd0-90b6-b79d6b28a275","id_v1":"/sensors/25","owner":{"rid":"14d1ac9c-f342-475f-a051-cc3586ed66be","rtype":"device"},"temperature":{"temperature":18.94,"temperature_report":{"changed":"2024-12-17T17:46:05.904Z","temperature":18.94},"temperature_valid":true},"type":"temperature"}],"id":"4783905c-fb08-465e-aaf3-ac9e47e36a44","type":"update"}]
2024-12-17 18:46:06.948 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:46:06Z","data":[{"id":"82276b4d-0c7e-4049-b409-4dd0d00ca4cf","id_v1":"/sensors/42","light":{"light_level":8993,"light_level_report":{"changed":"2024-12-17T17:46:06.210Z","light_level":8993},"light_level_valid":true},"owner":{"rid":"76ba713a-f91d-48fb-8dfa-e1ee48ac9601","rtype":"device"},"type":"light_level"}],"id":"8e6265fe-0994-44f6-a531-c657a4514c7e","type":"update"}]
2024-12-17 18:46:07.732 [INFO ] [l.googletv.GoogleTVConnectionManager] - 38E1F45B0AD3 - Error opening SSL connection to 172.16.50.182:6467 No route to host
2024-12-17 18:46:07.732 [INFO ] [l.googletv.GoogleTVConnectionManager] - 38E1F45B0AD3 - Error opening SSL connection to 172.16.50.182:6467 No route to host
2024-12-17 18:46:07.732 [INFO ] [l.googletv.GoogleTVConnectionManager] - 38E1F45B0AD3 - Error opening SSL connection to 172.16.50.182:6467 No route to host

Another example:

2024-12-17 18:58:23.519 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:58:23Z","data":[{"id":"8c98351f-fd56-474b-9667-b6e9e2ff4432","id_v1":"/sensors/41","motion":{"motion":true,"motion_report":{"changed":"2024-12-17T17:58:23.475Z","motion":true},"motion_valid":true},"owner":{"rid":"76ba713a-f91d-48fb-8dfa-e1ee48ac9601","rtype":"device"},"type":"motion"}],"id":"351d6f6a-3485-4564-b370-2e839e28ac62","type":"update"}]
2024-12-17 18:58:24.529 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:58:23Z","data":[{"id":"aab07fb5-b0c1-400b-828f-a9e0a05394f9","motion":{"motion_report":{"changed":"2024-12-17T17:58:23.538Z","motion":true}},"owner":{"rid":"bb9b4bb4-b0c8-4b6a-8dc0-85add8f77964","rtype":"bridge_home"},"type":"grouped_motion"}],"id":"9e53eef2-9f98-4275-85b3-3bca79ffe01b","type":"update"},{"creationtime":"2024-12-17T17:58:23Z","data":[{"id":"82276b4d-0c7e-4049-b409-4dd0d00ca4cf","id_v1":"/sensors/42","light":{"light_level":3733,"light_level_report":{"changed":"2024-12-17T17:58:23.723Z","light_level":3733},"light_level_valid":true},"owner":{"rid":"76ba713a-f91d-48fb-8dfa-e1ee48ac9601","rtype":"device"},"type":"light_level"}],"id":"2aff92b7-36b3-4df1-b4b2-1fdc473dbd24","type":"update"}]
==> /var/log/openhab/events.log <==
2024-12-17 18:58:26.407 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'pwrWZhifi_kWh' changed from 426.13 to 426.14
2024-12-17 18:58:26.415 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'pwrWZhifi_kWh_day' changed from 1.87 to 1.88
==> /var/log/openhab/openhab.log <==
2024-12-17 18:58:26.415 [INFO ] [.openhab.core.model.script.pwrWZhifi] - itmpwrSocket_kWh  initialisiert! 1.88kWh / 0.5264€
==> /var/log/openhab/events.log <==
2024-12-17 18:58:26.417 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'pwrWZhifi_Cost_day' changed from 0.5236 to 0.5264
==> /var/log/openhab/openhab.log <==
2024-12-17 18:58:28.803 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:58:28Z","data":[{"id":"82276b4d-0c7e-4049-b409-4dd0d00ca4cf","id_v1":"/sensors/42","light":{"light_level":7587,"light_level_report":{"changed":"2024-12-17T17:58:28.762Z","light_level":7587},"light_level_valid":true},"owner":{"rid":"76ba713a-f91d-48fb-8dfa-e1ee48ac9601","rtype":"device"},"type":"light_level"}],"id":"0da9b16e-7b68-4f02-b86c-ee1b8d91ec54","type":"update"}]
2024-12-17 18:58:33.459 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:58:33Z","data":[{"id":"8c98351f-fd56-474b-9667-b6e9e2ff4432","id_v1":"/sensors/41","motion":{"motion":false,"motion_report":{"changed":"2024-12-17T17:58:33.415Z","motion":false},"motion_valid":true},"owner":{"rid":"76ba713a-f91d-48fb-8dfa-e1ee48ac9601","rtype":"device"},"type":"motion"}],"id":"2e163cba-49ab-46f6-a3a7-a482b38d2843","type":"update"}]
2024-12-17 18:58:34.472 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:58:33Z","data":[{"id":"aab07fb5-b0c1-400b-828f-a9e0a05394f9","motion":{"motion_report":{"changed":"2024-12-17T17:58:33.494Z","motion":false}},"owner":{"rid":"bb9b4bb4-b0c8-4b6a-8dc0-85add8f77964","rtype":"bridge_home"},"type":"grouped_motion"}],"id":"9c66d1c1-2f91-40d3-8f19-ca7426ee1d7b","type":"update"}]
2024-12-17 18:58:41.858 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:58:41Z","data":[{"id":"8c98351f-fd56-474b-9667-b6e9e2ff4432","id_v1":"/sensors/41","motion":{"motion":true,"motion_report":{"changed":"2024-12-17T17:58:41.816Z","motion":true},"motion_valid":true},"owner":{"rid":"76ba713a-f91d-48fb-8dfa-e1ee48ac9601","rtype":"device"},"type":"motion"}],"id":"bd8dac13-a04a-4905-a668-d899e70a411a","type":"update"}]
==> /var/log/openhab/events.log <==
2024-12-17 18:58:41.970 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'pwrAZpc_Watt_akt' changed from 187.8 to 218.8
==> /var/log/openhab/openhab.log <==
2024-12-17 18:58:42.867 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T17:58:41Z","data":[{"id":"aab07fb5-b0c1-400b-828f-a9e0a05394f9","motion":{"motion_report":{"changed":"2024-12-17T17:58:41.877Z","motion":true}},"owner":{"rid":"bb9b4bb4-b0c8-4b6a-8dc0-85add8f77964","rtype":"bridge_home"},"type":"grouped_motion"}],"id":"de2e6bd0-217f-4584-b86b-2bc618fc91be","type":"update"}]
2024-12-17 18:58:45.162 [INFO ] [hab.core.model.script.Beamer pruefen] - Der Receiver läuft auf HDMI3
2024-12-17 18:58:45.168 [INFO ] [hab.core.model.script.Beamer pruefen] - Der Fernseher läuft aber
2024-12-17 18:58:45.171 [INFO ] [hab.core.model.script.Beamer pruefen] - Zustand seit 5 Minute so...
2024-12-17 18:58:45.175 [INFO ] [hab.core.model.script.Beamer pruefen] - und Beamerstatus wie vermutet OFF
2024-12-17 18:58:50.826 [INFO ] [.core.model.script.Bewegungspruefung] - Bewohner anwesend ...

The Log filtered for _Motion:

2024-12-17 19:03:36.755 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T18:03:35Z","data":[{"id":"aab07fb5-b0c1-400b-828f-a9e0a05394f9","motion":{"motion_report":{"changed":"2024-12-17T18:03:35.765Z","motion":true}},"owner":{"rid":"bb9b4bb4-b0c8-4b6a-8dc0-85add8f77964","rtype":"bridge_home"},"type":"grouped_motion"}],"id":"6b022c13-61e3-4ff7-b66e-3af6eb92fb30","type":"update"}]
2024-12-17 19:03:52.553 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T18:03:51Z","data":[{"id":"aab07fb5-b0c1-400b-828f-a9e0a05394f9","motion":{"motion_report":{"changed":"2024-12-17T18:03:51.563Z","motion":false}},"owner":{"rid":"bb9b4bb4-b0c8-4b6a-8dc0-85add8f77964","rtype":"bridge_home"},"type":"grouped_motion"}],"id":"b2b76ed6-9888-43f3-9803-e1cadbeb5cf5","type":"update"}]
2024-12-17 19:04:17.084 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from OFF to ON
2024-12-17 19:04:17.092 [INFO ] [nhab.core.model.script.Flur_EG_Timer] - FlurEG: TriggerItem setzen: SENSOR_Motion: ON
2024-12-17 19:04:17.103 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:02:03.517+0100 to 2024-12-17T19:02:08.353+0100
2024-12-17 19:04:17.133 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'eyeFlurEG_Motion_Kurve' changed from 6 to 6.9
2024-12-17 19:04:25.151 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Temperature_Last_Updated' changed from 2024-12-17T18:52:21.342+0100 to 2024-12-17T19:02:17.412+0100
2024-12-17 19:04:25.160 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from ON to OFF
2024-12-17 19:04:25.163 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:02:08.353+0100 to 2024-12-17T19:02:18.319+0100
2024-12-17 19:04:25.175 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'eyeFlurEG_Motion_Kurve' changed from 6.9 to 6
2024-12-17 19:04:31.630 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T18:04:30Z","data":[{"id":"aab07fb5-b0c1-400b-828f-a9e0a05394f9","motion":{"motion_report":{"changed":"2024-12-17T18:04:30.642Z","motion":true}},"owner":{"rid":"bb9b4bb4-b0c8-4b6a-8dc0-85add8f77964","rtype":"bridge_home"},"type":"grouped_motion"}],"id":"95c9308e-09a6-437c-9f4d-b6bab164d41b","type":"update"}]
2024-12-17 19:04:37.109 [INFO ] [nhab.core.model.script.Flur_EG_Timer] - FlurEG: Trigger gefeuert: hue_Motion: OFF
2024-12-17 19:04:41.592 [TRACE] [.hue.internal.connection.Clip2Bridge] - onEventData() data:[{"creationtime":"2024-12-17T18:04:40Z","data":[{"id":"aab07fb5-b0c1-400b-828f-a9e0a05394f9","motion":{"motion_report":{"changed":"2024-12-17T18:04:40.604Z","motion":false}},"owner":{"rid":"bb9b4bb4-b0c8-4b6a-8dc0-85add8f77964","rtype":"bridge_home"},"type":"grouped_motion"}],"id":"880accb4-17b9-4a79-bac3-cae8cd4e8186","type":"update"}]
2024-12-17 19:04:48.804 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from OFF to ON
2024-12-17 19:04:48.821 [INFO ] [nhab.core.model.script.Flur_EG_Timer] - FlurEG: TriggerItem setzen: SENSOR_Motion: ON
2024-12-17 19:04:48.828 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:02:18.319+0100 to 2024-12-17T19:02:42.958+0100
2024-12-17 19:04:48.857 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'eyeFlurEG_Motion_Kurve' changed from 6 to 6.9
2024-12-17 19:05:05.089 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from ON to OFF
2024-12-17 19:05:05.096 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:02:42.958+0100 to 2024-12-17T19:03:16.611+0100
2024-12-17 19:05:05.108 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from OFF to ON
2024-12-17 19:05:05.109 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:03:16.611+0100 to 2024-12-17T19:03:17.823+0100
2024-12-17 19:05:05.118 [INFO ] [nhab.core.model.script.Flur_EG_Timer] - FlurEG: TriggerItem setzen: SENSOR_Motion: ON
2024-12-17 19:05:05.123 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from ON to OFF
2024-12-17 19:05:05.146 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'eyeFlurEG_Motion_Kurve' changed from 6.9 to 6
2024-12-17 19:05:05.149 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:03:17.823+0100 to 2024-12-17T19:03:27.791+0100
2024-12-17 19:05:05.156 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from OFF to ON
2024-12-17 19:05:05.158 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:03:27.791+0100 to 2024-12-17T19:03:35.705+0100
2024-12-17 19:05:05.168 [INFO ] [nhab.core.model.script.Flur_EG_Timer] - FlurEG: TriggerItem setzen: SENSOR_Motion: ON
2024-12-17 19:05:05.181 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'eyeFlurEG_Motion_Kurve' changed from 6 to 6.9
2024-12-17 19:05:05.241 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from ON to OFF
2024-12-17 19:05:05.245 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:03:35.705+0100 to 2024-12-17T19:03:51.502+0100
2024-12-17 19:05:05.256 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'eyeFlurEG_Motion_Kurve' changed from 6.9 to 6
2024-12-17 19:05:08.154 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from OFF to ON
2024-12-17 19:05:08.159 [INFO ] [nhab.core.model.script.Flur_EG_Timer] - FlurEG: TriggerItem setzen: SENSOR_Motion: ON
2024-12-17 19:05:08.168 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:03:51.502+0100 to 2024-12-17T19:04:30.582+0100
2024-12-17 19:05:08.180 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'eyeFlurEG_Motion_Kurve' changed from 6 to 6.9
2024-12-17 19:05:08.235 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'hue_FlurE_Motion' changed from ON to OFF
2024-12-17 19:05:08.244 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T19:04:30.582+0100 to 2024-12-17T19:04:40.543+0100
2024-12-17 19:05:08.261 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'eyeFlurEG_Motion_Kurve' changed from 6.9 to 6

HTOP shows no significant CPU load.
Nevertheless, the messages seem to hang in a queue before they update the items.

Here the complete log:
Event&Openhab-Log.txt (848.3 KB)

regards
Thomas

62

Please confirm that your lamps DO NOT WORK. You may be able to command things but you are not seeing any event status updates for ANYTHING. It is not an issue with motion detectors. It is a much bigger problem.

63

Update from my side. No issues so far since I uninstalled the VS Code openhab extension.

64

Good morning Andrew,

NO Lamps work fine!
If I change them over Alexa or Dashboard,
I immediately get a reaction on the lamp and in the log. If I trigger a movement at the sensor or change the brightness in the faulty system status, I see the incoming message in the debug. However, these are not forwarded to the corresponding item. At least not immediately. If you wait several (>10) minutes, a whole bunch of change events suddenly appear. I cannot say whether they are all in the correct order.
It is also not yet clear to me what triggers this behavior.
I was out of the house for several hours yesterday and when I came back, I suspected that the movement was not recognized again and then started the analysis.

My findings so far:

  • Trigger unknown
  • Messages from the bridge are received, but not immediately forwarded to the corresponding item
  • The command direction to the lamps works fine
  • Suspicion: a connection with the AndroidTV binding. Since the wrong IP always appeared several times in the log following a HUE message. I will now integrate the associated media player in OH so that I can check whether there are parallel errors in the connection.
  • I cannot recognize a correlation with VS, because the error does not occur when I am working with VS, but he did when I am not present at all.

Edit:
next time I have to check if changing a Lamp will update the corrospending item too.
The Alexa Item is linked direct to the hue channel. But the plug has it’s owen item, that has to change too…

Thomas

65

Please focus on the ‘onEventData()’ messages. They contain a ‘creationTime’ when the event was created by the Hue bridge. And the log entry shows the time when the event was received by OH. And it seems those two times are always mutually consistent (your system is one hour ahead of UTC). => Do you agree?

Inside the binding all ‘onEventData()’ messages are processed in the same way regardless of their content. And forwarded by the binding to OH by updating the respective channels. There is absolutely no discrimination inside the binding concerning the processing of motion events (except that a different channel gets notified).

66

Yes the onEventData messages are logged exactly when they are send from te bridge.
But the item change event doesn’t match. The event was recognised by the bridge at 17:02:24 and shows up in the log at 17:11:02

2024-12-17 17:11:02.880 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T17:02:24.249+0100 to 2024-12-17T17:06:11.226+0100

So for me it seems not to be a problem with motion or lux. Looks more like a delayed processing of the change event.

Here you see that something change yesterday after 10:00. At the end there is a short delay:

2024-12-17 10:01:10.829 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Temperature_Last_Updated' changed from 2024-12-17T09:56:13.061+0100 to 2024-12-17T10:01:10.790+0100
2024-12-17 10:16:47.298 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Temperature_Last_Updated' changed from 2024-12-17T10:01:10.790+0100 to 2024-12-17T10:16:04.105+0100
2024-12-17 10:21:47.261 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Temperature_Last_Updated' changed from 2024-12-17T10:16:04.105+0100 to 2024-12-17T10:21:01.839+0100
2024-12-17 10:27:15.569 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T09:59:52.526+0100 to 2024-12-17T10:25:54.471+0100
2024-12-17 10:27:17.301 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Temperature_Last_Updated' changed from 2024-12-17T10:21:01.839+0100 to 2024-12-17T10:25:59.604+0100
2024-12-17 10:27:17.319 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T10:25:54.471+0100 to 2024-12-17T10:26:08.854+0100
2024-12-17 10:31:57.471 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T10:26:08.854+0100 to 2024-12-17T10:31:27.623+0100
2024-12-17 10:31:57.789 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T10:31:27.623+0100 to 2024-12-17T10:31:42.072+0100
2024-12-17 10:40:56.615 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Temperature_Last_Updated' changed from 2024-12-17T10:25:59.604+0100 to 2024-12-17T10:40:52.943+0100
2024-12-17 10:46:09.102 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Temperature_Last_Updated' changed from 2024-12-17T10:40:52.943+0100 to 2024-12-17T10:45:50.767+0100
2024-12-17 10:51:43.875 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Temperature_Last_Updated' changed from 2024-12-17T10:45:50.767+0100 to 2024-12-17T10:50:48.582+0100

and this delay grows over the time:

2024-12-17 18:33:22.038 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T18:29:03.302+0100 to 2024-12-17T18:29:17.595+0100
2024-12-17 18:33:22.072 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T18:29:17.595+0100 to 2024-12-17T18:29:59.460+0100
2024-12-17 18:33:22.096 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T18:29:59.460+0100 to 2024-12-17T18:30:18.885+0100
2024-12-17 18:33:22.123 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T18:30:18.885+0100 to 2024-12-17T18:30:58.626+0100
2024-12-17 18:33:22.166 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T18:30:58.626+0100 to 2024-12-17T18:31:08.578+0100
2024-12-17 18:33:22.212 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T18:31:08.578+0100 to 2024-12-17T18:31:09.791+0100
2024-12-17 18:33:22.232 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T18:31:09.791+0100 to 2024-12-17T18:31:19.750+0100
2024-12-17 18:33:24.939 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T18:31:19.750+0100 to 2024-12-17T18:31:21.769+0100
2024-12-17 18:33:27.141 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Motion_Last_Updated' changed from 2024-12-17T18:31:21.769+0100 to 2024-12-17T18:31:34.728+0100
2024-12-17 18:33:27.155 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hue_Motion_Flur_EG_Temperature_Last_Updated' changed from 2024-12-17T18:27:31.170+0100 to 2024-12-17T18:32:29.231+0100
67

It looks like a problem of thread pool exhaustion in OH core. Which brings us back to the question of what else do you have running in OH that could result in thread pool exhaustion? It more or less confirms that there is nothing I can do in the binding to fix this…

68
69

I have created a rule that triggers on the change of Last_Update. I want to compare the message time with the update time and generate a message if the difference is greater than 1 second.
But I still have problems with the syntax around the current time with the state of the item. :crazy_face:

I stumbled across another problem that I had suppressed, because in my tests the lighting values were delivered almost simultaneously with the movement messages. Normally, however, changes in brightness are only reported every 5 minutes. So if I switch the lighting off again independently of the hue sensor, i.e. without a change in movement, it is still bright for OH for 5 minutes. And when I go back out into the hallway three minutes later, the light stays off.
Let’s see how I have to program around it… :thinking:

Anyway, I’ll let you know when I can say more about the offset. I have the feeling that the offset will decrease again if it doesn’t get too big.
But first I have to improve the logging.

70

What do you mean? The bridge sends update events for all things immediately. As I have said repeatedly before there is no difference between events for lights or any other signals.

71

Yes, but I fear the sensor is updating the bridge only every 5 minutes or or when he detects a motion.

This behavior has nothing to do with the delay!

72

When you talk about ‘brightness’ are you talking about the illuminance sensor in the motion/illuminance/temperature sensor? You made me think that you were talking about the brightness of a light. The latter always update immediately. But the former may indeed have a delay built in (that is hard coded in the device, and up to Philips / Signify how often they poll the illuminance; and indeed the temperature, sensors).

73

Exactly!
We are in the hands of Phillips.

I have previously used Fibaro Motion Sensors. They report an adjustable percentage change.
Unfortunately, the batteries were used up very quickly. And since it happened again and again that the connection to the network was lost after changing the batteries, I switched to HUE Motion, where the batteries last forever and are inexpensive.
Only to discover the 5-minute interval. But I think I can work around the problem with program logic. I just remember the LUX value before I switch on the light. And evaluate this value at the next movement with…