Shelly Binding

Samir.E · February 22, 2021, 2:00pm

Hi,
using the latest snapshot binding to try to get rid of a minor problem I have with the binding but no success.

Platform: Windows
Version: openHAB 3.0.1
Binding version: 3.1.0-SNAPSHOT (the same problem with the 3.0.1 stable release version)
Hardware: Shelly2.5 Roller SHSW-25

Managed to get the snapshot to work after manual installation of the jar-file even after it was complaining about californium missing (this is only for other people searching for this error, it took me a while to find it):

Error executing command: Error executing command on bundles:
        Error starting bundle 268: Could not resolve module: org.openhab.binding.shelly [268]
  Unresolved requirement: Import-Package: org.eclipse.californium.core; version="[2.0.0,3.0.0)"

Solved by entering this in the console:
openhab> feature:install openhab-transport-coap

Confirm by:
openhab> bundle:list -s

Problem description
Everything works fine with the latest Shelly binding (and also the 3.0.1 stable version) with buttons, sensors, curtains and windows. The only small problem I have is that every fifteen seconds or so, Shelly (I guess) reports the state like this, over and over again:

14:15:20.423 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
14:15:22.865 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
14:15:35.438 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
14:15:38.152 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
14:16:01.225 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
14:16:02.689 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
....

So everything is working fine except the logging. Buttons respond correctly, window and curtains open/close correctly and show up in the openHAB interface and can be controlled from there.

My trials to solve it
I have tried to downgrade and upgrade the firmware of the Shelly switch but no change. I have deleted and added the things/items - no change. Note: I have two exactly the same switches used in the same manner (one for curtains and one for window).

Configured the same, the same (and also tested different) firmware. Absolutely no change.
The only thing that I can see as the difference is that the curtains (the problematic switch) has NOT been calibrated since it is actually not possible to do with a motor that uses so little current as my curtains do. So maybe the Shelly binding thinks (or tries to get) the state is “floating” and that is why it reports all the time. I have no idea.

This is not a big problem, just a bit annoying since the log fills up with unnecessary rows. I can of course reduce the logging of the Shelly binding but I thought I should report this, maybe it confuses someone else.

If there is anything information I can add to the above, please tell me.

PS: thanks for a very well working binding and also lots of kudos to all working on the openHAB3 release!

markus7017 · February 22, 2021, 7:34pm

hmm, check the code, but looks ok

markus7017 · February 22, 2021, 7:36pm

what does “bundle:list | grep Shelly” shows?
Please follow the How-To-Install and make sure you have the correct jars

This problem is known, but was fixed month ago.

Samir.E · February 22, 2021, 8:18pm

Hi,
thanks for coming back to me.

I have tried to follow the install of jars very carefully according to the manual. And as I wrote, this is exactly the same with the released 3.0.1 version (stable). I am just showing you the result of my trials to upgrade to the absolutely latest version. But it is the same with the stable one.

I am on Windows so I am not fully aware of your command but is this what you are looking for?
bundle:list

258 │ Active │  80 │ 3.0.1                   │ openHAB Add-ons :: Bundles :: Network Binding
260 │ Active │  80 │ 1.0.0                   │ IO.Socket Engine Client
261 │ Active │  80 │ 1.0.0                   │ IO.Socket Socket Client
262 │ Active │  80 │ 20180813.0.0            │ JSON in Java
263 │ Active │  80 │ 3.8.1.1                 │ Apache ServiceMix :: Bundles :: okhttp
264 │ Active │  80 │ 1.13.0.1                │ Apache ServiceMix :: Bundles :: okio
265 │ Active │  80 │ 3.0.1                   │ openHAB Add-ons :: Bundles :: IO :: openHAB Cloud Connector
266 │ Active │  80 │ 3.0.1                   │ openHAB Add-ons :: Bundles :: Voice :: Google Cloud Text-to-Speech
268 │ Active │  80 │ 3.1.0.202102210341      │ openHAB Add-ons :: Bundles :: Shelly Binding
269 │ Active │  80 │ 2.0.0                   │ Californium (Cf) Core
270 │ Active │  80 │ 2.0.0                   │ Californium (Cf) Element Connector
271 │ Active │  80 │ 2.0.0                   │ Californium (Cf) OSGi
272 │ Active │  80 │ 2.0.0                   │ Scandium (Sc) Core

