Handler disposed?

Setup, Configuration and Use Runtime
1

on a recent snapshot I get tons of …
whats it about?

2018-08-10 16:32:23.175 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:23.405 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:25.956 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:26.303 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:33.200 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:33.436 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:35.973 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:36.323 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:43.221 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:43.455 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:46.170 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:46.477 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:50.191 [WARN ] [he.commons.httpclient.HttpMethodBase] - Stream closed
2018-08-10 16:32:53.240 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:54.028 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:56.199 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:32:56.522 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:03.336 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:04.054 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:06.244 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:06.550 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:13.360 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:14.073 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:16.269 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:16.610 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:23.384 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:23.537 [WARN ] [he.commons.httpclient.HttpMethodBase] - Stream closed
2018-08-10 16:33:24.097 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:26.289 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:26.639 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:33.402 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:34.121 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:36.310 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:36.720 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:43.425 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-08-10 16:33:44.158 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2

Can you figure out from which binding that might derive?

3

How about chromecast? :wink:

It looks like the chromecast handler leakes some scheduled job/callback/whatever which still keeps running after the handler got disposed. Nevertheless, I can’t locate it in the chromecast binding code on the first glance… Might be worth filing a bug in https://github.com/openhab/openhab2-addons/issues?utf8=✓&q=is%3Aissue+is%3Aopen+chromecast in order to further analyze it.

4

Stupid me - I should have scrolled the logs slightly to the right :slight_smile:

5

FWIW, I saw this behavior with the Unifi binding. My post about it is here.

In my case, it occurred at startup, and appeared to be related to the binding being started/stopped/started in rapid succession.

Edit: I added some additional information here.

6

I saw it additionally for the broadlink binding

1 Like
7

@shorty707 I’ve created some fixes for this in:

8

I see this on different bindings (Neato, Netatmo, VolvoOnCall).
Yesterday it popped up after restarting the core by karaf (Eclipse SmartHome Core).

2018-09-29 06:53:13.290 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler NetatmoBridgeHandler tried updating the thing status although the handler was already disposed.
2018-09-29 06:53:13.296 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler NAMainHandler tried updating the thing status although the handler was already disposed.
2018-09-29 07:34:59.774 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler VehicleHandler tried updating the thing status although the handler was already disposed.

So it’s more like a general issue (or my system!?)

9

I also have this on VolvoOnCall.

/Mike

10

I am having this issue right now on BroadlinkSocketModel2Handler with the latest 2.4.0-snapshot version. Is there a workaround for this?

11

Same Thing with Synology Surveillance Station Binding.
I restarted the binding, because it did not update the Motion items.
Afterwards the log is flooded with:

2018-10-25 07:41:26.395 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler SynoBridgeHandler of thing synologysurveillancestation:station:1470lsn887804 tried checking if channel homemode is linked although the handler was already disposed.
2018-10-25 07:41:29.395 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler SynoBridgeHandler of thing synologysurveillancestation:station:1470lsn887804 tried checking if channel homemode is linked although the handler was already disposed.
2018-10-25 07:41:32.394 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler SynoBridgeHandler of thing synologysurveillancestation:station:1470lsn887804 tried checking if channel homemode is linked although the handler was already disposed.
2018-10-25 07:41:35.661 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler SynoBridgeHandler of thing synologysurveillancestation:station:1470lsn887804 tried checking if channel homemode is linked although the handler was already disposed.
2018-10-25 07:41:43.043 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler SynoBridgeHandler of thing synologysurveillancestation:station:1470lsn887804 tried checking if channel homemode is linked although the handler was already disposed.
12

Hi,

I suddenly get this message too - but not using unstable but 2.3.0 Release Build.

Log is flooded with this message:

2018-11-23 13:46:36.858 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:46:36.860 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:46:36.949 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:46:36.976 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:46:37.236 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:10.295 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:33.913 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:34.457 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:35.308 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:35.857 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:36.700 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:36.860 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:36.862 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:36.952 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:36.978 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.
2018-11-23 13:47:37.238 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler LaMetricTimeHandler tried updating the thing status although the handler was already disposed.

This even happens when the binding is disabled via bundle:stop…?

Would be happy with any suggestions.

Cheers,
Marianne

13

All those messages are no “general framework issue”, but individual bugs in the different bindings - you should best file a bug per binding, where you experience those warnings, so that those bindings can be fixed similar to Chromecast above.

14

Thanks Kai,
I will do my best.
Currently I try to figure out if it’s the Synology Surveillance Station Binding or the Amazon Echo Control Binding.

This logfile entry does not help (whats is the item @1202306 is referring to):

2018-10-26 07:38:30.649 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.internal.items.ItemUpdater@1202306' takes more than 5000ms.

These entries actually will usually be followed by OOM error (Java heap space) and I am running now without both bindings to make sure that nothng else causes these issues above…

15

I also have this on the Wemo Binding (Openhab 2.4 under docker)

Mark

16

I have this on openhab 2.4 as well with the chromecast binding. It happens every 13 seconds.

2019-01-12 13:45:05.195 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

2019-01-12 13:45:18.315 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

2019-01-12 13:45:31.434 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

2019-01-12 13:45:44.554 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

2019-01-12 13:45:57.674 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

2019-01-12 13:46:10.794 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

2019-01-12 13:46:23.915 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

2019-01-12 13:46:37.035 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

2019-01-12 13:46:50.154 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
17

Any news on this issue? Started here too:
Between 3-10s:

2019-02-20 12:55:22.302 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:55:25.374 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:55:35.358 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:55:38.430 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:55:48.414 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:55:51.486 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:01.470 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:04.542 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:14.526 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:17.598 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:27.582 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:30.655 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:40.638 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:43.710 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:53.694 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2019-02-20 12:56:56.766 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

18

Hello Mr. Wounter,

Sorry my ignorance, but I didn’t find the way to solve it, may you help me? I’m running OH 2.5.2

19

Solved for me by cleaning the OH cache: [SOLVED] Innogy binding RST - "handler was already disposed."

Have you already tried this?

2 Likes
20

Great first post Merlin and welcome to the OpenHAB community!