Hue Dimmer Switch gets pressed without human interaction

[fanfadrian]

Issue

Hey,

I encountered a strange behavior in my setup for the second time now. After homebridge-hue lost connection to the Hue bridge (v2, latest update), in the case of today, because of a scheduled Hue bridge update, all of my dimmer switches get pressed once "magically" over the next minutes after homebridge-hue reconnected to the Hue bridge. When this first appeared a couple of weeks ago it freaked me out a bit, because I thought somebody hacked my system. I changed all passwords and checked if any system was breached. I found nothing. I disconnected the Hue bridge from the hue.com online service, so that also all hue components are only online via Homekit. It didn't help. The problem reoccurred tonight.

To be a bit more precise what you can also see in the logs:

• Homebridge-hue loses connection to the Hue bridge (4:56:07 AM)
• After it reconnected (4:57:24 AM), everything seems fine, but as you can see at 5:01:27 AM, 5:03:31 AM and 5:09:40 AM for every Hue dimmer switch one button is "magically" pressed while I was asleep ("homekit button single press"). There was no human interaction with these switches at all! There are also no automations regarding these switches in Homekit at a all.
• What I also can't explain is the "hostname: homebridge: Host not found" right after 5:05:16 AM. Maybe this has something to do with it?

I would appreciate any feedback on what can cause that issue. If my setup is wrong, or if it's a bug.
And thanks again for you amazing work!

Best regards,
Adrian

Log Messages