bundle:list -s

260 │ Active │  80 │ 1.0.0                   │ io.socket.engine.io-client
261 │ Active │  80 │ 1.0.0                   │ io.socket.socket.io-client
262 │ Active │  80 │ 20180813.0.0            │ json
263 │ Active │  80 │ 3.8.1.1                 │ org.apache.servicemix.bundles.okhttp
264 │ Active │  80 │ 1.13.0.1                │ org.apache.servicemix.bundles.okio
265 │ Active │  80 │ 3.0.1                   │ org.openhab.io.openhabcloud
266 │ Active │  80 │ 3.0.1                   │ org.openhab.voice.googletts
268 │ Active │  80 │ 3.1.0.202102210341      │ org.openhab.binding.shelly
269 │ Active │  80 │ 2.0.0                   │ org.eclipse.californium.core
270 │ Active │  80 │ 2.0.0                   │ org.eclipse.californium.element-connector
271 │ Active │  80 │ 2.0.0                   │ org.eclipse.californium.osgi
272 │ Active │  80 │ 2.0.0                   │ org.eclipse.californium.scandium

markus7017 · February 22, 2021, 8:46pm

300% sure that the DEV build is different to the release version

—

Latest DEV build: 2.5.13 - 3.1.0 - README - Installation - Bugs/Features - Firmware Index - Firmware Archive - API Doc
Note: The binding version included in the final OH 3.0 distro is significantly older than the DEV build. I can’t make it in-time. Make sure you deleted older versions of the binding when installing the 2.5.13-SNAPSHOT or 3.1.0-SNAPSHOT if you are already on OH 3.

markus7017 · February 23, 2021, 7:49am

It’s time to make you aware of an significant new feature of the Shelly Binding: Shelly Manager

Development of this integrated feature was driven by

it’s hard to get an overview on all your devices and check if they are working fine
the hazel dealing with firmware updates (esp. when finding older ones)
the demand of separating the device from the Internet for improved security

Core features

Allows to see device insights not available has regular channels (settings + status)
Provides statistics to verify WiFi and device stability (timeouts, restarts, device alarms)
integrated device actions like reboot, factory reset, set login credentials…
Integrated firmware upgrade to latest prod/beta version and from firmware archive
Integrated download proxy to detach device from Internet (improve device security)

overview_devsettings overview_devstatus

overview_actions

Versions from the Allterco repository and also http://archive.shelly-tools.de
overview_versions

Those could be installed

in regular mode (device downloads the image from the Internet)
using the openHAB host as download proxy (device doesn’t require Internet connection)
using a custom URL (e.g. a local web server)

This allows to separate the device from the Internet. You could also set a local time server (sntp). However, when you are using the Cloud feature of course is requires an Internet connection. The firmware download is transparent, so the device gets 1:1 the image from the remote server, the binding is just redirecting the URLs and requests the device to start the download (same as the Shelly App and Web UI do).

More primarily information could be found here

The current status is almost stable. @igi supported on testing during the last weeks. This feature will be part of the next PR, but is already available for the 2.5.13 and 3.1 DEV builds. I’ll add some more info to the README outlining how the statistics helps you to identify instable WiFi connectivity.

You get access to the Shelly Manager by opening http://:8080/shelly/manager in the browser (you could also use https to access the manager, but the firmware download in proxy mode will fail, because the device doesn’t support https).

Have fun, feedback welcome.

