Shelly Binding

Garbisa · January 10, 2023, 8:59am

As I understand, it is not possible to request data from this device. It will send the data only when temperature or humidity changed the configured delta. In my case 0.5°C for temperature and 1% for humidity.

Ranzkarl · January 13, 2023, 3:17pm

Hello Openhab community, I’m using openhab since about 2 years and was always able to solve issues on my own or with the already existing explanations. This time I’m desperate enough to create an account and start a thread here as it appears no one had the same issue before - so let me explain:
I’m using Openhab with a broad variety of protocols and devices - where also Shellys are in use. Since upgrading to Openhab 2.4 around 2 weeks ago my Shellys are working for about 5 minutes after the restart of Openhab afterwards they’re “read only” - meaning status changes on the Shelly webinterface are reflected within the Openhab points, but actions performed on the Openhab points are not “sent” to the Shellys. It works fine for a short period of time after the reboot of the system and suddenly stops working without throwing any error messages - even the debug log is empty. Up until now I was not able to find a solution for this - I tried: deleting the things, uninstalling the binding, recreating the things and channel links. Restarting the Shellys itself - nothing worked. Does anyone have any idea?
Thanks,
Marc

hmerk · January 13, 2023, 3:31pm

Really? Then I would strongly recommend to upgrade to 3.4.1

All I can tell for now is that all my Shelly devices (43) are working as expected

Please set the Shelly binding to TRACE logging and post the output after openHAB start or binding restart.

Ranzkarl · January 13, 2023, 3:36pm

Thanks for the feedback, It was a typo, I meant 3.4 - not 2.4 and also performed the upgrade to 3.4.1 about two hours ago - without any noticable improvement. I will post the trace log here, but to be fair it works for a limited amount of time without any issues - so the initial binding and openhab start is without any error messages…

hmerk · January 13, 2023, 4:24pm

In that case, please post the log entries from around the time when the issues occur.

Ranzkarl · January 13, 2023, 4:32pm

it worked for about 35 minutes, and it’s back now. Restarting the binding only results in the following TRACE output:

