Error Dispatching event to subscriber takes more than 5000ms

Setup, Configuration and Use Runtime
1

I’m seeing this error on a regular basis:
“Dispatching event to subscriber ‘org.eclipse.smarthome.io.monitor.internal.EventLogger@14e76dd’ takes more than 5000ms.”

I recently moved my setup to a new Raspberry PI 4 with a new install of Raspbian 1.6 (OH v2.5.10).

  • Platform information:
    • Hardware: Raspberry pi 4 (2GB) w/60 GB SSD
    • OS:Raspbian 1.6
    • openHAB version: 2.5.10

The server runs fine for a couple of days and then I see a bunch of these errors:

2020-12-19 15:06:28.144 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber ‘org.eclipse.smarthome.io.monitor.internal.EventLogger@14e76dd’ takes more than 5000ms.

2020-12-19 15:07:49.755 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber ‘org.eclipse.smarthome.io.monitor.internal.EventLogger@14e76dd’ takes more than 5000ms.

2020-12-19 15:09:19.124 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber ‘org.eclipse.smarthome.io.monitor.internal.EventLogger@14e76dd’ takes more than 5000ms.

2020-12-19 15:10:09.454 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber ‘org.eclipse.smarthome.core.thing.internal.CommunicationManager@1a271ed’ takes more than 5000ms.

2020-12-19 15:10:55.454 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber ‘org.eclipse.smarthome.io.monitor.internal.EventLogger@14e76dd’ takes more than 5000ms.

2020-12-19 15:12:53.374 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber ‘org.eclipse.smarthome.io.monitor.internal.EventLogger@14e76dd’ takes more than 5000ms.

2020-12-19 15:13:54.452 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber ‘org.openhab.core.events.internal.EventBridge@64b198’ takes more than 5000ms.

When I restart OpenHAB the error goes away, only to come back a couple of days later.

Any ideas and suggestions are very welcomed!

2

Hi,

I can see this under OH3 too.

2021-01-06 11:03:27.161 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber ‘org.openhab.core.internal.items.ItemUpdater@dfdac9’ takes more than 5000ms.

Platform information:
    Hardware: Raspberry pi 3 (2GB) w/60 GB SSD
    OS:Raspbian 1.6.2
    openHAB version: 3.0.0

best regards,
Lars

3

You get this error when an event processing takes too long and there is a risk of delaying processing of subsequent events. Given that you have multiple event handlers affected I suggest to first look into CPU usage.

4

God point. I’m running openhabian 1.6 on a RPI 4 with 2GB. Normally the CPU using is between 1 and 6%

What is the best way to track CPU usage continuously?
Thanks!

5

There is system info binding, making OH kind of ‘self monitoring’.

Depending on your abilities you can also try tool called telegraf combined with influxdb (aside of OH/rpi unless you have attached hard drive). Then it is an independent agent which works outside of openhab.

6

Duh… I’m already using the systembinding. I’ll check my logs.
Thanks!
Bjarne

7

General comment - the thing that is complaining is the thing that has been held up, and not the actual logjam. It’s not easy to pinpoint, consider network delays (e.g. HTTP hold ups), i/o delays (e.g. wonky SD card) etc.

1 Like