Latest DEV build: 2.5.13 - 3.1.0 - README - Installation - Bugs/Features - Firmware Index - Firmware Archive - API Doc
Note: The binding version included in the final OH 3.0 distro is significantly older than the DEV build. I can’t make it in-time. Make sure you deleted older versions of the binding when installing the 2.5.13-SNAPSHOT or 3.1.0-SNAPSHOT if you are already on OH 3.

Samir.E · February 23, 2021, 8:28am

Hi Markus,
I honestly don’t know what to do with your information. I uninstalled the 3.0.1 binding that came with the released stable version, checked so it was gone with bundle:list, restarted, downloaded the snapshot addons for 3.1.0 from here:

Inside that package I found the Shelly binding and manually installed that according to the documentation.
This is the file inside the addon package:

I checked that the new version was installed and indeed it was:

I am obviously doing something wrong but I don’t know what to do with your comment. I am sure you are sure it is different from the release version - but what version is it that I am downloading and installing from the openHAB homepage if it is not the snapshot version? Or is the version you are talking about different from the snapshot addon on the homepage?

I am sorry but I am a bit lost and don’t know how to give you more information or do something else from what I have done.

UPDATE
Redid the whole procedure, found your links in your footer (Read Me - installation) and did exactly as it stated there. Also downloaded the californium-core and element-connector from that page. Uninstalled them first and then put them in the addons folder as described (I had manually installed them before so this was a new variant to put them into the folder, just to follow your instructions very carefully).

Added the things again, linked them to items.

This is after update:

Seems correct to me. But sorry to say, the repeating of events goes on:

13:41:03.422 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
13:41:03.846 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
13:41:18.424 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
13:41:19.149 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0

markus7017 · February 23, 2021, 4:14pm

In general: OH 3.x release != 3.x SNAPSHOT != 3.1 DEV build

The 3.x-SNAPSHOT is based on the “official code base”. The code gets updated through a PR process, usual outcome are changes requested by review.

The DEV build is based on my latest code base. From time to time I create a new PR, run that process, but continue with development in parallel. So the DEV build is ahead of the official SNAPSHOT release and far ahead of the release version.

Could you please enable the DEBUG trace on the OH console “log:set DEBUG org.openhab.binding.shelly” This provides the output I need to check the code. Post log extract here or send me a PM.

Samir.E · February 23, 2021, 4:53pm

Aha, I did not know that about the snapshot/release/dev. Now I know, thank you

Here is a bit of the log as requested. Hope it makes sense:

17:33:00.817 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#uptime updated with 8028538 s (type class org.openhab.core.library.types.QuantityType).
17:33:00.818 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-e0980695993a: status.update=8028538, lastUpdate=8028525
17:33:10.101 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#heartBeat updated with 2021-02-23T17:33:10.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:10.122 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#uptime updated with 8028547 s (type class org.openhab.core.library.types.QuantityType).
17:33:10.123 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#internalTemp updated with 60 °C (type class org.openhab.core.library.types.QuantityType).
17:33:10.124 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-e0980695993a: status.update=8028547, lastUpdate=8028538
17:33:12.618 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28257): {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,59.75],[0,6101,0],[0,9101,"roller"]]}
17:33:12.619 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T17:33:12.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:12.620 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,59.75],[0,6101,0],[0,9101,"roller"]]} (serial=32274)
17:33:12.621 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
17:33:12.622 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
17:33:12.623 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 100.0 % (type class org.openhab.core.library.types.QuantityType).
17:33:12.624 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 1 channels updated from CoIoT status, serial=32274
17:33:12.624 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
17:33:14.516 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T17:33:14.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:14.540 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 0.0 % (type class org.openhab.core.library.types.QuantityType).
17:33:14.541 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
17:33:14.542 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 283316 s (type class org.openhab.core.library.types.QuantityType).
17:33:14.547 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=283316, lastUpdate=283259
17:33:15.435 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: CoIoT Message from /192.168.1.118:5683 (MID=64854): {"G":[[0,9103,0],[0,1102,"stop"],[0,1103,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,446],[0,6103,"normal"],[0,3104,60.61],[0,6101,0],[0,9101,"roller"]]}
17:33:15.437 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#heartBeat updated with 2021-02-23T17:33:15.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:15.438 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: Serial 25361 was already processed, ignore update
17:33:16.217 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28258): {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,59.87],[0,6101,0],[0,9101,"roller"]]}
17:33:16.219 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T17:33:16.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:16.219 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,59.87],[0,6101,0],[0,9101,"roller"]]} (serial=32530)
17:33:16.221 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
17:33:16.221 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
17:33:16.222 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 100.0 % (type class org.openhab.core.library.types.QuantityType).
17:33:16.223 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
17:33:16.223 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 1 channels updated from CoIoT status, serial=32530
17:33:17.791 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T17:33:17.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:17.836 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 0.0 % (type class org.openhab.core.library.types.QuantityType).
17:33:17.837 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
17:33:17.845 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 283319 s (type class org.openhab.core.library.types.QuantityType).
17:33:17.846 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=283319, lastUpdate=283316
17:33:30.466 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: CoIoT Message from /192.168.1.118:5683 (MID=64855): {"G":[[0,9103,0],[0,1102,"stop"],[0,1103,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,446],[0,6103,"normal"],[0,3104,60.61],[0,6101,0],[0,9101,"roller"]]}
17:33:30.468 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#heartBeat updated with 2021-02-23T17:33:30.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:30.469 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: Serial 25361 was already processed, ignore update
17:33:31.221 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28259): {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,59.75],[0,6101,0],[0,9101,"roller"]]}
17:33:31.223 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T17:33:31.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:31.228 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,59.75],[0,6101,0],[0,9101,"roller"]]} (serial=32530)
17:33:31.229 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
17:33:31.230 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
17:33:31.232 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 100.0 % (type class org.openhab.core.library.types.QuantityType).
17:33:31.233 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 1 channels updated from CoIoT status, serial=32530
17:33:31.232 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
17:33:33.089 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T17:33:33.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:33.111 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 0.0 % (type class org.openhab.core.library.types.QuantityType).
17:33:33.112 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
17:33:33.113 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 283335 s (type class org.openhab.core.library.types.QuantityType).
17:33:33.119 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=283335, lastUpdate=283319
17:33:45.489 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: CoIoT Message from /192.168.1.118:5683 (MID=64856): {"G":[[0,9103,0],[0,1102,"stop"],[0,1103,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,446],[0,6103,"normal"],[0,3104,60.61],[0,6101,0],[0,9101,"roller"]]}
17:33:45.491 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#heartBeat updated with 2021-02-23T17:33:45.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:45.494 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: Serial 25361 was already processed, ignore update
17:33:46.226 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28260): {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,59.99],[0,6101,0],[0,9101,"roller"]]}
17:33:46.228 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T17:33:46.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:46.228 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,59.99],[0,6101,0],[0,9101,"roller"]]} (serial=32530)
17:33:46.230 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
17:33:46.230 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
17:33:46.231 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 100.0 % (type class org.openhab.core.library.types.QuantityType).
17:33:46.232 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 1 channels updated from CoIoT status, serial=32530
17:33:46.232 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
17:33:48.381 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T17:33:48.000+0100 (type class org.openhab.core.library.types.DateTimeType).
17:33:48.430 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 0.0 % (type class org.openhab.core.library.types.QuantityType).
17:33:48.431 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 283350 s (type class org.openhab.core.library.types.QuantityType).
17:33:48.431 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
17:33:48.432 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=283350, lastUpdate=283335

Added it as text document as well, maybe it is easier to read:
Shelly_Log.txt (12.7 KB)

markus7017 · February 23, 2021, 5:34pm

what is the correct status? 100 or 0? open or closed?
did you send a command or used the physical button?
if OH triggered: Did you send UP/DOWN or 0/100%?

Please include the complete sequence in the log