[37m[5/11/2022, 4:53:07 AM] [39m[36m[Hue][39m Hue temperature sensor 1: set homekit temperature from 21.8°C to 21.9°C
[37m[5/11/2022, 4:54:46 AM] [39m[36m[Hue][39m Hue temperature sensor 2: set homekit temperature from 22°C to 22.2°C
[37m[5/11/2022, 4:56:04 AM] [39m[36m[Hue][39m Büro Multisensor: set homekit humidity from 52% to 53%
[37m[5/11/2022, 4:56:04 AM] [39m[36m[Hue][39m Büro Multisensor: set homekit pressure from 1008 hPa to 1007 hPa
[37m[5/11/2022, 4:56:07 AM] [39m[36m[Hue][39m Hue Bridge: request 1752930: GET /sensors
[37m[5/11/2022, 4:56:07 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752930: http status 502 Bad Gateway[39m
[37m[5/11/2022, 4:56:07 AM] [39m[36m[Hue][39m [33mHue Bridge: event stream connection to https://192.168.178.31/eventstream/clip/v2 closed - retrying in 15s[39m
[37m[5/11/2022, 4:56:08 AM] [39m[36m[Hue][39m Hue Bridge: request 1752931: GET /sensors
[37m[5/11/2022, 4:56:08 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752931: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:15 AM] [39m[36m[Hue][39m Hue Bridge: request 1752932: GET /sensors
[37m[5/11/2022, 4:56:15 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752932: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:17 AM] [39m[36m[Hue][39m Hue Bridge: request 1752933: GET /sensors
[37m[5/11/2022, 4:56:17 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752933: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:19 AM] [39m[36m[Hue][39m Hue Bridge: request 1752934: GET /sensors
[37m[5/11/2022, 4:56:19 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752934: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:21 AM] [39m[36m[Hue][39m Hue Bridge: request 1752935: GET /sensors
[37m[5/11/2022, 4:56:21 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752935: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:23 AM] [39m[36m[Hue][39m Hue Bridge: request 1752936: GET /sensors
[37m[5/11/2022, 4:56:23 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752936: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:25 AM] [39m[36m[Hue][39m Hue Bridge: request 1752937: GET /sensors
[37m[5/11/2022, 4:56:25 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752937: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:27 AM] [39m[36m[Hue][39m Hue Bridge: request 1752938: GET /sensors
[37m[5/11/2022, 4:56:27 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752938: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:29 AM] [39m[36m[Hue][39m Hue Bridge: request 1752939: GET /sensors
[37m[5/11/2022, 4:56:29 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752939: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:31 AM] [39m[36m[Hue][39m Hue Bridge: request 1752940: GET /sensors
[37m[5/11/2022, 4:56:31 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752940: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:33 AM] [39m[36m[Hue][39m Hue Bridge: request 1752941: GET /sensors
[37m[5/11/2022, 4:56:33 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752941: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:35 AM] [39m[36m[Hue][39m Hue Bridge: request 1752942: GET /sensors
[37m[5/11/2022, 4:56:35 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752942: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:37 AM] [39m[36m[Hue][39m Hue Bridge: request 1752943: GET /sensors
[37m[5/11/2022, 4:56:37 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752943: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:39 AM] [39m[36m[Hue][39m Hue Bridge: request 1752944: GET /sensors
[37m[5/11/2022, 4:56:39 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752944: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:41 AM] [39m[36m[Hue][39m Hue Bridge: request 1752945: GET /sensors
[37m[5/11/2022, 4:56:41 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752945: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:42 AM] [39m[36m[Hue][39m Flur Multisensor: set homekit pressure from 1007 hPa to 1008 hPa
[37m[5/11/2022, 4:56:43 AM] [39m[36m[Hue][39m Hue Bridge: request 1752946: GET /sensors
[37m[5/11/2022, 4:56:43 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752946: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:44 AM] [39m[36m[Hue][39m Hue Bridge: request 1752949: GET /sensors
[37m[5/11/2022, 4:56:44 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752949: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:45 AM] [39m[36m[Hue][39m Hue Bridge: request 1752947: GET /sensors
[37m[5/11/2022, 4:56:45 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752947: timeout after 5 seconds[39m
[37m[5/11/2022, 4:56:45 AM] [39m[36m[Hue][39m Hue Bridge: request 1752948: GET /sensors
[37m[5/11/2022, 4:56:45 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752948: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:46 AM] [39m[36m[Hue][39m Hue Bridge: request 1752950: GET /sensors
[37m[5/11/2022, 4:56:46 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752950: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:48 AM] [39m[36m[Hue][39m Hue Bridge: request 1752951: GET /sensors
[37m[5/11/2022, 4:56:48 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752951: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:50 AM] [39m[36m[Hue][39m Hue Bridge: request 1752952: GET /sensors
[37m[5/11/2022, 4:56:50 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752952: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:52 AM] [39m[36m[Hue][39m Hue Bridge: request 1752953: GET /sensors
[37m[5/11/2022, 4:56:52 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752953: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:53 AM] [39m[36m[Hue][39m Hue Bridge: event stream request 3: GET /eventstream/clip/v2
[37m[5/11/2022, 4:56:53 AM] [39m[36m[Hue][39m [33mHue Bridge: event stream request 3: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:54 AM] [39m[36m[Hue][39m Hue Bridge: request 1752954: GET /sensors
[37m[5/11/2022, 4:56:54 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752954: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:56 AM] [39m[36m[Hue][39m Hue Bridge: request 1752955: GET /sensors
[37m[5/11/2022, 4:56:56 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752955: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:56:58 AM] [39m[36m[Hue][39m Hue Bridge: request 1752956: GET /sensors
[37m[5/11/2022, 4:56:58 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752956: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:00 AM] [39m[36m[Hue][39m Hue Bridge: request 1752957: GET /sensors
[37m[5/11/2022, 4:57:00 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752957: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:02 AM] [39m[36m[Hue][39m Hue Bridge: request 1752958: GET /sensors
[37m[5/11/2022, 4:57:02 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752958: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:04 AM] [39m[36m[Hue][39m Hue Bridge: request 1752959: GET /sensors
[37m[5/11/2022, 4:57:04 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752959: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:06 AM] [39m[36m[Hue][39m Hue Bridge: request 1752960: GET /sensors
[37m[5/11/2022, 4:57:06 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752960: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:08 AM] [39m[36m[Hue][39m Hue Bridge: event stream request 4: GET /eventstream/clip/v2
[37m[5/11/2022, 4:57:08 AM] [39m[36m[Hue][39m [33mHue Bridge: event stream request 4: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:08 AM] [39m[36m[Hue][39m Hue Bridge: request 1752961: GET /sensors
[37m[5/11/2022, 4:57:08 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752961: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:10 AM] [39m[36m[Hue][39m Hue Bridge: request 1752962: GET /sensors
[37m[5/11/2022, 4:57:10 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752962: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:12 AM] [39m[36m[Hue][39m Hue Bridge: request 1752963: GET /sensors
[37m[5/11/2022, 4:57:12 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752963: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:14 AM] [39m[36m[Hue][39m Hue Bridge: request 1752964: GET /sensors
[37m[5/11/2022, 4:57:14 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752964: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:16 AM] [39m[36m[Hue][39m Hue Bridge: request 1752965: GET /sensors
[37m[5/11/2022, 4:57:16 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752965: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:18 AM] [39m[36m[Hue][39m Hue Bridge: request 1752966: GET /sensors
[37m[5/11/2022, 4:57:18 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752966: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:20 AM] [39m[36m[Hue][39m Hue Bridge: request 1752967: GET /sensors
[37m[5/11/2022, 4:57:20 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752967: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:22 AM] [39m[36m[Hue][39m Hue Bridge: request 1752968: GET /sensors
[37m[5/11/2022, 4:57:22 AM] [39m[36m[Hue][39m [33mHue Bridge: request 1752968: connect ECONNREFUSED 192.168.178.31:443[39m
[37m[5/11/2022, 4:57:24 AM] [39m[36m[Hue][39m Hue Bridge: event stream connected to https://192.168.178.31/eventstream/clip/v2
[37m[5/11/2022, 4:58:07 AM] [39m[36m[Hue][39m Hue temperature sensor 1: set homekit temperature from 21.9°C to 21.8°C
[37m[5/11/2022, 4:58:08 AM] [39m[36m[Hue][39m Badezimmer Sensor: set homekit status fault from 0 to 1
[37m[5/11/2022, 4:58:08 AM] [39m[36m[Hue][39m Hue temperature sensor 4: set homekit status fault from 0 to 1
[37m[5/11/2022, 4:58:08 AM] [39m[36m[Hue][39m Hue ambient light sensor 4: set homekit status fault from 0 to 1
[37m[5/11/2022, 4:58:17 AM] [39m[36m[Hue][39m Badezimmer Sensor: set homekit status fault from 1 to 0
[37m[5/11/2022, 4:58:18 AM] [39m[36m[Hue][39m Hue temperature sensor 4: set homekit status fault from 1 to 0
[37m[5/11/2022, 4:58:18 AM] [39m[36m[Hue][39m Hue ambient light sensor 4: set homekit status fault from 1 to 0
[37m[5/11/2022, 4:58:52 AM] [39m[36m[Hue][39m Wohnzimmer Schalter: set homekit battery level from 86% to 76%
[37m[5/11/2022, 4:59:17 AM] [39m[36m[Hue][39m Kleines Bad Multisensor: set homekit pressure from 1007 hPa to 1006 hPa
[37m[5/11/2022, 5:00:22 AM] [39m[36m[Hue][39m Wohnzimmer Schalter: set homekit status fault from 0 to 1
[37m[5/11/2022, 5:01:27 AM] [39m[36m[Hue][39m Saugbert Schalter Dim Down: homekit button single press
[37m[5/11/2022, 5:01:27 AM] [39m[36m[Saugbert][39m INF getPauseState | roborock.vacuum.s5 | Pause possible is false
[37m[5/11/2022, 5:01:32 AM] [39m[36m[Hue][39m Hue temperature sensor 4: set homekit temperature from 22.1°C to 21.8°C
[37m[5/11/2022, 5:03:06 AM] [39m[36m[Hue][39m Hue temperature sensor 1: set homekit temperature from 21.8°C to 21.7°C
[37m[5/11/2022, 5:03:31 AM] [39m[36m[Hue][39m Alarmanlage Schalter On: homekit button single press
[37m[5/11/2022, 5:03:31 AM] [39m[36m[Alarm][39m Target mode (Away)
[37m[5/11/2022, 5:03:34 AM] [39m[36m[Alarm][39m Webhook event (Sent)
[37m[5/11/2022, 5:03:51 AM] [39m[36m[Alarm][39m Current mode (Away)
[37m[5/11/2022, 5:03:53 AM] [39m[36m[Alarm][39m Webhook event (Sent)
[37m[5/11/2022, 5:04:15 AM] [39m[36m[Hue][39m Hue temperature sensor 5: set homekit temperature from 21.7°C to 21.6°C
[37m[5/11/2022, 5:04:55 AM] [39m[36m[Hue][39m Badezimmer Multisensor: set homekit pressure from 1007 hPa to 1006 hPa
[37m[5/11/2022, 5:05:16 AM] [39m[36m[Hue][39m Wohnzimmer Multisensor: set homekit temperature from 23.8°C to 23.9°C
[37m[5/11/2022, 5:05:16 AM] [39m[36m[Hue][39m Flur Multisensor: set homekit pressure from 1008 hPa to 1007 hPa
hostname: homebridge: Host not found
[37m[5/11/2022, 5:05:31 AM] [39m[36m[Hue][39m Küche Multisensor: set homekit pressure from 1008 hPa to 1007 hPa
[37m[5/11/2022, 5:07:38 AM] [39m[36m[Hue][39m Wohnzimmer Multisensor: set homekit temperature from 23.9°C to 23.8°C
[37m[5/11/2022, 5:09:40 AM] [39m[36m[Hue][39m Büro Schalter Off: homekit button single press
[37m[5/11/2022, 5:09:44 AM] [39m[36m[Hue][39m Hue temperature sensor 2: set homekit temperature from 22.2°C to 22°C
[37m[5/11/2022, 5:11:31 AM] [39m[36m[Hue][39m Hue temperature sensor 4: set homekit temperature from 21.8°C to 22°C

Debug Files

[ebaauw]

Homebridge-hue loses connection to the Hue bridge (4:56:07 AM)

Why? Did the Hue bridge crash and reboot?

every Hue dimmer switch one button is "magically" pressed

Most likely because the Hue bridge reports a new value for lastupdated. I need to see the log with Homebridge debug mode enabled to see if that’s because of the event stream or through the API.

You might also want to double-check the clock on the Hue bridge vs the server running Homebridge.

What I also can't explain is the "hostname: homebridge: Host not found" right after 5:05:16 AM. Maybe this has something to do with it?

That message doesn’t come from Homebridge Hue. It could indicate an issue with your network / DNS server.

[fanfadrian]

Thanks for your super fast response!

Homebridge-hue loses connection to the Hue bridge (4:56:07 AM)

Why? Did the Hue bridge crash and reboot?

The hue bridge did a firmware update at that time, therefore I assumed that it rebooted because of that.

every Hue dimmer switch one button is "magically" pressed

Most likely because the Hue bridge reports a new value for lastupdated. I need to see the log with Homebridge debug mode enabled to see if that’s because of the event stream or through the API.

You might also want to double-check the clock on the Hue bridge vs the server running Homebridge.

Will do the clock check and and debug mode tomorrow. I hope I can emulate the behavior by just unplugging the Hue bridge for a minute or two.

What I also can't explain is the "hostname: homebridge: Host not found" right after 5:05:16 AM. Maybe this has something to do with it?

That message doesn’t come from Homebridge Hue. It could indicate an issue with your network / DNS server.

Okay, thanks for clarifying. Will deal with that later.

[fanfadrian]

Clock of homebridge server and Hue bridge match.

Unfortunately a restart (pull power plug) of the Hue bridge doesn't lead to the "ghost" pressing. I tired it two times. Leaving the Hue bridge powerless for at least a minute.

The behavior in the logs is the same (get sensors fails, EHOSTUNREACH and then ECONNREFUSED before get sensors works again) and then it cycles through a lot of my devices, like this:
"Wohnzimmer Stehlampe: light reachable changed from true to false"
"Wohnzimmer Stehlampe: set homekit status fault from 0 to 1"

But but it's not "cycling" through the dimmer switches (and magically presses each one of them), like at the two occasions I experienced it before.

I checked again and confirmed that the Hue bridge did a firmware update last night at the time. So my assumption is, that I can only replicate, or observe this behavior again, when the Hue bridge will do the next firmware update.

Is it safe and reasonable to leave the homebridge debug mode on until that will happen again? Even if the next firmware update of the Hue bridge will be in weeks or months?

Then I would just wait and see what is happening in the logs then.

[ebaauw]

Any updates? I think my bridge updated to 1952043030 (API version 1.52.0) this week.

[fanfadrian]

Thanks for the reminder, I missed that.

The behavior occurred after the update! Please find attached the log for two dimmer switches that gets magically pressed a couple of minutes after the hue bridge updated (Bridge came back online at [6/18/2022, 1:53:16 PM])

If I understand the logs correctly homebridge-hue recalls an actual button press (sensor buttonevent) of the past?! (For "Saugbert Schalter" 2022-06-16 and for "Alarmanlage Schalter" 2022-03-29)

This dimmer switch is called "Saugbert Schalter":

Log Messages

[6/18/2022, 1:57:05 PM] [Hue] Hue Bridge: request 4017196: 200 OK
[6/18/2022, 1:57:05 PM] [Hue] Hue Bridge: request 4017197: GET /groups
[6/18/2022, 1:57:05 PM] [Hue] Hue Bridge: request 4017197: 200 OK
[6/18/2022, 1:57:06 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T11:57:06Z","data":[{"id":"6786f4ed-44a6-4c3a-9c2c-4e72fcc02e05","id_v1":"/sensors/118","metadata":{"archetype":"unknown_archetype","name":"Saugbert Schalter"},"product_data":{"certified":true,"hardware_platform_type":"100b-109","manufacturer_name":"Signify Netherlands B.V.","model_id":"RWL021","product_archetype":"unknown_archetype","product_name":"Hue dimmer switch","software_version":"1.1.28573"},"services":[{"rid":"48a56c79-98dd-4966-a731-c1c0834ddc9b","rtype":"button"},{"rid":"5c8be81d-876d-4c38-8aa1-ae0fcd7191cd","rtype":"button"},{"rid":"5ab72d6a-468c-4b12-9aa1-fe2ce161927b","rtype":"button"},{"rid":"91734015-9700-4fd1-88b8-c1df96ce5564","rtype":"button"},{"rid":"98463983-69fd-43ed-a9e0-079bdf0b2d6a","rtype":"device_power"},{"rid":"6670b714-2891-4d1a-a412-50701ac59e97","rtype":"zigbee_connectivity"}],"type":"device"}],"id":"cdce9ef9-be54-4e3c-a100-701c61ce171b","type":"update"}
[6/18/2022, 1:57:06 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T11:57:06Z","data":[{"id":"48a56c79-98dd-4966-a731-c1c0834ddc9b","id_v1":"/sensors/118","metadata":{"control_id":1},"owner":{"rid":"6786f4ed-44a6-4c3a-9c2c-4e72fcc02e05","rtype":"device"},"type":"button"}],"id":"a16b1a13-c232-41d4-822b-aca8aa03648b","type":"update"}
[6/18/2022, 1:57:06 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T11:57:06Z","data":[{"id":"5c8be81d-876d-4c38-8aa1-ae0fcd7191cd","id_v1":"/sensors/118","metadata":{"control_id":2},"owner":{"rid":"6786f4ed-44a6-4c3a-9c2c-4e72fcc02e05","rtype":"device"},"type":"button"}],"id":"5b80a6e2-416c-49d1-9668-eaed01275c93","type":"update"}
[6/18/2022, 1:57:06 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T11:57:06Z","data":[{"id":"5ab72d6a-468c-4b12-9aa1-fe2ce161927b","id_v1":"/sensors/118","metadata":{"control_id":3},"owner":{"rid":"6786f4ed-44a6-4c3a-9c2c-4e72fcc02e05","rtype":"device"},"type":"button"}],"id":"749a2451-0df3-48e3-bca6-59701667a75c","type":"update"}
[6/18/2022, 1:57:06 PM] [Hue] Saugbert Schalter: state changed event: {"buttonevent":4002,"lastupdated":"2022-06-18T11:57:06"}
[6/18/2022, 1:57:06 PM] [Hue] Saugbert Schalter: sensor buttonevent 4002 on 2022-06-16T10:37:46
[6/18/2022, 1:57:06 PM] [Hue] Saugbert Schalter Off: homekit button single press
[6/18/2022, 1:57:06 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T11:57:06Z","data":[{"id":"98463983-69fd-43ed-a9e0-079bdf0b2d6a","id_v1":"/sensors/118","owner":{"rid":"6786f4ed-44a6-4c3a-9c2c-4e72fcc02e05","rtype":"device"},"power_state":{"battery_level":100,"battery_state":"normal"},"type":"device_power"}],"id":"670aa991-bacd-4510-8e94-a03d367fc142","type":"update"}
[6/18/2022, 1:57:06 PM] [Hue] Saugbert Schalter: config changed event: {"reachable":true}
[6/18/2022, 1:57:06 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T11:57:06Z","data":[{"children":[{"rid":"b38cfd4b-2d14-429e-9a1d-510077d0b0b4","rtype":"room"},{"rid":"04e7b513-4043-431a-8f5f-217a783b70e4","rtype":"room"},{"rid":"cbfa9e73-5159-4485-91c8-dfc9dbc1e574","rtype":"room"},{"rid":"5a98dcf0-bbfa-4a8d-a28d-a079ddfab6c0","rtype":"room"},{"rid":"7cdeaaeb-80dc-450f-bc4a-291b5e3cc768","rtype":"room"},{"rid":"ac2b114f-12ac-4cdf-bc4f-c715f254f26a","rtype":"room"},{"rid":"5ccdfde3-1897-4145-b902-4fadb6e09671","rtype":"room"},{"rid":"ec9803c4-7a23-4fcc-b648-ff7119cb162c","rtype":"device"},{"rid":"0ddad1c5-c02d-4ac4-86af-27304aeada28","rtype":"device"},{"rid":"0857bb27-4933-4578-98cc-d382ef3411d6","rtype":"device"},{"rid":"8edbea77-27b6-4e7e-b597-544f4cde0e9f","rtype":"device"},{"rid":"1c0a2b6a-ba4c-4934-92cd-3faddadf7013","rtype":"device"},{"rid":"0860d98f-b5cf-4145-b3b1-ffd48eb9a91c","rtype":"device"},{"rid":"e4bb083e-7be1-4cad-b8e1-599ff2ede0e0","rtype":"device"},{"rid":"89e334d0-103e-4ec1-8abf-38cf7ca9953d","rtype":"device"},{"rid":"94ca2bc6-e56b-4f43-8d90-a0077bec0ab4","rtype":"device"},{"rid":"6786f4ed-44a6-4c3a-9c2c-4e72fcc02e05","rtype":"device"}],"id":"6f4ed5da-3a7b-4ac0-9cf8-fe4f9dd99640","id_v1":"/groups/0","type":"bridge_home"}],"id":"ebac728a-8415-4019-ba00-407cd24f4a64","type":"update"}

And this dimmer switch is called "Alarmanlage Schalter":

Log Messages

6/18/2022, 2:05:49 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T12:05:49Z","data":[{"id":"0857bb27-4933-4578-98cc-d382ef3411d6","id_v1":"/sensors/119","metadata":{"archetype":"unknown_archetype","name":"Alarmanlage Schalter"},"product_data":{"certified":true,"hardware_platform_type":"100b-109","manufacturer_name":"Signify Netherlands B.V.","model_id":"RWL021","product_archetype":"unknown_archetype","product_name":"Hue dimmer switch","software_version":"1.1.28573"},"services":[{"rid":"fa35616a-a2fb-4d6f-96fb-9b4d98ba2591","rtype":"button"},{"rid":"559bcd83-159d-460a-a5c9-c744dffd917a","rtype":"button"},{"rid":"5ac7101f-c3fb-4d2c-93bc-81e0e6feb856","rtype":"button"},{"rid":"2e7fdf47-c453-476c-923e-355ffd143526","rtype":"button"},{"rid":"074dadff-e1d2-42af-98ac-e7311845cf2c","rtype":"device_power"},{"rid":"b2401399-6844-4e00-a5db-11b347f1a189","rtype":"zigbee_connectivity"}],"type":"device"}],"id":"482282a1-32b7-4428-9389-eac6176b70ac","type":"update"}
[6/18/2022, 2:05:49 PM] [Hue] Alarmanlage Schalter: state changed event: {"buttonevent":1002,"lastupdated":"2022-06-18T12:05:49"}
[6/18/2022, 2:05:49 PM] [Hue] Alarmanlage Schalter: sensor buttonevent 1002 on 2022-03-29T16:06:00
[6/18/2022, 2:05:49 PM] [Hue] Alarmanlage Schalter On: homekit button single press
[6/18/2022, 2:05:49 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T12:05:49Z","data":[{"id":"559bcd83-159d-460a-a5c9-c744dffd917a","id_v1":"/sensors/119","metadata":{"control_id":2},"owner":{"rid":"0857bb27-4933-4578-98cc-d382ef3411d6","rtype":"device"},"type":"button"}],"id":"f14b8e1a-8120-4f37-b4f7-569fdf497a8c","type":"update"}
[6/18/2022, 2:05:49 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T12:05:49Z","data":[{"id":"5ac7101f-c3fb-4d2c-93bc-81e0e6feb856","id_v1":"/sensors/119","metadata":{"control_id":3},"owner":{"rid":"0857bb27-4933-4578-98cc-d382ef3411d6","rtype":"device"},"type":"button"}],"id":"7c1c3f4c-b739-458d-9f14-1156ed1b9248","type":"update"}
[6/18/2022, 2:05:49 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T12:05:49Z","data":[{"id":"2e7fdf47-c453-476c-923e-355ffd143526","id_v1":"/sensors/119","metadata":{"control_id":4},"owner":{"rid":"0857bb27-4933-4578-98cc-d382ef3411d6","rtype":"device"},"type":"button"}],"id":"4c31540a-be77-4612-baa6-55ab263c76f2","type":"update"}
[6/18/2022, 2:05:49 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T12:05:49Z","data":[{"id":"074dadff-e1d2-42af-98ac-e7311845cf2c","id_v1":"/sensors/119","owner":{"rid":"0857bb27-4933-4578-98cc-d382ef3411d6","rtype":"device"},"power_state":{"battery_level":100,"battery_state":"normal"},"type":"device_power"}],"id":"fad51bc5-c74b-4ead-a191-d5613c186484","type":"update"}
[6/18/2022, 2:05:49 PM] [Hue] Alarmanlage Schalter: config changed event: {"reachable":true}
[6/18/2022, 2:05:49 PM] [Hue] Hue Bridge: event: {"creationtime":"2022-06-18T12:05:49Z","data":[{"children":[{"rid":"b38cfd4b-2d14-429e-9a1d-510077d0b0b4","rtype":"room"},{"rid":"04e7b513-4043-431a-8f5f-217a783b70e4","rtype":"room"},{"rid":"cbfa9e73-5159-4485-91c8-dfc9dbc1e574","rtype":"room"},{"rid":"5a98dcf0-bbfa-4a8d-a28d-a079ddfab6c0","rtype":"room"},{"rid":"7cdeaaeb-80dc-450f-bc4a-291b5e3cc768","rtype":"room"},{"rid":"ac2b114f-12ac-4cdf-bc4f-c715f254f26a","rtype":"room"},{"rid":"5ccdfde3-1897-4145-b902-4fadb6e09671","rtype":"room"},{"rid":"8edbea77-27b6-4e7e-b597-544f4cde0e9f","rtype":"device"},{"rid":"0857bb27-4933-4578-98cc-d382ef3411d6","rtype":"device"},{"rid":"1c0a2b6a-ba4c-4934-92cd-3faddadf7013","rtype":"device"},{"rid":"0860d98f-b5cf-4145-b3b1-ffd48eb9a91c","rtype":"device"},{"rid":"e4bb083e-7be1-4cad-b8e1-599ff2ede0e0","rtype":"device"},{"rid":"89e334d0-103e-4ec1-8abf-38cf7ca9953d","rtype":"device"},{"rid":"94ca2bc6-e56b-4f43-8d90-a0077bec0ab4","rtype":"device"},{"rid":"6786f4ed-44a6-4c3a-9c2c-4e72fcc02e05","rtype":"device"},{"rid":"0ddad1c5-c02d-4ac4-86af-27304aeada28","rtype":"device"},{"rid":"ec9803c4-7a23-4fcc-b648-ff7119cb162c","rtype":"device"}],"id":"6f4ed5da-3a7b-4ac0-9cf8-fe4f9dd99640","id_v1":"/groups/0","type":"bridge_home"}],"id":"a98c45ee-9049-484f-8465-357d52656097","type":"update"}

[ebaauw]

[6/18/2022, 1:57:06 PM] [Hue] Saugbert Schalter: state changed event: {"buttonevent":4002,"lastupdated":"2022-06-18T11:57:06"}

The Hue bridge sends on the event stream an event, which Homebridge Hue cannot distinguish from a real button press.
I would theorise that 4002 was the last real button event before the software update? And there's no state changed event for a buttonevent 4000 before that?

What does the log look like, after the first successful GET /sensors since the reboot? I would suspect that reports buttonevent 4002 and lastupdated from the last actual button action, which matches the cached values by Homebridge Hue. What does the API report for state.lastupdated? Probably still the old value for lastupdated? The event doesn't actually contain lastupdated; Homebridge Hue fills this in from the creation time of the event.

Do you also see state change events for other sensor types?

Note that there's over 8.5 minutes between the two button events. I really don't understand what triggers the Hue bridge to send these events. Normally the Hue bridge configures the Hue dimmer switch to wakeup every 5 minutes to report its battery state. Did the bridge reboot in between these events?

This looks to be a weird bug in the Hue bridge. I'm not sure I could even create a viable workaround (like trying to recognise a firmware update and ignoring the first state event after that, or simply ignoring any x002 or x003 event without a previous x000 or x001). They would probably do more harm than good. I will definitely not try before Homebridge Hue2.

[fanfadrian]

[6/18/2022, 1:57:06 PM] [Hue] Saugbert Schalter: state changed event: {"buttonevent":4002,"lastupdated":"2022-06-18T11:57:06"}

The Hue bridge sends on the event stream an event, which Homebridge Hue cannot distinguish from a real button press. I would theorise that 4002 was the last real button event before the software update? And there's no state changed event for a buttonevent 4000 before that?

These are all buttonevents in the log file of homebridge (which unfortunately only reaches back a couple of hours):

Log Messages

~/Downloads/homebridge-alarm.log:23322: [6/18/2022, 1:24:55 PM] [Hue] Büro Schalter: state changed event: {"buttonevent":1000,"lastupdated":"2022-06-18T11:24:55"}
~/Downloads/homebridge-alarm.log:23323: [6/18/2022, 1:24:55 PM] [Hue] Büro Schalter: sensor buttonevent 1000 on 2022-06-18T00:30:28
~/Downloads/homebridge-alarm.log:23327: [6/18/2022, 1:24:56 PM] [Hue] Büro Schalter: state changed event: {"buttonevent":1002,"lastupdated":"2022-06-18T11:24:55"}
~/Downloads/homebridge-alarm.log:23328: [6/18/2022, 1:24:56 PM] [Hue] Büro Schalter: sensor buttonevent 1002 on 2022-06-18T11:24:55
~/Downloads/homebridge-alarm.log:23341: [6/18/2022, 1:24:56 PM] [Hue] Büro Schalter: state changed event: {"buttonevent":4002,"lastupdated":"2022-06-18T11:24:56"}
~/Downloads/homebridge-alarm.log:23342: [6/18/2022, 1:24:56 PM] [Hue] Büro Schalter: sensor buttonevent 4002 on 2022-06-18T11:24:55
~/Downloads/homebridge-alarm.log:30716: [6/18/2022, 1:50:57 PM] [Hue] Büro Schalter: state changed event: {"buttonevent":1000,"lastupdated":"2022-06-18T11:50:57"}
~/Downloads/homebridge-alarm.log:30717: [6/18/2022, 1:50:57 PM] [Hue] Büro Schalter: sensor buttonevent 1000 on 2022-06-18T11:24:56
~/Downloads/homebridge-alarm.log:30726: [6/18/2022, 1:50:58 PM] [Hue] Büro Schalter: state changed event: {"buttonevent":1002,"lastupdated":"2022-06-18T11:50:57"}
~/Downloads/homebridge-alarm.log:30727: [6/18/2022, 1:50:58 PM] [Hue] Büro Schalter: sensor buttonevent 1002 on 2022-06-18T11:50:57
~/Downloads/homebridge-alarm.log:32052: [6/18/2022, 1:55:48 PM] [Hue] Büro Schalter: state changed event: {"buttonevent":4002,"lastupdated":"2022-06-18T11:55:48"}
~/Downloads/homebridge-alarm.log:32053: [6/18/2022, 1:55:48 PM] [Hue] Büro Schalter: sensor buttonevent 4002 on 2022-06-18T11:50:57
~/Downloads/homebridge-alarm.log:32440: [6/18/2022, 1:57:06 PM] [Hue] Saugbert Schalter: state changed event: {"buttonevent":4002,"lastupdated":"2022-06-18T11:57:06"}
~/Downloads/homebridge-alarm.log:32441: [6/18/2022, 1:57:06 PM] [Hue] Saugbert Schalter: sensor buttonevent 4002 on 2022-06-16T10:37:46
~/Downloads/homebridge-alarm.log:33133: [6/18/2022, 1:59:30 PM] [Hue] Schlafzimmer Schalter: state changed event: {"buttonevent":1000,"lastupdated":"2022-06-18T11:59:30"}
~/Downloads/homebridge-alarm.log:33134: [6/18/2022, 1:59:30 PM] [Hue] Schlafzimmer Schalter: sensor buttonevent 1000 on 2022-06-18T04:42:48
~/Downloads/homebridge-alarm.log:33151: [6/18/2022, 1:59:31 PM] [Hue] Schlafzimmer Schalter: state changed event: {"buttonevent":1002,"lastupdated":"2022-06-18T11:59:30"}
~/Downloads/homebridge-alarm.log:33152: [6/18/2022, 1:59:31 PM] [Hue] Schlafzimmer Schalter: sensor buttonevent 1002 on 2022-06-18T11:59:30
~/Downloads/homebridge-alarm.log:33243: [6/18/2022, 1:59:45 PM] [Hue] Schlafzimmer Schalter: state changed event: {"buttonevent":1002,"lastupdated":"2022-06-18T11:59:45"}
~/Downloads/homebridge-alarm.log:33244: [6/18/2022, 1:59:45 PM] [Hue] Schlafzimmer Schalter: sensor buttonevent 1002 on 2022-06-18T11:59:30
~/Downloads/homebridge-alarm.log:34275: [6/18/2022, 2:03:32 PM] [Hue] Wohnzimmer Schalter: state changed event: {"buttonevent":4002,"lastupdated":"2022-06-18T12:03:32"}
~/Downloads/homebridge-alarm.log:34276: [6/18/2022, 2:03:32 PM] [Hue] Wohnzimmer Schalter: sensor buttonevent 4002 on 2022-06-18T00:14:22
~/Downloads/homebridge-alarm.log:34856: [6/18/2022, 2:05:49 PM] [Hue] Alarmanlage Schalter: state changed event: {"buttonevent":1002,"lastupdated":"2022-06-18T12:05:49"}
~/Downloads/homebridge-alarm.log:34857: [6/18/2022, 2:05:49 PM] [Hue] Alarmanlage Schalter: sensor buttonevent 1002 on 2022-03-29T16:06:00

I attached the whole log file. I'm not sure I can completely follow you on your next thoughts. (Where can I read out the API? Via the HUE hub ip address?)

What does the log look like, after the first successful GET /sensors since the reboot? I would suspect that reports buttonevent 4002 and lastupdated from the last actual button action, which matches the cached values by Homebridge Hue. What does the API report for state.lastupdated? Probably still the old value for lastupdated? The event doesn't actually contain lastupdated; Homebridge Hue fills this in from the creation time of the event.

Do you also see state change events for other sensor types?

Note that there's over 8.5 minutes between the two button events. I really don't understand what triggers the Hue bridge to send these events. Normally the Hue bridge configures the Hue dimmer switch to wakeup every 5 minutes to report its battery state. Did the bridge reboot in between these events?

This looks to be a weird bug in the Hue bridge. I'm not sure I could even create a viable workaround (like trying to recognise a firmware update and ignoring the first state event after that, or simply ignoring any x002 or x003 event without a previous x000 or x001). They would probably do more harm than good. I will definitely not try before Homebridge Hue2.
homebridge-alarm.log

[ebaauw]

Where can I read out the API? Via the HUE hub ip address?

ph -H x.x.x.x get /sensors/##/state, substituting the IP address of the Hue bridge and the resource ID of the Hue dimmer switch. You might need to create an API key; ph will mention that.

[fanfadrian]

I tried it for two of the affected dimmer switches. Since state returns "null" and "none", I just tried with get /sensors/

get /sensors/118/state

ph.js -H 192.168.178.31 get /sensors/118/state
{
  "buttonevent": null,
  "lastupdated": "none"
}

get /sensors/119/state

ph.js -H 192.168.178.31 get /sensors/119/state
{
  "buttonevent": null,
  "lastupdated": "none"
}

get /sensors/

"118": {
    "state": {
      "buttonevent": null,
      "lastupdated": "none"
    },
    "swupdate": {
      "state": "noupdates",
      "lastinstall": "2020-11-29T22:42:08"
    },
    "config": {
      "on": true,
      "battery": 100,
      "reachable": true,
      "pending": []
    },
    "name": "Saugbert Schalter",
    "type": "ZLLSwitch",
    "modelid": "RWL021",
    "manufacturername": "Signify Netherlands B.V.",
    "productname": "Hue dimmer switch",
    "diversityid": "73bbabea-3420-499a-9856-46bf437e119b",
    "swversion": "6.1.1.28573",
    "uniqueid": "00:17:88:01:06:b7:38:fa-02-fc00",
    "capabilities": {
      "certified": true,
      "primary": true,
      "inputs": [
        {
          "repeatintervals": [
            800
          ],
          "events": [
            {
              "buttonevent": 1000,
              "eventtype": "initial_press"
            },
            {
              "buttonevent": 1001,
              "eventtype": "repeat"
            },
            {
              "buttonevent": 1002,
              "eventtype": "short_release"
            },
            {
              "buttonevent": 1003,
              "eventtype": "long_release"
            },
            {
              "buttonevent": 1004,
              "eventtype": "long_press"
            }
          ]
        },
        {
          "repeatintervals": [
            800
          ],
          "events": [
            {
              "buttonevent": 2000,
              "eventtype": "initial_press"
            },
            {
              "buttonevent": 2001,
              "eventtype": "repeat"
            },
            {
              "buttonevent": 2002,
              "eventtype": "short_release"
            },
            {
              "buttonevent": 2003,
              "eventtype": "long_release"
            },
            {
              "buttonevent": 2004,
              "eventtype": "long_press"
            }
          ]
        },
        {
          "repeatintervals": [
            800
          ],
          "events": [
            {
              "buttonevent": 3000,
              "eventtype": "initial_press"
            },
            {
              "buttonevent": 3001,
              "eventtype": "repeat"
            },
            {
              "buttonevent": 3002,
              "eventtype": "short_release"
            },
            {
              "buttonevent": 3003,
              "eventtype": "long_release"
            },
            {
              "buttonevent": 3004,
              "eventtype": "long_press"
            }
          ]
        },
        {
          "repeatintervals": [
            800
          ],
          "events": [
            {
              "buttonevent": 4000,
              "eventtype": "initial_press"
            },
            {
              "buttonevent": 4001,
              "eventtype": "repeat"
            },
            {
              "buttonevent": 4002,
              "eventtype": "short_release"
            },
            {
              "buttonevent": 4003,
              "eventtype": "long_release"
            },
            {
              "buttonevent": 4004,
              "eventtype": "long_press"
            }
          ]
        }
      ]
    }
  },
  "119": {
    "state": {
      "buttonevent": null,
      "lastupdated": "none"
    },
    "swupdate": {
      "state": "noupdates",
      "lastinstall": "2020-12-01T03:44:56"
    },
    "config": {
      "on": true,
      "battery": 100,
      "reachable": true,
      "pending": []
    },
    "name": "Alarmanlage Schalter",
    "type": "ZLLSwitch",
    "modelid": "RWL021",
    "manufacturername": "Signify Netherlands B.V.",
    "productname": "Hue dimmer switch",
    "diversityid": "73bbabea-3420-499a-9856-46bf437e119b",
    "swversion": "6.1.1.28573",
    "uniqueid": "00:17:88:01:06:b7:38:49-02-fc00",
    "capabilities": {
      "certified": true,
      "primary": true,
      "inputs": [
        {
          "repeatintervals": [
            800
          ],
          "events": [
            {
              "buttonevent": 1000,
              "eventtype": "initial_press"
            },
            {
              "buttonevent": 1001,
              "eventtype": "repeat"
            },
            {
              "buttonevent": 1002,
              "eventtype": "short_release"
            },
            {
              "buttonevent": 1003,
              "eventtype": "long_release"
            },
            {
              "buttonevent": 1004,
              "eventtype": "long_press"
            }
          ]
        },
        {
          "repeatintervals": [
            800
          ],
          "events": [
            {
              "buttonevent": 2000,
              "eventtype": "initial_press"
            },
            {
              "buttonevent": 2001,
              "eventtype": "repeat"
            },
            {
              "buttonevent": 2002,
              "eventtype": "short_release"
            },
            {
              "buttonevent": 2003,
              "eventtype": "long_release"
            },
            {
              "buttonevent": 2004,
              "eventtype": "long_press"
            }
          ]
        },
        {
          "repeatintervals": [
            800
          ],
          "events": [
            {
              "buttonevent": 3000,
              "eventtype": "initial_press"
            },
            {
              "buttonevent": 3001,
              "eventtype": "repeat"
            },
            {
              "buttonevent": 3002,
              "eventtype": "short_release"
            },
            {
              "buttonevent": 3003,
              "eventtype": "long_release"
            },
            {
              "buttonevent": 3004,
              "eventtype": "long_press"
            }
          ]
        },
        {
          "repeatintervals": [
            800
          ],
          "events": [
            {
              "buttonevent": 4000,
              "eventtype": "initial_press"
            },
            {
              "buttonevent": 4001,
              "eventtype": "repeat"
            },
            {
              "buttonevent": 4002,
              "eventtype": "short_release"
            },
            {
              "buttonevent": 4003,
              "eventtype": "long_release"
            },
            {
              "buttonevent": 4004,
              "eventtype": "long_press"
            }
          ]
        }
      ]
    }
  },

[ebaauw]

Weird. I take it, the state is populated after you press a button for real? I'll try and pair an old Hue dimmer to my Hue bridge, but i'll probably only get to it, when I'll start working on Homebridge Hue2.