PatchworkBoy / homebridge-edomoticz

Domoticz Homebridge-Plugin
Other
118 stars 44 forks source link

[homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' didn't respond at all! See https://git.io/JtMGR for more info. #237

Open rjblake opened 2 years ago

rjblake commented 2 years ago

Running Homebridge v1.3.4 Release and started noticing a lot of these messages, which seem in turn to impact on the performance of switch devices on/off through Home app. They are either 'didn't respond at all, or 'was slow to respond'. This is all running on an 8GB Raspberry Pi 4, which is showing and average of around 6% load

Note that the only devices that seem to generate this message are from a number of tempErature/humidity devies in Domoticz. I have 8 of these sensors around the house, which update Domoticz using MQTT & NodeRed. The full list of messages per device is as follows:

[7/12/2021, 4:42:47 PM] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[7/12/2021, 4:42:47 PM] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Relative Humidity' was slow to respond! See https://git.io/JtMGR for more info.
[7/12/2021, 4:42:47 PM] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Low Battery' was slow to respond! See https://git.io/JtMGR for more info.
[7/12/2021, 4:42:47 PM] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' was slow to respond! See https://git.io/JtMGR for more info.

[7/12/2021, 4:42:53 PM] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[7/12/2021, 4:42:53 PM] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Relative Humidity' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[7/12/2021, 4:42:53 PM] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Low Battery' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[7/12/2021, 4:42:53 PM] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.

Anyone seen the same or got some thoughts?

PatchworkBoy commented 2 years ago

I suspect this is something Domoticz is doing, or Raspbian... I get the same, in batches consistently an hour apart usually (within a few seconds). Will see what I can find... note, it's not to do with sensor type as it does it with Color Temperature. I suspect it reports this for everything that isn't a switch, and switches just timeout / don't respond until Domoticz / MQTT starts responding again. I don't think the error is impacting the performance of switch devices... I suspect the two issues have the same cause - domoticz or mqtt not replying / accepting requests.

[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Color Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Target Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Color Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Color Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Motion Detected' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Target Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Relative Humidity' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Pressure' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Relative Humidity' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Wind Speed' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Wind Chill' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Wind Direction' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:00:25] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' didn't respond at all!. Please check that you properly call the callback! See https://git.io/JtMGR for more info.
[12/07/2021, 16:08:51] [eDomoticz] synchronizeAccessories in progress...
[12/07/2021, 16:13:54] [Alexa] WARNING: Duplicate device name Weather
[12/07/2021, 16:18:51] [eDomoticz] synchronizeAccessories in progress...
[12/07/2021, 16:28:51] [eDomoticz] synchronizeAccessories in progress...
[12/07/2021, 16:28:54] [Alexa] WARNING: Duplicate device name Weather
[12/07/2021, 16:38:51] [eDomoticz] synchronizeAccessories in progress...
[12/07/2021, 16:43:54] [Alexa] WARNING: Duplicate device name Weather
[12/07/2021, 16:48:51] [eDomoticz] synchronizeAccessories in progress...
[12/07/2021, 16:58:51] [eDomoticz] synchronizeAccessories in progress...
[12/07/2021, 16:58:54] [Alexa] WARNING: Duplicate device name Weather
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Color Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Target Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Color Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Color Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Motion Detected' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Target Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Relative Humidity' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Pressure' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Relative Humidity' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Status Fault' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Wind Speed' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Wind Chill' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Wind Direction' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:00:29] [homebridge-edomoticz] This plugin slows down Homebridge. The read handler for the characteristic 'Current Temperature' was slow to respond! See https://git.io/JtMGR for more info.
[12/07/2021, 17:08:51] [eDomoticz] synchronizeAccessories in progress...
[12/07/2021, 17:13:54] [Alexa] WARNING: Duplicate device name Weather
[12/07/2021, 17:18:51] [eDomoticz] synchronizeAccessories in progress...
PatchworkBoy commented 2 years ago

Hmmm... whenever it happens on mine it co-incides with:

2021-07-12 20:00:01.139 Status: Starting automatic database backup procedure...
2021-07-12 20:00:29.157 Status: Ending automatic database backup procedure...

(from Domoticz log)

PatchworkBoy commented 2 years ago

Have disabled automatic backup in Domoticz, cleared my logs and will keep an eye out.

rjblake commented 2 years ago

I removed all the Temp/Humidity sensors from the Roomplan in Domoticz so they are not showing up in Apple Homekit. The messages have not been seen for past 12 hours so far. I don't have automatic backup turned on. I'm getting value from the devices from MQTT, which in turn updates the devices in Domoticz. I also have a device updating an Influx DB every 5 minutes. Perhaps it is a conflict with Domiticz writes and reads. Will try running some continuous scripts to see if I notice any issue with this.

PatchworkBoy commented 2 years ago

I haven't had the error since turning off auto-backup in Domoticz at my end - that's all I've changed. The read handler it's talking about is a http get request and Domoticz isn't replying.

MQTT doesn't request a 'read' as such. It's told... data is pushed to eDomoticz via MQTT so wouldn't trigger an error of this kind.

rjblake commented 2 years ago

On MQTT, yes - was clear in my wording. Meant that there was possibly some writing activity update sensors in Domoticz as result of the MQTT/NodeRed updates. After some more digging through logs, the only thing I can find is perhaps related to InfluxDB. Seems that at/around the same time this error pops up, I have an influx Error:

Jul 14 20:05:10 raspberrypi influxd[724]: ts=2021-07-14T18:05:10.354265Z lvl=info msg="failed to store statistics" log_id=0VDHRfAG000 service=monitor error=timeout

Could be related to (at least in my case) a similar issues posted here

PatchworkBoy commented 2 years ago

Proper solution to this is tied to https://github.com/PatchworkBoy/homebridge-edomoticz/issues/197

Need to replace request.js with something more modern that handles async / promise requests...