Samir.E · February 23, 2021, 6:07pm

The first log was from when I did not touch the Curtains at all. If I change the Curtains I need to use either UP or DOWN. If I use 0 or 100, it complains:

18:38:31.598 [WARN ] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: Device is not calibrated, use Shelly App to perform initial roller calibration.

That is what I wrote in my initial report, this is what I think is the problem. If it is not calibrated, it really doesn’t know if it is 0 or 100 and that is why it is repeating itself. But that is just a wild guess…

If I do the same on my Window opener (which is calibrated), it looks like this when I open it:

18:45:57.180 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Window' changed from 100.0 to 0
18:45:57.630 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Window' changed from 0 to 100.0
18:46:01.768 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0

I don’t know why the values are repeated like this (100=>0=>100) but that happens with many items. But if I send 100 to the Window, it closes. So closed is 100 and open is 0.

I think it should be the same for the Curtains but since it is not possible to calibrate them (they draw to little power) maybe this is what confuses the binding.

At the time the first log was taken, the Curtains were OPEN. So it should be 0 but I cannot confirm that since it is not possible to check the state like this without calibration.
If I check the Curtains.state anyway, it is 0.0 in both OPEN and CLOSED state. During the time Curtains are moving, the state is 100.0.

When I change the Curtains, I always send a command. No physical button.

Here is the log from a complete sequence: first command UP, waiting to finish, waiting several seconds more then command DOWN. You can see where it happens in the log.