openhab>
bundle:restart org.openhab.binding.shelly
openhab> log:tail
17:19:32.870 [DEBUG] [internal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(371)] : Set implementation object for component
17:19:32.871 [DEBUG] [internal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(371)] : Changed state from satisfied to active
17:19:32.873 [INFO ] [rd.internal.element.ServletWebElement] - Ignoring servlet [637] without valid alias or url patterns property.
17:19:32.874 [DEBUG] [internal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(371)] : dm $005 tracking 6 SingleStatic added {org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider}={service.id=632, service.bundleid=281, service.scope=bundle, component.name=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider, component.id=374} (exit)
17:19:32.875 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : dm $000 tracking 1 SingleStatic added {org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider}={service.id=632, service.bundleid=281, service.scope=bundle, component.name=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider, component.id=374} (enter)
17:19:32.876 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : ActivateInternal
17:19:32.877 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : Querying state unsatisfiedReference
17:19:32.878 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : Querying state unsatisfiedReference
17:19:32.879 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : Activating component from state unsatisfiedReference
17:19:32.880 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : Querying state unsatisfiedReference
17:19:32.881 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : Querying state unsatisfiedReference
17:19:32.882 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : Changed state from unsatisfiedReference to satisfied
17:19:32.883 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : registration change queue [registered]
17:19:32.886 [DEBUG] [nal.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:3.4.1 (281)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(372)] : dm $000 tracking 1 SingleStatic added {org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider}={service.id=632, service.bundleid=281, service.scope=bundle, component.name=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider, component.id=374} (exit)
17:19:33.069 [DEBUG] [.shelly.internal.ShellyHandlerFactory] - Licht Mauer - X.X.X.203: Create new thing of type shelly:shellydimmer2 using ShellyRelayHandler
17:19:33.091 [DEBUG] [.shelly.internal.ShellyHandlerFactory] - Thing handler for uid shelly:shellydimmer2:e8db84d3c8e4 added, total things = 1
17:19:33.102 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'shelly:shellydimmer2:e8db84d3c8e4' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
17:19:33.118 [DEBUG] [.shelly.internal.ShellyHandlerFactory] - Licht Küchenschrank - X.X.X.201: Create new thing of type shelly:shellyrgbw2-white using ShellyLightHandler
17:19:33.140 [DEBUG] [.shelly.internal.ShellyHandlerFactory] - Thing handler for uid shelly:shellyrgbw2-white:00bccf added, total things = 2
17:19:33.152 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'shelly:shellyrgbw2-white:00bccf' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
17:19:33.157 [DEBUG] [y.internal.handler.ShellyLightHandler] - Thing is using  class org.openhab.binding.shelly.internal.handler.ShellyLightHandler
17:19:33.168 [DEBUG] [.shelly.internal.ShellyHandlerFactory] - Licht Küche Regal Fenster - X.X.X.204: Create new thing of type shelly:shellyrgbw2-white using ShellyLightHandler
17:19:33.193 [DEBUG] [.shelly.internal.ShellyHandlerFactory] - Thing handler for uid shelly:shellyrgbw2-white:D9625E added, total things = 3
17:19:33.205 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'shelly:shellyrgbw2-white:D9625E' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
17:19:33.209 [DEBUG] [y.internal.handler.ShellyLightHandler] - Thing is using  class org.openhab.binding.shelly.internal.handler.ShellyLightHandler
17:19:33.952 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Message from /X.X.X.203:5683 (MID=4982): {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.91],[0,4103,145],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.58],[0,3105,87.04],[0,6101,0],[0,9101,"white"]]}
17:19:33.953 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
17:19:33.954 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellydimmer2-e8db84d3c8e4: Watchdog restarted (expires in 70 sec)
17:19:33.955 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.91],[0,4103,145],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.58],[0,3105,87.04],[0,6101,0],[0,9101,"white"]]} (serial=1536)
17:19:33.956 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: 18 CoAP sensor updates received
17:19:33.956 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[0]: id=9103, Value=0.0 (cfgChanged, Type=EVC, Range=U16, Link=4: device)
17:19:33.957 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[1]: id=1101, Value=1.0 (output, Type=S, Range=0/1, Link=1: light_0)
17:19:33.958 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[2]: id=5101, Value=14.0 (brightness, Type=S, Range=1/100, Link=1: light_0)
17:19:33.959 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[3]: id=2101, Value=0.0 (input, Type=S, Range=0/1, Link=2: input_0)
17:19:33.959 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[4]: id=2102, Value=-1.0 (inputEvent, Type=EV, Range=S/L;, Link=2: input_0)
17:19:33.960 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[5]: id=2103, Value=0.0 (inputEventCnt, Type=EVC, Range=U16, Link=2: input_0)
17:19:33.961 [TRACE] [ly.internal.api1.Shelly1CoIoTProtocol] - shellydimmer2-e8db84d3c8e4: Check button[0] for event trigger (isButtonMode=true, isButton=false, hasBattery=false, serial=1536, count=0, lastEventCount[0]=0
17:19:33.962 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[6]: id=2201, Value=0.0 (input, Type=S, Range=0/1, Link=3: input_1)
17:19:33.963 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[7]: id=2202, Value=-1.0 (inputEvent, Type=EV, Range=S/L;, Link=3: input_1)
17:19:33.963 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[8]: id=2203, Value=0.0 (inputEventCnt, Type=EVC, Range=U16, Link=3: input_1)
17:19:33.964 [TRACE] [ly.internal.api1.Shelly1CoIoTProtocol] - shellydimmer2-e8db84d3c8e4: Check button[1] for event trigger (isButtonMode=true, isButton=false, hasBattery=false, serial=1536, count=0, lastEventCount[1]=0
17:19:33.965 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[9]: id=4101, Value=9.91 (power, Type=P, Range=0/230;-1, Link=1: light_0)
17:19:33.966 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[10]: id=4103, Value=145.0 (energy, Type=E, Range=U32;-1, Link=1: light_0)
17:19:33.967 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[11]: id=6102, Value=0.0 (overpower, Type=A, Range=0/1;-1, Link=1: light_0)
17:19:33.968 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[12]: id=6109, Value=0.0 (overpowerValue, Type=P, Range=U32;-1, Link=1: light_0)
17:19:33.968 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[13]: id=6104, Value=0.0 (loadError, Type=A, Range=0/1, Link=1: light_0)
17:19:33.969 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[14]: id=3104, Value=30.58 (deviceTemp, Type=T, Range=-40/300;999, Link=4: device)
17:19:33.970 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[15]: id=3105, Value=87.04 (deviceTemp, Type=T, Range=-40/572;999, Link=4: device)
17:19:33.971 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[16]: id=6101, Value=0.0 (overtemp, Type=A, Range=0/1;-1, Link=4: device)
17:19:33.971 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[17]: id=9101, Value=white (mode, Type=S, Range=color/white, Link=4: device)
17:19:35.461 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin notification (configuration successful) to org/ops4j/pax/logging/Configuration
17:19:40.005 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:19:31.447+0100 to 2023-01-13T17:19:39.999+0100
17:19:40.008 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Power' changed from 2 W to 0 W
17:19:40.862 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:19:39.999+0100 to 2023-01-13T17:19:40.854+0100
17:19:40.867 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Power' changed from 0 W to 2 W
17:19:42.316 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: CoIoT Message from /X.X.X.204:5683 (MID=42401): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,1],[0,1301,0],[0,5301,1],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,190],[0,4201,0.00],[0,4203,0],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:19:42.324 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:19:42.327 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-white-d9625e: Watchdog restarted (expires in 70 sec)
17:19:42.330 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: Serial 27904 was already processed, ignore update
17:19:42.831 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: CoIoT Message from /X.X.X.201:5683 (MID=19140): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,100],[0,1301,0],[0,5301,0],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,129],[0,4201,0.00],[0,4203,129],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:19:42.833 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:19:42.835 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-00bccf: Watchdog restarted (expires in 70 sec)
17:19:42.837 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: Serial 36608 was already processed, ignore update
17:19:44.852 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:19:40.854+0100 to 2023-01-13T17:19:44.843+0100
17:19:44.856 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Power' changed from 2 W to 0 W
17:19:48.954 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Message from /X.X.X.203:5683 (MID=4983): {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.96],[0,4103,145],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.68],[0,3105,87.22],[0,6101,0],[0,9101,"white"]]}
17:19:48.960 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
17:19:48.964 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellydimmer2-e8db84d3c8e4: Watchdog restarted (expires in 70 sec)
17:19:48.966 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.96],[0,4103,145],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.68],[0,3105,87.22],[0,6101,0],[0,9101,"white"]]} (serial=1536)
17:19:48.968 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: 18 CoAP sensor updates received
17:19:48.970 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[0]: id=9103, Value=0.0 (cfgChanged, Type=EVC, Range=U16, Link=4: device)
17:19:48.972 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[1]: id=1101, Value=1.0 (output, Type=S, Range=0/1, Link=1: light_0)
17:19:48.974 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[2]: id=5101, Value=14.0 (brightness, Type=S, Range=1/100, Link=1: light_0)
17:19:48.976 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[3]: id=2101, Value=0.0 (input, Type=S, Range=0/1, Link=2: input_0)
17:19:48.978 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[4]: id=2102, Value=-1.0 (inputEvent, Type=EV, Range=S/L;, Link=2: input_0)
17:19:48.980 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[5]: id=2103, Value=0.0 (inputEventCnt, Type=EVC, Range=U16, Link=2: input_0)
17:19:48.981 [TRACE] [ly.internal.api1.Shelly1CoIoTProtocol] - shellydimmer2-e8db84d3c8e4: Check button[0] for event trigger (isButtonMode=true, isButton=false, hasBattery=false, serial=1536, count=0, lastEventCount[0]=0
17:19:48.983 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[6]: id=2201, Value=0.0 (input, Type=S, Range=0/1, Link=3: input_1)
17:19:48.988 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[7]: id=2202, Value=-1.0 (inputEvent, Type=EV, Range=S/L;, Link=3: input_1)
17:19:48.990 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[8]: id=2203, Value=0.0 (inputEventCnt, Type=EVC, Range=U16, Link=3: input_1)
17:19:48.992 [TRACE] [ly.internal.api1.Shelly1CoIoTProtocol] - shellydimmer2-e8db84d3c8e4: Check button[1] for event trigger (isButtonMode=true, isButton=false, hasBattery=false, serial=1536, count=0, lastEventCount[1]=0
17:19:48.994 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[9]: id=4101, Value=9.96 (power, Type=P, Range=0/230;-1, Link=1: light_0)
17:19:48.996 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[10]: id=4103, Value=145.0 (energy, Type=E, Range=U32;-1, Link=1: light_0)
17:19:48.998 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[11]: id=6102, Value=0.0 (overpower, Type=A, Range=0/1;-1, Link=1: light_0)
17:19:48.999 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[12]: id=6109, Value=0.0 (overpowerValue, Type=P, Range=U32;-1, Link=1: light_0)
17:19:49.001 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[13]: id=6104, Value=0.0 (loadError, Type=A, Range=0/1, Link=1: light_0)
17:19:49.003 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[14]: id=3104, Value=30.68 (deviceTemp, Type=T, Range=-40/300;999, Link=4: device)
17:19:49.005 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[15]: id=3105, Value=87.22 (deviceTemp, Type=T, Range=-40/572;999, Link=4: device)
17:19:49.007 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[16]: id=6101, Value=0.0 (overtemp, Type=A, Range=0/1;-1, Link=4: device)
17:19:49.009 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[17]: id=9101, Value=white (mode, Type=S, Range=color/white, Link=4: device)
17:19:55.665 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'deconz:onofflight:00212E06912F:7cb03eaa00b2319503' changed from OFFLINE: Not reachable to ONLINE
17:19:55.671 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseHandstaubsaugerOSRAM_State' changed from UNDEF to ON
17:19:57.318 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: CoIoT Message from /X.X.X.204:5683 (MID=42402): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,1],[0,1301,0],[0,5301,1],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,190],[0,4201,0.00],[0,4203,0],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:19:57.323 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:19:57.326 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-white-d9625e: Watchdog restarted (expires in 70 sec)
17:19:57.329 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: Serial 27904 was already processed, ignore update
17:19:57.835 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: CoIoT Message from /X.X.X.201:5683 (MID=19141): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,100],[0,1301,0],[0,5301,0],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,129],[0,4201,0.00],[0,4203,129],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:19:57.838 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:19:57.840 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-00bccf: Watchdog restarted (expires in 70 sec)
17:19:57.841 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: Serial 36608 was already processed, ignore update
17:20:03.958 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Message from /X.X.X.203:5683 (MID=4984): {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.96],[0,4103,155],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.73],[0,3105,87.31],[0,6101,0],[0,9101,"white"]]}
17:20:03.965 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseMonitorrechtsinnr_LastUpdated' changed from 2023-01-13T17:19:28.551+0100 to 2023-01-13T17:20:03.949+0100
17:20:03.967 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
17:20:03.971 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellydimmer2-e8db84d3c8e4: Watchdog restarted (expires in 70 sec)
17:20:03.975 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.96],[0,4103,155],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.73],[0,3105,87.31],[0,6101,0],[0,9101,"white"]]} (serial=1536)
17:20:03.978 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: 18 CoAP sensor updates received
17:20:03.981 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[0]: id=9103, Value=0.0 (cfgChanged, Type=EVC, Range=U16, Link=4: device)
17:20:03.984 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[1]: id=1101, Value=1.0 (output, Type=S, Range=0/1, Link=1: light_0)
17:20:03.987 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[2]: id=5101, Value=14.0 (brightness, Type=S, Range=1/100, Link=1: light_0)
17:20:03.990 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[3]: id=2101, Value=0.0 (input, Type=S, Range=0/1, Link=2: input_0)
17:20:03.993 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[4]: id=2102, Value=-1.0 (inputEvent, Type=EV, Range=S/L;, Link=2: input_0)
17:20:03.996 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[5]: id=2103, Value=0.0 (inputEventCnt, Type=EVC, Range=U16, Link=2: input_0)
17:20:03.999 [TRACE] [ly.internal.api1.Shelly1CoIoTProtocol] - shellydimmer2-e8db84d3c8e4: Check button[0] for event trigger (isButtonMode=true, isButton=false, hasBattery=false, serial=1536, count=0, lastEventCount[0]=0
17:20:04.002 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[6]: id=2201, Value=0.0 (input, Type=S, Range=0/1, Link=3: input_1)
17:20:04.005 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[7]: id=2202, Value=-1.0 (inputEvent, Type=EV, Range=S/L;, Link=3: input_1)
17:20:04.008 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[8]: id=2203, Value=0.0 (inputEventCnt, Type=EVC, Range=U16, Link=3: input_1)
17:20:04.011 [TRACE] [ly.internal.api1.Shelly1CoIoTProtocol] - shellydimmer2-e8db84d3c8e4: Check button[1] for event trigger (isButtonMode=true, isButton=false, hasBattery=false, serial=1536, count=0, lastEventCount[1]=0
17:20:04.014 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[9]: id=4101, Value=9.96 (power, Type=P, Range=0/230;-1, Link=1: light_0)
17:20:04.017 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[10]: id=4103, Value=155.0 (energy, Type=E, Range=U32;-1, Link=1: light_0)
17:20:04.020 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[11]: id=6102, Value=0.0 (overpower, Type=A, Range=0/1;-1, Link=1: light_0)
17:20:04.023 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[12]: id=6109, Value=0.0 (overpowerValue, Type=P, Range=U32;-1, Link=1: light_0)
17:20:04.025 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[13]: id=6104, Value=0.0 (loadError, Type=A, Range=0/1, Link=1: light_0)
17:20:04.028 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[14]: id=3104, Value=30.73 (deviceTemp, Type=T, Range=-40/300;999, Link=4: device)
17:20:04.031 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[15]: id=3105, Value=87.31 (deviceTemp, Type=T, Range=-40/572;999, Link=4: device)
17:20:04.034 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[16]: id=6101, Value=0.0 (overtemp, Type=A, Range=0/1;-1, Link=4: device)
17:20:04.037 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[17]: id=9101, Value=white (mode, Type=S, Range=color/white, Link=4: device)
17:20:04.409 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:19:44.843+0100 to 2023-01-13T17:20:04.400+0100
17:20:04.411 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Voltage' changed from 234 V to 230 V
17:20:09.014 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseMonitorrechtsinnr_LastUpdated' changed from 2023-01-13T17:20:03.949+0100 to 2023-01-13T17:20:08.997+0100
17:20:09.019 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseMonitorrechtsinnr_Voltage' changed from 235 V to 232 V
17:20:12.321 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: CoIoT Message from /X.X.X.204:5683 (MID=42403): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,1],[0,1301,0],[0,5301,1],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,190],[0,4201,0.00],[0,4203,0],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:20:12.326 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:20:12.330 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-white-d9625e: Watchdog restarted (expires in 70 sec)
17:20:12.333 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: Serial 27904 was already processed, ignore update
17:20:12.832 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: CoIoT Message from /X.X.X.201:5683 (MID=19142): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,100],[0,1301,0],[0,5301,0],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,129],[0,4201,0.00],[0,4203,129],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:20:12.836 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:20:12.839 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-00bccf: Watchdog restarted (expires in 70 sec)
17:20:12.843 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: Serial 36608 was already processed, ignore update
17:20:18.584 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:20:04.400+0100 to 2023-01-13T17:20:18.578+0100
17:20:18.589 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Voltage' changed from 230 V to 234 V
17:20:18.956 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Message from /X.X.X.203:5683 (MID=4985): {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.96],[0,4103,155],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.73],[0,3105,87.31],[0,6101,0],[0,9101,"white"]]}
17:20:18.959 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
17:20:18.961 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellydimmer2-e8db84d3c8e4: Watchdog restarted (expires in 70 sec)
17:20:18.963 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: Serial 1536 was already processed, ignore update
17:20:21.832 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:20:18.578+0100 to 2023-01-13T17:20:21.822+0100
17:20:21.838 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Power' changed from 0 W to 2 W
17:20:22.976 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseMonitorrechtsinnr_LastUpdated' changed from 2023-01-13T17:20:08.997+0100 to 2023-01-13T17:20:22.970+0100
17:20:22.981 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseMonitorrechtsinnr_Voltage' changed from 232 V to 235 V
17:20:27.030 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:20:21.822+0100 to 2023-01-13T17:20:27.021+0100
17:20:27.035 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Power' changed from 2 W to 0 W
17:20:27.324 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: CoIoT Message from /X.X.X.204:5683 (MID=42404): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,1],[0,1301,0],[0,5301,1],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,190],[0,4201,0.00],[0,4203,0],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:20:27.327 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:20:27.329 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-white-d9625e: Watchdog restarted (expires in 70 sec)
17:20:27.331 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: Serial 27904 was already processed, ignore update
17:20:27.811 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:20:27.021+0100 to 2023-01-13T17:20:27.801+0100
17:20:27.817 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Power' changed from 0 W to 2 W
17:20:27.832 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: CoIoT Message from /X.X.X.201:5683 (MID=19143): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,100],[0,1301,0],[0,5301,0],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,129],[0,4201,0.00],[0,4203,129],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:20:27.837 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:20:27.845 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-00bccf: Watchdog restarted (expires in 70 sec)
17:20:27.848 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: Serial 36608 was already processed, ignore update
17:20:28.845 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:20:27.801+0100 to 2023-01-13T17:20:28.836+0100
17:20:28.850 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Power' changed from 2 W to 0 W
17:20:32.410 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:20:28.836+0100 to 2023-01-13T17:20:32.404+0100
17:20:32.415 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Voltage' changed from 234 V to 230 V
17:20:34.082 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Message from /X.X.X.203:5683 (MID=4986): {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.96],[0,4103,155],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.48],[0,3105,86.86],[0,6101,0],[0,9101,"white"]]}
17:20:34.084 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
17:20:34.086 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellydimmer2-e8db84d3c8e4: Watchdog restarted (expires in 70 sec)
17:20:34.088 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.96],[0,4103,155],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.48],[0,3105,86.86],[0,6101,0],[0,9101,"white"]]} (serial=1536)
17:20:34.090 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: 18 CoAP sensor updates received
17:20:34.092 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[0]: id=9103, Value=0.0 (cfgChanged, Type=EVC, Range=U16, Link=4: device)
17:20:34.093 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[1]: id=1101, Value=1.0 (output, Type=S, Range=0/1, Link=1: light_0)
17:20:34.095 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[2]: id=5101, Value=14.0 (brightness, Type=S, Range=1/100, Link=1: light_0)
17:20:34.097 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[3]: id=2101, Value=0.0 (input, Type=S, Range=0/1, Link=2: input_0)
17:20:34.099 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[4]: id=2102, Value=-1.0 (inputEvent, Type=EV, Range=S/L;, Link=2: input_0)
17:20:34.101 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[5]: id=2103, Value=0.0 (inputEventCnt, Type=EVC, Range=U16, Link=2: input_0)
17:20:34.102 [TRACE] [ly.internal.api1.Shelly1CoIoTProtocol] - shellydimmer2-e8db84d3c8e4: Check button[0] for event trigger (isButtonMode=true, isButton=false, hasBattery=false, serial=1536, count=0, lastEventCount[0]=0
17:20:34.104 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[6]: id=2201, Value=0.0 (input, Type=S, Range=0/1, Link=3: input_1)
17:20:34.106 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[7]: id=2202, Value=-1.0 (inputEvent, Type=EV, Range=S/L;, Link=3: input_1)
17:20:34.108 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[8]: id=2203, Value=0.0 (inputEventCnt, Type=EVC, Range=U16, Link=3: input_1)
17:20:34.110 [TRACE] [ly.internal.api1.Shelly1CoIoTProtocol] - shellydimmer2-e8db84d3c8e4: Check button[1] for event trigger (isButtonMode=true, isButton=false, hasBattery=false, serial=1536, count=0, lastEventCount[1]=0
17:20:34.112 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[9]: id=4101, Value=9.96 (power, Type=P, Range=0/230;-1, Link=1: light_0)
17:20:34.114 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[10]: id=4103, Value=155.0 (energy, Type=E, Range=U32;-1, Link=1: light_0)
17:20:34.116 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[11]: id=6102, Value=0.0 (overpower, Type=A, Range=0/1;-1, Link=1: light_0)
17:20:34.117 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[12]: id=6109, Value=0.0 (overpowerValue, Type=P, Range=U32;-1, Link=1: light_0)
17:20:34.119 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[13]: id=6104, Value=0.0 (loadError, Type=A, Range=0/1, Link=1: light_0)
17:20:34.121 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[14]: id=3104, Value=30.48 (deviceTemp, Type=T, Range=-40/300;999, Link=4: device)
17:20:34.123 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[15]: id=3105, Value=86.86 (deviceTemp, Type=T, Range=-40/572;999, Link=4: device)
17:20:34.125 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[16]: id=6101, Value=0.0 (overtemp, Type=A, Range=0/1;-1, Link=4: device)
17:20:34.126 [TRACE] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4:  Sensor value[17]: id=9101, Value=white (mode, Type=S, Range=color/white, Link=4: device)
17:20:36.831 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:20:32.404+0100 to 2023-01-13T17:20:36.824+0100
17:20:36.836 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Power' changed from 0 W to 2 W
17:20:37.339 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseMonitorlinksinnr_LastUpdated' changed from 2023-01-13T17:18:30.142+0100 to 2023-01-13T17:20:37.331+0100
17:20:40.835 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_LastUpdated' changed from 2023-01-13T17:20:36.824+0100 to 2023-01-13T17:20:40.822+0100
17:20:40.843 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'SteckdoseKlingelinnr_Power' changed from 2 W to 0 W
17:20:42.326 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: CoIoT Message from /X.X.X.204:5683 (MID=42405): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,1],[0,1301,0],[0,5301,1],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,190],[0,4201,0.00],[0,4203,0],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:20:42.331 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:20:42.335 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-white-d9625e: Watchdog restarted (expires in 70 sec)
17:20:42.339 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-white-d9625e: Serial 27904 was already processed, ignore update
17:20:42.838 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: CoIoT Message from /X.X.X.201:5683 (MID=19144): {"G":[[0,9103,0],[0,1101,0],[0,5101,100],[0,1201,0],[0,5201,100],[0,1301,0],[0,5301,0],[0,1401,0],[0,5401,1],[0,4101,0.00],[0,4103,129],[0,4201,0.00],[0,4203,129],[0,4301,0.00],[0,4303,0],[0,4401,0.00],[0,4403,0],[0,6102,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,9101,"white"]]}
17:20:42.843 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyLightHandler tried updating the thing status although the handler was already disposed.
17:20:42.846 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellyrgbw2-00bccf: Watchdog restarted (expires in 70 sec)
17:20:42.849 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyrgbw2-00bccf: Serial 36608 was already processed, ignore update
17:20:49.085 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: CoIoT Message from /X.X.X.203:5683 (MID=4987): {"G":[[0,9103,0],[0,1101,1],[0,5101,14],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4101,9.96],[0,4103,155],[0,6102,0],[0,6109,0.00],[0,6104,0],[0,3104,30.48],[0,3105,86.86],[0,6101,0],[0,9101,"white"]]}
17:20:49.088 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
17:20:49.089 [TRACE] [ly.internal.handler.ShellyBaseHandler] - shellydimmer2-e8db84d3c8e4: Watchdog restarted (expires in 70 sec)
17:20:49.091 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellydimmer2-e8db84d3c8e4: Serial 1536 was already processed, ignore update