18:59:02.061 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'Curtains' received command UP
18:59:02.066 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item 'Curtains' predicted to become UP
18:59:02.066 [DEBUG] [y.internal.handler.ShellyRelayHandler] - shellyswitch25-68c63af93d76: Roller command/position UP
18:59:02.094 [DEBUG] [y.internal.handler.ShellyRelayHandler] - shellyswitch25-68c63af93d76: Open roller
18:59:02.113 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 0 (type class org.openhab.core.library.types.PercentType).
18:59:02.114 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:02.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:02.323 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28652): {"G":[[0,9103,1],[0,1102,"open"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.49],[0,6101,0],[0,9101,"roller"]]}
18:59:02.324 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"open"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.49],[0,6101,0],[0,9101,"roller"]]} (serial=57618)
18:59:02.331 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
18:59:02.332 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
18:59:02.333 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 100.0 % (type class org.openhab.core.library.types.QuantityType).
18:59:02.335 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0 to 100.0
18:59:02.337 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#state updated with open (type class org.openhab.core.library.types.StringType).
18:59:02.340 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 2 channels updated from CoIoT status, serial=57618
18:59:02.895 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#heartBeat updated with 2021-02-23T18:59:02.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:02.915 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#uptime updated with 8033700 s (type class org.openhab.core.library.types.QuantityType).
18:59:02.915 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-e0980695993a: status.update=8033700, lastUpdate=8033684
18:59:03.305 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:03.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:03.323 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 288465 s (type class org.openhab.core.library.types.QuantityType).
18:59:03.324 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=288465, lastUpdate=288461
18:59:06.543 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:06.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:06.569 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 288468 s (type class org.openhab.core.library.types.QuantityType).
18:59:06.570 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=288468, lastUpdate=288465
18:59:10.159 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28653): {"G":[[0,9103,1],[0,1102,"open"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.36],[0,6101,0],[0,9101,"roller"]]}
18:59:10.162 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:10.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:10.162 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"open"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.36],[0,6101,0],[0,9101,"roller"]]} (serial=57874)
18:59:10.163 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
18:59:10.164 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
18:59:11.318 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28654): {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.36],[0,6101,0],[0,9101,"roller"]]}
18:59:11.319 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:11.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:11.320 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.36],[0,6101,0],[0,9101,"roller"]]} (serial=58130)
18:59:11.320 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
18:59:11.321 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
18:59:11.322 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#state updated with stop (type class org.openhab.core.library.types.StringType).
18:59:11.323 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 1 channels updated from CoIoT status, serial=58130
18:59:12.811 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:12.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:12.831 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 0.0 % (type class org.openhab.core.library.types.QuantityType).
18:59:12.831 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
18:59:12.831 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 288474 s (type class org.openhab.core.library.types.QuantityType).
18:59:12.838 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=288474, lastUpdate=288468
18:59:15.633 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: CoIoT Message from /192.168.1.118:5683 (MID=65214): {"G":[[0,9103,0],[0,1102,"stop"],[0,1103,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,449],[0,6103,"normal"],[0,3104,60.61],[0,6101,0],[0,9101,"roller"]]}
18:59:15.635 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#heartBeat updated with 2021-02-23T18:59:15.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:15.636 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: Serial 50193 was already processed, ignore update
18:59:23.569 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'Curtains' received command DOWN
18:59:23.572 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item 'Curtains' predicted to become DOWN
18:59:23.573 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100
18:59:23.575 [DEBUG] [y.internal.handler.ShellyRelayHandler] - shellyswitch25-68c63af93d76: Roller command/position DOWN
18:59:23.596 [DEBUG] [y.internal.handler.ShellyRelayHandler] - shellyswitch25-68c63af93d76: Closing roller
18:59:23.614 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 0 (type class org.openhab.core.library.types.PercentType).
18:59:23.615 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100 to 0
18:59:23.615 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:23.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:23.821 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28655): {"G":[[0,9103,1],[0,1102,"close"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.36],[0,6101,0],[0,9101,"roller"]]}
18:59:23.823 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"close"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.36],[0,6101,0],[0,9101,"roller"]]} (serial=58386)
18:59:23.826 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
18:59:23.827 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
18:59:23.828 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 100.0 % (type class org.openhab.core.library.types.QuantityType).
18:59:23.829 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0 to 100.0
18:59:23.829 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#state updated with close (type class org.openhab.core.library.types.StringType).
18:59:23.830 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 2 channels updated from CoIoT status, serial=58386
18:59:25.109 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:25.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:25.131 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 288486 s (type class org.openhab.core.library.types.QuantityType).
18:59:25.132 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=288486, lastUpdate=288474
18:59:28.351 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:28.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:28.383 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 288490 s (type class org.openhab.core.library.types.QuantityType).
18:59:28.384 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=288490, lastUpdate=288486
18:59:30.690 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: CoIoT Message from /192.168.1.118:5683 (MID=65215): {"G":[[0,9103,0],[0,1102,"stop"],[0,1103,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,449],[0,6103,"normal"],[0,3104,60.61],[0,6101,0],[0,9101,"roller"]]}
18:59:30.692 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#heartBeat updated with 2021-02-23T18:59:30.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:30.694 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: Serial 50193 was already processed, ignore update
18:59:32.820 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28656): {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.61],[0,6101,0],[0,9101,"roller"]]}
18:59:32.823 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:32.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:32.824 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.61],[0,6101,0],[0,9101,"roller"]]} (serial=58642)
18:59:32.825 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
18:59:32.826 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
18:59:32.827 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#internalTemp updated with 61 °C (type class org.openhab.core.library.types.QuantityType).
18:59:32.828 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#state updated with stop (type class org.openhab.core.library.types.StringType).
18:59:32.828 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 2 channels updated from CoIoT status, serial=58642
18:59:34.633 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:34.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:34.656 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 0.0 % (type class org.openhab.core.library.types.QuantityType).
18:59:34.658 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 288496 s (type class org.openhab.core.library.types.QuantityType).
18:59:34.659 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=288496, lastUpdate=288490
18:59:34.656 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
18:59:34.662 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: Max Internal Temp for device changed to 61
18:59:45.266 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#heartBeat updated with 2021-02-23T18:59:45.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:45.283 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#uptime updated with 8033742 s (type class org.openhab.core.library.types.QuantityType).
18:59:45.285 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#internalTemp updated with 60 °C (type class org.openhab.core.library.types.QuantityType).
18:59:45.286 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-e0980695993a: status.update=8033742, lastUpdate=8033700
18:59:45.703 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: CoIoT Message from /192.168.1.118:5683 (MID=65216): {"G":[[0,9103,0],[0,1102,"stop"],[0,1103,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,449],[0,6103,"normal"],[0,3104,60.49],[0,6101,0],[0,9101,"roller"]]}
18:59:45.705 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: CoIoT Sensor data {"G":[[0,9103,0],[0,1102,"stop"],[0,1103,0],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,449],[0,6103,"normal"],[0,3104,60.49],[0,6101,0],[0,9101,"roller"]]} (serial=50193)
18:59:45.707 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-e0980695993a: 15 CoAP sensor updates received
18:59:45.707 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-e0980695993a: Updating meter1:currentWatts with 0.0
18:59:47.834 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Message from /192.168.1.176:5683 (MID=28657): {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.49],[0,6101,0],[0,9101,"roller"]]}
18:59:47.836 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:47.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:47.837 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: CoIoT Sensor data {"G":[[0,9103,1],[0,1102,"stop"],[0,1103,-1],[0,2101,0],[0,2102,""],[0,2103,0],[0,2201,0],[0,2202,""],[0,2203,0],[0,4102,0.00],[0,4104,0],[0,6103,"normal"],[0,3104,60.49],[0,6101,0],[0,9101,"roller"]]} (serial=58642)
18:59:47.838 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 15 CoAP sensor updates received
18:59:47.839 [DEBUG] [lly.internal.coap.ShellyCoIoTVersion2] - shellyswitch25-68c63af93d76: Updating meter1:currentWatts with 0.0
18:59:47.840 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#internalTemp updated with 60 °C (type class org.openhab.core.library.types.QuantityType).
18:59:47.841 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 100.0 % (type class org.openhab.core.library.types.QuantityType).
18:59:47.841 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
18:59:47.841 [DEBUG] [helly.internal.coap.ShellyCoapHandler] - shellyswitch25-68c63af93d76: 2 channels updated from CoIoT status, serial=58642
18:59:48.300 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'MQTTCommandsIN' changed from Humidity: 67 to HUMID FAN OFF, setting humidFanON = 0.
18:59:48.302 [INFO ] [org.openhab.core.model.script.rules  ] - MQTT received: HUMID FAN OFF, setting humidFanON = 0.
18:59:48.303 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'MQTTCommandsIN' changed from HUMID FAN OFF, setting humidFanON = 0. to Shutting OFF fan, both humidFanON and smellyFanON = -1
18:59:48.305 [INFO ] [org.openhab.core.model.script.rules  ] - MQTT received: Shutting OFF fan, both humidFanON and smellyFanON = -1
18:59:48.518 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#heartBeat updated with 2021-02-23T18:59:48.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:48.575 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-e0980695993a: Channel device#uptime updated with 8033745 s (type class org.openhab.core.library.types.QuantityType).
18:59:48.576 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-e0980695993a: status.update=8033745, lastUpdate=8033742
18:59:49.934 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#heartBeat updated with 2021-02-23T18:59:49.000+0100 (type class org.openhab.core.library.types.DateTimeType).
18:59:49.959 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel roller#control updated with 0.0 % (type class org.openhab.core.library.types.QuantityType).
18:59:49.960 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 100.0 to 0.0
18:59:49.961 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#uptime updated with 288511 s (type class org.openhab.core.library.types.QuantityType).
18:59:49.964 [DEBUG] [elly.internal.util.ShellyChannelCache] - shellyswitch25-68c63af93d76: Channel device#internalTemp updated with 61 °C (type class org.openhab.core.library.types.QuantityType).
18:59:49.965 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyswitch25-68c63af93d76: status.update=288511, lastUpdate=288496
openhab>

Also as text:
Shelly_Log_2.txt (20.5 KB)

Did you get answers to your questions as requested?

markus7017 · February 23, 2021, 7:00pm

I updated the build to include some more debug, I found one missing channel update, but this shouldn’t affect you, nevertheless, please run it again and provide log

markus7017 · February 23, 2021, 8:20pm

fyi: I just got a firmware beta, which implements CoIoT singlecast for Motion, this would solve the multicast routing problem having OH and devices on different IP networks. I don’t know if this will be available to all devices or only the new generation

Samir.E · February 24, 2021, 7:46am

Hello,
as requested.

Note: I only upgraded the Shelly binding in the AddOns folder by replacing it with the new dev build.
Sequence: stopping openHAB, replacing the file, starting openHAB.

I did not delete the things and added them again. If I need to do that, please tell me.

New error in normal log (not debug):

08:35:24.719 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Curtains' changed from 0.0 to 100.0
08:35:24.726 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler of thing shelly:shelly25-roller:Curtains tried checking if channel roller#eventCount2 is linked although the handler was already disposed.
08:35:24.728 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler of thing shelly:shelly25-roller:Curtains tried checking if channel roller#lastEvent1 is linked although the handler was already disposed.
08:35:24.729 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler of thing shelly:shelly25-roller:Curtains tried checking if channel roller#lastEvent2 is linked although the handler was already disposed.
08:35:24.734 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler of thing shelly:shelly25-roller:Curtains tried checking if channel roller#state is linked although the handler was already disposed.
08:35:24.735 [WARN ] [b.core.thing.binding.BaseThingHandler] - Handler ShellyRelayHandler of thing shelly:shelly25-roller:Curtains tried checking if channel roller#stopReason is linked although the handler was already disposed.