jabra_the_hut · January 14, 2023, 8:04am

Hi,

my logs are also flooded with the following messages:


2023-01-14 09:00:34.033 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
2023-01-14 09:00:34.658 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
2023-01-14 09:00:35.160 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
2023-01-14 09:00:36.672 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
2023-01-14 09:00:36.929 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.
2023-01-14 09:00:37.325 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.

Here is what I did:

In openHAB 3.4.0 I enabled a Thing via UI which was disabled via UI but still listed in a *.things file
I renamed the label only via the *.things file
The logs started to flood with the above mentioned snippet but all messages included the edited Thing
I removed the Shelly binding via UI
I updated to openHAB 3.4.1 via apt-get upgrade
I re-installed the Shelly binding via UI

All things appear as “Online” but the log is flooded with the message above and at some point openHAB forces a restart.

jabra_the_hut · January 14, 2023, 8:19am

OK, messages seem to be gone for now. I removed the binding again then I temporarily removed the *.things file containing all Shellys. Then re-installed the binding. Then I got no error messages.
Then I made the *.things file available again and now all things are back without error messages (so far).

thefox73 · January 14, 2023, 11:39am

Hi all,

since I updated to OH 3.4.1 via openHABian my log is flooded with

[WARN ] [.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler tried updating the thing status although the handler was already disposed.

as well. I do not use thing-files. I already removed the binding, cleaned the OH cache, removed the things, installed the binding again, added the things again, but I don’t get rid of the handler warning.
Any help is much appreciated.

Thank you

Oliver2 · January 14, 2023, 11:50am

I read a few times that physically switching off the machine helped.

thefox73 · January 14, 2023, 12:07pm

That did the trick!!!
THX!

scha · January 14, 2023, 4:06pm

switching off and on also on my side removed the log-flood.
No idea why, but all fine now again.

Ranzkarl · January 14, 2023, 9:39pm

Hi, I just found out that my openhab instance completely loses outbound TCP connections when the issue appears. E.g. It stops updating the date and time object as well as not being able to send updates to the Shellys. The Debian itself is not affected as I can ping external adresses fine and SSH / telnet is working as well… So thanks for trying to troubleshoot the Shelly issue but it is obviously related to something different.

The handler is already disposed message can be fixed by simply restarting the whole device. Binding reset or openhab service restart doesn’t do the trick for me.

Matthias_Kaufmann · January 16, 2023, 12:49pm

I got 3.4.2 installed, thank you for the hint.
Nevertheless still values get mapped “randomely” to 0 or 100 stillm, see also bug report here.

charlyx · January 17, 2023, 8:29pm

@markus7017: are you probably already working on support of Shelly Plus Smoke Alarm?
https://www.shelly.cloud/en-bg/products/product-overview/shelly-plus-smoke

the-ninth · January 19, 2023, 8:50am

Good morning!

Has anyone tried to use the range extender functionality of the current generation of Shellys?

I read that when using it, both the device acting as range extender and the device connecting to it would be accessible via the same IP but different ports. Would the binding support such a setup?

So far I cannot get it to work, but that seems to be a different issue, because I can’t even access the device connecting to the range extender in the Shelly app and do not really know how to troubleshoot that.

Baschtlwaschtl · January 22, 2023, 6:50pm

Hello, I have a question regarding this binding and battery operated shellys.
For example, shelly motion or TRV.
The devices go into sleep mode and commands are not sent sometimes.

shellymotionsensor-60a42386d716: FEHLER: Der Befehl 2 für Kanal shelly:shellymotion:f76253bbaa:sensors#sensorSleepTime kann nicht verarbeitet werden - API Timeout for GET http://192.168.0.225/settings?sleep_time=3

Is the command sent again or is it simply ignored after the timeout?

How can I be sure that a - for example - temperature change for a TRV has also been adopted.

Greets

hmerk · January 22, 2023, 7:10pm

I don‘t really know how the binding handles this internally, but can say that my TRVs did not miss any temperature change.

Oliver2 · January 22, 2023, 7:31pm

I can confirm this.
For TRVs the binding works perfectly.

Baschtlwaschtl · January 22, 2023, 9:43pm

Unfortunately, this is not the case with me.

shellytrv-60a423d92dd4: FEHLER: Der Befehl 22 für Kanal shelly:shellytrv:TrvBad226:control#targetTemp kann nicht verarbeitet werden - API Timeout for GET http://192.168.0.226/thermostat/0?target_t_enabled=1&target_t=22

There is a ‘window_open’ action in the TRV which isn’t included in the binding.
I wrote a shell script to send the action with check of the answer. And sometimes the script run twice to set the window open/close state.

Maybe @markus7017 can say something about that timeout behavior.
Greets.