New log with debug enabled. Sequence: Command: DOWN. Waiting. Command UP. Waiting. Copy log.

Shelly_Log_3.txt (58.7 KB)

markus7017 · February 24, 2021, 7:49pm

stop oh, clean cache, start oh
recreate log

markus7017 · February 24, 2021, 7:54pm

I habe very good news: Allterco provided firmware 1.0.9 (pre-final), which includes a new config option for CoIoT/Coap: Status update via

IP Multicast (as of now, known issues in routed environments)
Peer Mode - updates will be send as regular UDP traffic (single cast)

The new peer mode limits the number of recipients on the network to 1 (OH), but fixes the issues with seperated/routed networks, e.g. passing a VPN connection is already tested.

The ShellyManger already integrates setting of CoIoT mode as action so you could udate your Shellys from within the Overview page.

Samir.E · February 25, 2021, 8:15am

Done. Log attached here:
Shelly_Log_4.txt (35.5 KB)

markus7017 · February 25, 2021, 7:55pm

ok, I found something

Does sending UP/DOWN behave differently than sending 0/100 to control channel?
Please try it first, then install the updated binding an try UP/DOWN + 0/100 again
and send log

markus7017 · February 25, 2021, 8:11pm

Update on Unicast: Thiis is important to Motion users when installing the upcoming firmware

Shelly implemented a new feature: CoIoT Unicast (instead of Multicast)
This allows to set a dedicated IP address receiving the updates rather than broadcasting using Multicast on the local network. This is problematic when having the OH gateway and devices on different IP subnets or connected through a VPN. Using Multicast prevents the packets passing the router without special configurations. Switching to Unicast makes those packets routable.

The Shelly Motion only supports Unicast for energy efficiency reasons. This means you HAVE TO set the COIOT PEER address in the UI
Other devices will support this feature beginning with firmware 1.10, Unicast beside Multicast

The binding will support both modes. The user has to make sure that devices are properly configured.

markus7017 · February 25, 2021, 9:22pm

problem motionTime should fixed with latest build. In addition 2 new channels

device#sensorSleepTime = SLEEP TIME in the UI
sensor#motionActive: Indicates if the sensor is active and scanning
both are advanced channels