Shelly Binding

curlyel · May 10, 2020, 9:21am

Hi Markus,
new toy - new questions
I just got a couple of Shelly-DW’s and trying them to integrate into openHAB. Inclusion and firmware update went well. Now the sensors are on

Firmware: v1.6.5

Binding version is a quite recent jar (5th of May):

12 x Active x 80 x 2.5.5.202005052017 x openHAB Add-ons :: Bundles :: Shelly Binding

When the DW first got in touch with Shelly binding, I woke them up to let the binding initialize.

Basically, the DW sensor works: It reports OPEN/CLOSED and vibration and after some time the battery level. But some other values (wakeupReason, lastError, lastUpdate, uptime, wifiSignal) are missing.

Are they not available via Coap? What am I doing wrong?

Here is the thing:

Thing shelly:shellydw:tuer "Eingangstür" @ "somewhere" [ deviceIp="192.168.9.17", eventsCoIoT=true ]

and the items:

Group   gShellyTuer
Contact ShellyTuer              "Eingangstür [%s]"                                      (gShellyTuer, gMapdb)   {channel="shelly:shellydw:tuer:sensors#state" }
Switch ShellyTuer_vibration     "Erschütterung"                                         (gShellyTuer)           {channel="shelly:shellydw:tuer:sensors#vibration" }
String ShellyTuer_reason        "Aufwachgrund [%s]"                                     (gShellyTuer)           {channel="shelly:shellydw:tuer:sensors#wakeupReason" }
DateTime ShellyTuer_last        "Lebenszeichen [%1$ta, %1$te. %1$tb %1$tk:%1$tM]"       (gShellyTuer)           {channel="shelly:shellydw:tuer:sensors#lastUpdate" }
String ShellyTuer_error         "Letzter Fehler [%s]"                                   (gShellyTuer)           {channel="shelly:shellydw:tuer:sensors#lastError" }
Number ShellyTuer_batt          "Batterie"                                              (gShellyTuer)           {channel="shelly:shellydw:tuer:battery#batteryLevel" }
Number ShellyTuer_uptime        "Laufzeit [%d s]"               <clock_grey>            (gShellyTuer)           {channel="shelly:shellydw:tuer:device#uptime"}
Number ShellyTuer_rssi          "Signalstärke [%d]"             <wifi>                  (gShellyTuer)           {channel="shelly:shellydw:tuer:device#wifiSignal"}

This is the log from some OPEN/CLOSED cycles:

2020-05-10 11:00:27.216 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=25801): {"G":[[0,66,200],[0,55,1],[0,77,98],[0,88,-1],[0,99,0]]}
2020-05-10 11:00:27.220 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Duplicate serial 256 will be processed, because payload is different: {"G":[[0,66,200],[0,55,1],[0,77,98],[0,88,-1],[0,99,0]]} vs. {"G":[[0,66,164],[0,55,0],[0,77,98],[0,88,-1],[0,99,0]]}
2020-05-10 11:00:27.225 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,200],[0,55,1],[0,77,98],[0,88,-1],[0,99,0]]}
2020-05-10 11:00:27.230 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-10 11:00:27.256 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with OPEN (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-10 11:00:27.272 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-10 11:02:48.229 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=34478): {"G":[[0,66,280],[0,55,0],[0,77,98],[0,88,-1],[0,99,0]]}
2020-05-10 11:02:48.233 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Duplicate serial 256 will be processed, because payload is different: {"G":[[0,66,280],[0,55,0],[0,77,98],[0,88,-1],[0,99,0]]} vs. {"G":[[0,66,200],[0,55,1],[0,77,98],[0,88,-1],[0,99,0]]}
2020-05-10 11:02:48.236 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,280],[0,55,0],[0,77,98],[0,88,-1],[0,99,0]]}
2020-05-10 11:02:48.240 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-10 11:02:48.251 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with CLOSED (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-10 11:02:48.256 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-10 11:03:52.740 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=40787): {"G":[[0,66,319],[0,55,0],[0,77,98],[0,88,-1],[0,99,1]]}
2020-05-10 11:03:52.744 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Duplicate serial 256 will be processed, because payload is different: {"G":[[0,66,319],[0,55,0],[0,77,98],[0,88,-1],[0,99,1]]} vs. {"G":[[0,66,280],[0,55,0],[0,77,98],[0,88,-1],[0,99,0]]}
2020-05-10 11:03:52.747 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,319],[0,55,0],[0,77,98],[0,88,-1],[0,99,1]]}
2020-05-10 11:03:52.751 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-10 11:03:52.780 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#vibration updated with ON (type class org.eclipse.smarthome.core.library.types.OnOffType).
2020-05-10 11:03:52.789 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-10 11:04:13.259 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=5104): {"G":[[0,66,212],[0,55,0],[0,77,98],[0,88,-1],[0,99,1]]}
2020-05-10 11:04:13.266 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Duplicate serial 256 will be processed, because payload is different: {"G":[[0,66,212],[0,55,0],[0,77,98],[0,88,-1],[0,99,1]]} vs. {"G":[[0,66,319],[0,55,0],[0,77,98],[0,88,-1],[0,99,1]]}
2020-05-10 11:04:13.272 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,212],[0,55,0],[0,77,98],[0,88,-1],[0,99,1]]}
2020-05-10 11:04:13.278 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-10 11:05:14.027 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=27756): {"G":[[0,66,296],[0,55,1],[0,77,98],[0,88,-1],[0,99,0]]}
2020-05-10 11:05:14.031 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Duplicate serial 256 will be processed, because payload is different: {"G":[[0,66,296],[0,55,1],[0,77,98],[0,88,-1],[0,99,0]]} vs. {"G":[[0,66,212],[0,55,0],[0,77,98],[0,88,-1],[0,99,1]]}
2020-05-10 11:05:14.034 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,296],[0,55,1],[0,77,98],[0,88,-1],[0,99,0]]}
2020-05-10 11:05:14.038 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-10 11:05:14.051 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with OPEN (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-10 11:05:14.064 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#vibration updated with OFF (type class org.eclipse.smarthome.core.library.types.OnOffType).
2020-05-10 11:05:14.071 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 2 channels updated from CoIoT status

Any thoughts?

Nickthegr8 · May 10, 2020, 5:32pm

Hello,
i tried your rule to get the things status when they change,
the problem I am facing is that the shelly plug thing updates from unkown to online but doesn’t update from online to unknown in case i unplug any of the shellys,
I tried configuring through Paper UI and through .things file but as you can see in the screenshot the binding keeps showing the devices as always online unless i restart.
Plz let me know what can i provide to be able to trace the issue,

2020-05-10 20:25:08.884 [vent.ItemStateChangedEvent] - CPU_Uptime changed from 37.8 to 40.4

==> /var/log/openhab2/openhab.log <==

2020-05-10 20:27:44.760 [WARN ] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Event triggered: RESTARTED

==> /var/log/openhab2/events.log <==

2020-05-10 20:27:44.780 [vent.ChannelTriggeredEvent] - shelly:shellyplug:7bcca9:device#alarm triggered RESTARTED

2020-05-10 20:27:44.819 [me.event.ThingUpdatedEvent] - Thing 'shelly:shellyplug:7bcca9' has been updated.

2020-05-10 20:27:48.989 [vent.ItemStateChangedEvent] - CPU_Uptime changed from 40.4 to 43.1

2020-05-10 20:27:49.004 [vent.ItemStateChangedEvent] - Network_DataSent changed from 12 to 13

2020-05-10 20:27:49.253 [vent.ItemStateChangedEvent] - Sensor_CPUTemp changed from 34.3 to 34.9

2020-05-10 20:28:07.357 [me.event.ThingUpdatedEvent] - Thing 'shelly:shellyplug:7bcca9' has been updated.

2020-05-10 20:28:38.325 [vent.ItemStateChangedEvent] - Hotwater_RSSI changed from 66 to 64

2020-05-10 20:28:38.335 [vent.ItemStateChangedEvent] - Hotwater_Uptime changed from 0T00:30:11 to 0T00:35:11```

at 20:28 i unplugged the shelly and it still shows online

thanks,

hmerk · May 10, 2020, 5:52pm

The binding will not set the thing back to unknown, just offline - communication error.
Unknown can only be set at a restart.

Nickthegr8 · May 10, 2020, 6:01pm

it doesn’t even set it to offline, i just stays online that’s what i was trying to relay

Nickthegr8 · May 10, 2020, 6:17pm

I uninstalled the binding, restarted , reconfigured the shelly plug and still the thing state doesn’t go into offline mode. P.S Unknown was set at restart and Online sets up also only offline doesn’t show… can i help with a trace or something plz let me know,

thanks again,

markus7017 · May 10, 2020, 8:13pm

Did you tried to link them using PaperUI?

markus7017 · May 10, 2020, 8:17pm

yes, set the log level to TRACE
keep in mind, it will take 1min util the next poll turns into OFFLINE/COMMUNICATION_ERROR

curlyel · May 11, 2020, 3:49am

No. Does this make any difference?

Usually I prefer having everything configured in files…

Nickthegr8 · May 11, 2020, 11:14am

Hello Markus,

Please find below the log, i’ve set the binding log to TRACE

2020-05-11 14:04:52.874 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Updating status

2020-05-11 14:04:52.877 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:04:53.334 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.13","rssi":-69},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:04","unixtime":1589205893,"serial":1,"has_update":false,"mac":"A81B6A7BCCA9","relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"overpower":false}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589205893,"counters":[0.000, 0.000, 0.000],"total":0}],"update":{"status":"idle","has_update":false,"new_version":"20200408-085459/v1.6.5@b075f0bf","old_version":"20200408-085459/v1.6.5@b075f0bf"},"ram_total":59672,"ram_free":38008,"fs_size":83081,"fs_free":16817,"uptime":2030}

2020-05-11 14:04:53.337 [TRACE] [.internal.handler.ShellyRelayHandler] - shellyplug-7bcca9: Updating 1 relay(s)

2020-05-11 14:04:53.340 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status/relay/0

2020-05-11 14:04:53.764 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.13","rssi":-72},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:04","unixtime":1589205894,"serial":1,"has_update":false,"mac":"A81B6A7BCCA9","relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"overpower":false}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589205894,"counters":[0.000, 0.000, 0.000],"total":0}],"update":{"status":"idle","has_update":false,"new_version":"20200408-085459/v1.6.5@b075f0bf","old_version":"20200408-085459/v1.6.5@b075f0bf"},"ram_total":59672,"ram_free":38008,"fs_size":83081,"fs_free":16817,"uptime":2030}

2020-05-11 14:04:53.769 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Updating 1 standard meter(s)

2020-05-11 14:05:00.935 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyplug-7bcca9: CoIoT Message from /10.0.0.13:5683 (MID=24086): {"G":[[0,111,0.000000],[0,112,0],[0,211,0.000],[0,212,0.000],[0,213,0.000],[0,214,0]]}

2020-05-11 14:05:00.942 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyplug-7bcca9: Serial 256 was already processed, ignore update

2020-05-11 14:05:02.631 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating status

2020-05-11 14:05:02.633 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:05:05.737 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: API Timeout, retry #3 (Device unreachable or API Timeout (GET http://10.0.0.15/status))

2020-05-11 14:05:05.739 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:05:08.857 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: API Timeout, retry #4 (Device unreachable or API Timeout (GET http://10.0.0.15/status))

2020-05-11 14:05:08.860 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:05:11.977 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Ignore API Timeout, retry later

2020-05-11 14:05:53.780 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Updating status

2020-05-11 14:05:53.783 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:05:58.786 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: API Timeout, retry #1 (Device unreachable or API Timeout (GET http://10.0.0.13/status))

2020-05-11 14:05:58.789 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:06:03.796 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: API Timeout, retry #2 (Device unreachable or API Timeout (GET http://10.0.0.13/status))

2020-05-11 14:06:03.866 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:06:08.788 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Ignore API Timeout, retry later

2020-05-11 14:06:11.982 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating status

2020-05-11 14:06:11.987 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:06:15.097 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: API Timeout, retry #5 (Device unreachable or API Timeout (GET http://10.0.0.15/status))

2020-05-11 14:06:15.099 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:06:18.217 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: API Timeout, retry #6 (Device unreachable or API Timeout (GET http://10.0.0.15/status))

2020-05-11 14:06:18.220 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:06:21.337 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Ignore API Timeout, retry later

2020-05-11 14:07:08.794 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Updating status

2020-05-11 14:07:08.797 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:07:11.897 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: API Timeout, retry #3 (Device unreachable or API Timeout (GET http://10.0.0.13/status))

2020-05-11 14:07:11.899 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:07:15.017 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: API Timeout, retry #4 (Device unreachable or API Timeout (GET http://10.0.0.13/status))

2020-05-11 14:07:15.020 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:07:18.137 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Ignore API Timeout, retry later

2020-05-11 14:07:21.342 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating status

2020-05-11 14:07:21.347 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:07:24.456 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: API Timeout, retry #7 (Device unreachable or API Timeout (GET http://10.0.0.15/status))

2020-05-11 14:07:24.458 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:07:27.577 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: API Timeout, retry #8 (Device unreachable or API Timeout (GET http://10.0.0.15/status))

2020-05-11 14:07:27.580 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:07:30.697 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Ignore API Timeout, retry later

2020-05-11 14:08:18.150 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Updating status

2020-05-11 14:08:18.153 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:08:21.257 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: API Timeout, retry #5 (Device unreachable or API Timeout (GET http://10.0.0.13/status))

2020-05-11 14:08:21.259 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:08:24.377 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: API Timeout, retry #6 (Device unreachable or API Timeout (GET http://10.0.0.13/status))

2020-05-11 14:08:24.380 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:08:27.497 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Ignore API Timeout, retry later

2020-05-11 14:08:30.703 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating status

2020-05-11 14:08:30.705 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:08:33.817 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: API Timeout, retry #9 (Device unreachable or API Timeout (GET http://10.0.0.15/status))

2020-05-11 14:08:33.819 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:08:36.937 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: API Timeout, retry #10 (Device unreachable or API Timeout (GET http://10.0.0.15/status))

2020-05-11 14:08:36.939 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:08:40.057 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Ignore API Timeout, retry later

2020-05-11 14:08:53.589 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyplug-7bcca9: CoIoT Message from /10.0.0.13:5683 (MID=4492): {"G":[[0,111,0.000000],[0,112,0],[0,211,0.000],[0,212,0.000],[0,213,0.000],[0,214,0]]}

2020-05-11 14:08:53.591 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyplug-7bcca9: Serial 256 was already processed, ignore update

2020-05-11 14:09:00.143 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c: CoIoT Message from /10.0.0.15:5683 (MID=26723): {"G":[[0,112,0],[0,122,0],[0,111,0.000000],[0,118,0],[0,128,0],[0,211,0.000],[0,212,0.000],[0,213,0.000],[0,214,0]]}

2020-05-11 14:09:00.146 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c: CoIoT Sensor data {"G":[[0,112,0],[0,122,0],[0,111,0.000000],[0,118,0],[0,128,0],[0,211,0.000],[0,212,0.000],[0,213,0.000],[0,214,0]]}

2020-05-11 14:09:00.150 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c: 9 CoAP sensor updates received

2020-05-11 14:09:00.152 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c:  Sensor value[0]: id=112, Value=0.0 (State, Type=S, Range=0/1, Link=0: Relay0)

2020-05-11 14:09:00.155 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c:  Sensor value[1]: id=122, Value=0.0 (State, Type=S, Range=0/1, Link=1: Relay1)

2020-05-11 14:09:00.158 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c:  Sensor value[2]: id=111, Value=0.0 (Power, Type=P, Range=0/3680, Link=2: Device)

2020-05-11 14:09:00.162 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c:  Sensor value[3]: id=118, Value=0.0 (Input, Type=S, Range=0(off)/1(on)/2(longpush), Link=0: Relay0)

2020-05-11 14:09:00.164 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c:  Sensor value[4]: id=128, Value=0.0 (Input, Type=S, Range=0(off)/1(on)/2(longpush), Link=1: Relay1)

2020-05-11 14:09:00.167 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c:  Sensor value[5]: id=211, Value=0.0 (Energy counter 0 [W-min], Type=S, Range=null, Link=2: Device)

2020-05-11 14:09:00.170 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c:  Sensor value[6]: id=212, Value=0.0 (Energy counter 1 [W-min], Type=S, Range=null, Link=2: Device)

2020-05-11 14:09:00.173 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c:  Sensor value[7]: id=213, Value=0.0 (Energy counter 2 [W-min], Type=S, Range=null, Link=2: Device)

2020-05-11 14:09:00.176 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shellyswitch-32b77c:  Sensor value[8]: id=214, Value=0.0 (Energy counter total [W-min], Type=S, Range=null, Link=2: Device)

2020-05-11 14:09:01.060 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating status

2020-05-11 14:09:01.063 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:09:01.862 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.15","rssi":-70},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:09","unixtime":1589206142,"serial":1,"has_update":false,"mac":"84F3EB32B77C","relays":[{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true},{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206142,"counters":[0.000, 0.000, 0.000],"total":0}],"inputs":[{"input":0},{"input":0}],"update":{"status":"unknown","has_update":false,"new_version":"","old_version":"20200309-104038/v1.6.0@43056d58"},"ram_total":49992,"ram_free":39288,"fs_size":233681,"fs_free":152608,"voltage":232.49,"uptime":8}

2020-05-11 14:09:01.868 [TRACE] [.internal.handler.ShellyRelayHandler] - shellyswitch-32b77c: Updating 2 relay(s)

2020-05-11 14:09:01.871 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status/relay/0

2020-05-11 14:09:01.952 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.15","rssi":-70},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:09","unixtime":1589206142,"serial":1,"has_update":false,"mac":"84F3EB32B77C","relays":[{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true},{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206142,"counters":[0.000, 0.000, 0.000],"total":0}],"inputs":[{"input":0},{"input":0}],"update":{"status":"unknown","has_update":false,"new_version":"","old_version":"20200309-104038/v1.6.0@43056d58"},"ram_total":49992,"ram_free":39108,"fs_size":233681,"fs_free":152608,"voltage":232.59,"uptime":8}

2020-05-11 14:09:01.964 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating 1 standard meter(s)

2020-05-11 14:09:01.973 [WARN ] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Event triggered: RESTARTED

==> /var/log/openhab2/events.log <==

2020-05-11 14:09:01.980 [vent.ChannelTriggeredEvent] - shelly:shelly2-relay:32b77c:device#alarm triggered RESTARTED

==> /var/log/openhab2/openhab.log <==

2020-05-11 14:09:02.032 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: 0 more updates requested

==> /var/log/openhab2/events.log <==

2020-05-11 14:09:02.049 [me.event.ThingUpdatedEvent] - Thing 'shelly:shelly2-relay:32b77c' has been updated.

==> /var/log/openhab2/openhab.log <==

2020-05-11 14:09:27.503 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Updating status

2020-05-11 14:09:27.506 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:09:28.413 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.13","rssi":-70},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:09","unixtime":1589206168,"serial":1,"has_update":false,"mac":"A81B6A7BCCA9","relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"overpower":false}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206168,"counters":[0.000, 0.000, 0.000],"total":0}],"update":{"status":"idle","has_update":false,"new_version":"20200408-085459/v1.6.5@b075f0bf","old_version":"20200408-085459/v1.6.5@b075f0bf"},"ram_total":59672,"ram_free":38008,"fs_size":83081,"fs_free":16817,"uptime":45}

2020-05-11 14:09:28.417 [TRACE] [.internal.handler.ShellyRelayHandler] - shellyplug-7bcca9: Updating 1 relay(s)

2020-05-11 14:09:28.420 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status/relay/0

2020-05-11 14:09:28.849 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.13","rssi":-70},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:09","unixtime":1589206169,"serial":1,"has_update":false,"mac":"A81B6A7BCCA9","relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"overpower":false}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206169,"counters":[0.000, 0.000, 0.000],"total":0}],"update":{"status":"idle","has_update":false,"new_version":"20200408-085459/v1.6.5@b075f0bf","old_version":"20200408-085459/v1.6.5@b075f0bf"},"ram_total":59672,"ram_free":38008,"fs_size":83081,"fs_free":16817,"uptime":46}

2020-05-11 14:09:28.855 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Updating 1 standard meter(s)

2020-05-11 14:09:28.861 [WARN ] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Event triggered: RESTARTED

2020-05-11 14:09:28.865 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellyplug-7bcca9: Channel device#alarm updated with RESTARTED (type class org.eclipse.smarthome.core.library.types.StringType).

==> /var/log/openhab2/events.log <==

2020-05-11 14:09:28.866 [vent.ChannelTriggeredEvent] - shelly:shellyplug:7bcca9:device#alarm triggered RESTARTED

2020-05-11 14:09:28.899 [me.event.ThingUpdatedEvent] - Thing 'shelly:shellyplug:7bcca9' has been updated.

==> /var/log/openhab2/openhab.log <==

2020-05-11 14:09:41.038 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating status

2020-05-11 14:09:41.041 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:09:41.138 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.15","rssi":-71},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:09","unixtime":1589206181,"serial":1,"has_update":false,"mac":"84F3EB32B77C","relays":[{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true},{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206181,"counters":[0.000, 0.000, 0.000],"total":0}],"inputs":[{"input":0},{"input":0}],"update":{"status":"idle","has_update":false,"new_version":"20200309-104038/v1.6.0@43056d58","old_version":"20200309-104038/v1.6.0@43056d58"},"ram_total":49992,"ram_free":38832,"fs_size":233681,"fs_free":152608,"voltage":232.39,"uptime":47}

2020-05-11 14:09:41.143 [TRACE] [.internal.handler.ShellyRelayHandler] - shellyswitch-32b77c: Updating 2 relay(s)

2020-05-11 14:09:41.147 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status/relay/0

2020-05-11 14:09:41.256 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.15","rssi":-71},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:09","unixtime":1589206181,"serial":1,"has_update":false,"mac":"84F3EB32B77C","relays":[{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true},{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206181,"counters":[0.000, 0.000, 0.000],"total":0}],"inputs":[{"input":0},{"input":0}],"update":{"status":"idle","has_update":false,"new_version":"20200309-104038/v1.6.0@43056d58","old_version":"20200309-104038/v1.6.0@43056d58"},"ram_total":49992,"ram_free":38656,"fs_size":233681,"fs_free":152608,"voltage":232.39,"uptime":47}

2020-05-11 14:09:41.266 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating 1 standard meter(s)

2020-05-11 14:09:54.224 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyswitch-32b77c: Shelly device discovered with empty IP address (service-name=[ServiceInfoImpl@12708463 name: 'shellyswitch-32B77C._http._tcp.local.' address: '(null):0' status: 'DNS: JmDNS-/10.0.0.3 [/10.0.0.3] state: probing 1 task: null', has NO data

	shellyswitch-32B77C._http._tcp.local.: ])

2020-05-11 14:09:54.232 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyswitch-32b77c: Shelly device discovered: IP-Adress=10.0.0.15, type=shellyswitch

2020-05-11 14:09:54.238 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/settings

2020-05-11 14:09:54.483 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP Response 200: {"device":{"type":"SHSW-21","mac":"84F3EB32B77C","hostname":"shellyswitch-32B77C","num_outputs":2, "num_meters":1, "num_rollers":1},"wifi_ap":{"enabled":false,"ssid":"shellyswitch-32B77C","key":""},"wifi_sta":{"enabled":true,"ssid":"NickyGhz","ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"10.0.0.3:1883","user":"openhabian","id":"shellyswitch-32B77C","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":0,"retain":false,"update_period":30},"coiot": {"update_period":15},"sntp":{"server":"time.google.com","enabled":true},"login":{"enabled":false,"unprotected":false,"username":"admin","password":"admin"},"pin_code":"FY5DHu","name":"","fw":"20200309-104038/v1.6.0@43056d58","factory_reset_from_switch":true,"discoverable":false,"build_info":{"build_id":"20200309-104038/v1.6.0@43056d58","build_timestamp":"2020-03-09T10:40:38Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Asia/Beirut","lat":33.893799,"lng":35.501801,"tzautodetect":true,"tz_utc_offset":10800,"tz_dst":true,"tz_dst_auto":true,"time":"14:09","unixtime":1589206194,"hwinfo":{"hw_revision":"prod-2018-10c", "batch_id":5},"mode":"relay","max_power":1840,"longpush_time":800,"relays":[{"name":null,"ison":false,"has_timer":false,"overpower":false,"default_state":"off","btn_type":"toggle","btn_reverse":0,"auto_on":0.00,"auto_off":600.00,"btn_on_url":null,"btn_off_url":null,"out_on_url":null,"out_off_url":null,"longpush_url":null,"shortpush_url":null,"schedule":true,"schedule_rules":[]},{"name":null,"ison":false,"has_timer":false,"overpower":false,"default_state":"off","btn_type":"edge","btn_reverse":0,"auto_on":0.00,"auto_off":0.00,"btn_on_url":null,"btn_off_url":null,"out_on_url":null,"out_off_url":null,"longpush_url":null,"shortpush_url":null,"schedule":false,"schedule_rules":[]}],"rollers":[{"maxtime":20.00,"maxtime_open":20.00,"maxtime_close":20.00,"default_state":"stop","swap":false,"swap_inputs":false,"input_mode":"openclose","button_type":"toggle","btn_reverse":0,"state":"stop","power":0.00,"is_valid":true,"safety_switch":false,"roller_open_url":null,"roller_close_url":null,"roller_stop_url":null,"schedule":false,"schedule_rules":[],"obstacle_mode":"disabled","obstacle_action":"stop","obstacle_power":200,"obstacle_delay":1,"safety_mode":"while_opening","safety_action":"stop","safety_allowed_on_trigger":"none","off_power":2,"positioning":true}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206194,"counters":[0.000, 0.000, 0.000],"total":0}]}

2020-05-11 14:09:54.505 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyswitch-32b77c: Shelly settings : {"device":{"type":"SHSW-21","mac":"84F3EB32B77C","hostname":"shellyswitch-32B77C","num_outputs":2, "num_meters":1, "num_rollers":1},"wifi_ap":{"enabled":false,"ssid":"shellyswitch-32B77C","key":""},"wifi_sta":{"enabled":true,"ssid":"NickyGhz","ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"10.0.0.3:1883","user":"openhabian","id":"shellyswitch-32B77C","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":0,"retain":false,"update_period":30},"coiot": {"update_period":15},"sntp":{"server":"time.google.com","enabled":true},"login":{"enabled":false,"unprotected":false,"username":"admin","password":"admin"},"pin_code":"FY5DHu","name":"","fw":"20200309-104038/v1.6.0@43056d58","factory_reset_from_switch":true,"discoverable":false,"build_info":{"build_id":"20200309-104038/v1.6.0@43056d58","build_timestamp":"2020-03-09T10:40:38Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Asia/Beirut","lat":33.893799,"lng":35.501801,"tzautodetect":true,"tz_utc_offset":10800,"tz_dst":true,"tz_dst_auto":true,"time":"14:09","unixtime":1589206194,"hwinfo":{"hw_revision":"prod-2018-10c", "batch_id":5},"mode":"relay","max_power":1840,"longpush_time":800,"relays":[{"name":null,"ison":false,"has_timer":false,"overpower":false,"default_state":"off","btn_type":"toggle","btn_reverse":0,"auto_on":0.00,"auto_off":600.00,"btn_on_url":null,"btn_off_url":null,"out_on_url":null,"out_off_url":null,"longpush_url":null,"shortpush_url":null,"schedule":true,"schedule_rules":[]},{"name":null,"ison":false,"has_timer":false,"overpower":false,"default_state":"off","btn_type":"edge","btn_reverse":0,"auto_on":0.00,"auto_off":0.00,"btn_on_url":null,"btn_off_url":null,"out_on_url":null,"out_off_url":null,"longpush_url":null,"shortpush_url":null,"schedule":false,"schedule_rules":[]}],"rollers":[{"maxtime":20.00,"maxtime_open":20.00,"maxtime_close":20.00,"default_state":"stop","swap":false,"swap_inputs":false,"input_mode":"openclose","button_type":"toggle","btn_reverse":0,"state":"stop","power":0.00,"is_valid":true,"safety_switch":false,"roller_open_url":null,"roller_close_url":null,"roller_stop_url":null,"schedule":false,"schedule_rules":[],"obstacle_mode":"disabled","obstacle_action":"stop","obstacle_power":200,"obstacle_delay":1,"safety_mode":"while_opening","safety_action":"stop","safety_allowed_on_trigger":"none","off_power":2,"positioning":true}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206194,"counters":[0.000, 0.000, 0.000],"total":0}]}

2020-05-11 14:09:54.508 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyswitch-32b77c: thingType=shellyswitch, deviceType=SHSW-21, mode=relay

2020-05-11 14:09:54.512 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyswitch-32b77c: Adding Shelly thing, UID=shelly:shelly2-relay:32b77c

2020-05-11 14:10:28.900 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Updating status

2020-05-11 14:10:28.902 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status

2020-05-11 14:10:29.309 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.13","rssi":-67},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:10","unixtime":1589206229,"serial":1,"has_update":false,"mac":"A81B6A7BCCA9","relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"overpower":false}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206229,"counters":[0.000, 0.000, 0.000],"total":0}],"update":{"status":"idle","has_update":false,"new_version":"20200408-085459/v1.6.5@b075f0bf","old_version":"20200408-085459/v1.6.5@b075f0bf"},"ram_total":59672,"ram_free":38008,"fs_size":83081,"fs_free":16817,"uptime":106}

2020-05-11 14:10:29.313 [TRACE] [.internal.handler.ShellyRelayHandler] - shellyplug-7bcca9: Updating 1 relay(s)

2020-05-11 14:10:29.317 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP GET for http://10.0.0.13/status/relay/0

2020-05-11 14:10:30.111 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyplug-7bcca9: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.13","rssi":-66},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:10","unixtime":1589206230,"serial":1,"has_update":false,"mac":"A81B6A7BCCA9","relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"overpower":false}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206230,"counters":[0.000, 0.000, 0.000],"total":0}],"update":{"status":"idle","has_update":false,"new_version":"20200408-085459/v1.6.5@b075f0bf","old_version":"20200408-085459/v1.6.5@b075f0bf"},"ram_total":59672,"ram_free":38008,"fs_size":83081,"fs_free":16817,"uptime":107}

2020-05-11 14:10:30.120 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyplug-7bcca9: Updating 1 standard meter(s)

2020-05-11 14:10:41.277 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating status

2020-05-11 14:10:41.281 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status

2020-05-11 14:10:41.816 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.15","rssi":-71},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:10","unixtime":1589206242,"serial":1,"has_update":false,"mac":"84F3EB32B77C","relays":[{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true},{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206242,"counters":[0.000, 0.000, 0.000],"total":0}],"inputs":[{"input":0},{"input":0}],"update":{"status":"idle","has_update":false,"new_version":"20200309-104038/v1.6.0@43056d58","old_version":"20200309-104038/v1.6.0@43056d58"},"ram_total":49992,"ram_free":38836,"fs_size":233681,"fs_free":152608,"voltage":232.18,"uptime":108}

2020-05-11 14:10:41.820 [TRACE] [.internal.handler.ShellyRelayHandler] - shellyswitch-32b77c: Updating 2 relay(s)

2020-05-11 14:10:41.824 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP GET for http://10.0.0.15/status/relay/0

2020-05-11 14:10:41.917 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyswitch-32b77c: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"NickyGhz","ip":"10.0.0.15","rssi":-71},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:10","unixtime":1589206242,"serial":1,"has_update":false,"mac":"84F3EB32B77C","relays":[{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true},{"ison":false,"has_timer":false,"timer_remaining":0,"overpower":false,"is_valid":true}],"meters":[{"power":0.00,"is_valid":true,"timestamp":1589206242,"counters":[0.000, 0.000, 0.000],"total":0}],"inputs":[{"input":0},{"input":0}],"update":{"status":"idle","has_update":false,"new_version":"20200309-104038/v1.6.0@43056d58","old_version":"20200309-104038/v1.6.0@43056d58"},"ram_total":49992,"ram_free":38660,"fs_size":233681,"fs_free":152608,"voltage":232.08,"uptime":108}

2020-05-11 14:10:41.928 [TRACE] [y.internal.handler.ShellyBaseHandler] - shellyswitch-32b77c: Updating 1 standard meter(s)

thanks

markus7017 · May 11, 2020, 2:04pm

ok, we are in the mid of the implementation for timeout handling

1st I found a bug that should implement 3 retries rather than 2 as show in your log
Currently timeouts of the status refresh are ignored. Due to some technical limitations in handling parallel API requests on the device side. Therefore I implemented this work-around (retries, ignoring the timout)
what’s missing is a watchdog, which observers device inactivity, this is especially important for sensors. Per definition they wake up only every 12h, so there should be a watchdog, which makes sure to get at least 1 API result within 13h (for example).
CoAP provides regular updates, usally every 15s for non-battery powered devices. The spec states that a device could be considered unavailable when those updates are no provided

Therefore I plan a watchdog process, which observes device availability.

For sensors the Timeout will be 12h + some extra time
for other devices:
– If CoAP is anabled: Timeout = 315+5sec (15s is the default setting, could be higher)
– otherwise the default will be 260+5sec = 125sec

If the timeout expires and there is no update the device will change to OFFLINE/COMM_ERROR, otherwise the timeout is ignored (as of now). On setting/control request there will be 3 retries, but then the device will also go offline.

I need to work on this, a GitHub issue has already been created.

markus7017 · May 11, 2020, 2:07pm

Please try it. AFAIK advanced channels (those you see only when you click [Show Advanced] in PaperUI) need to be declared within the Thing definition in the .things file. @hmerk could you confirm/provide an example?

hmerk · May 11, 2020, 2:12pm

Shure, these are two devices in my installation

Thing shelly:shelly1:8b18f7 "Deckenlicht" @ "Flur DG" [ deviceIp="192.168.0.170", coap="true", eventsRelayButton="false", eventsRelaySwitch="false", eventsCoIoT="true" ]
Thing shelly:shelly2-relay:5b2785 "Deckenlicht" @ "Arbeitszimmer" [ deviceIp="192.168.0.171", coap="true", eventsRelayButton="false", eventsRelaySwitch="false", eventsCoIoT="true" ]

markus7017 · May 11, 2020, 2:33pm

yes, but does thise automatically makes the advanced channels available for item linkage

hmerk · May 11, 2020, 2:35pm

You always have to press more to see the advanced channels. You cannot override this with the things definition.

curlyel · May 11, 2020, 4:58pm

Thanks Markus,
I gave it a try:

deleted the shellydw from the *.things file
stopped the Shelly binding via Karaf console
started the Shelly binding again
woke the shellydw by pressing the internal button

The behaviour is similar to what I had before. The channels got discovered,
but populated just once at initialization, see:

2020-05-11 18:13:29.630 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Start initializing thing tuer - 192.168.9.17, type shellydw, ip address 192.168.9.17, CoIoT: true
2020-05-11 18:13:30.054 [vent.ItemStateChangedEvent] - ShellyTuer_uptime changed from NULL to 21
2020-05-11 18:13:30.069 [vent.ItemStateChangedEvent] - ShellyTuer_rssi changed from NULL to 4
2020-05-11 18:13:30.437 [vent.ItemStateChangedEvent] - ShellyTuer_reason changed from NULL to button
2020-05-11 18:13:33.641 [vent.ItemStateChangedEvent] - ShellyTuer_uptime changed from 21 to 24
2020-05-11 18:13:42.348 [vent.ItemStateChangedEvent] - ShellyTuer_vibration changed from OFF to ON
2020-05-11 18:13:57.383 [vent.ItemStateChangedEvent] - ShellyTuer_vibration changed from ON to OFF
2020-05-11 18:14:00.256 [vent.ItemStateChangedEvent] - ShellyTuer changed from CLOSED to OPEN
2020-05-11 18:14:05.278 [vent.ItemStateChangedEvent] - ShellyTuer changed from OPEN to CLOSED
2020-05-11 18:14:39.688 [vent.ItemStateChangedEvent] - ShellyTuer changed from CLOSED to OPEN
2020-05-11 18:14:44.626 [vent.ItemStateChangedEvent] - ShellyTuer changed from OPEN to CLOSED
2020-05-11 18:14:59.650 [vent.ItemStateChangedEvent] - ShellyTuer_vibration changed from OFF to ON
2020-05-11 18:15:48.365 [vent.ItemStateChangedEvent] - ShellyTuer_vibration changed from ON to OFF
2020-05-11 18:19:47.787 [vent.ItemStateChangedEvent] - ShellyTuer_vibration changed from OFF to ON
2020-05-11 18:20:02.801 [vent.ItemStateChangedEvent] - ShellyTuer_vibration changed from ON to OFF
2020-05-11 18:20:34.256 [vent.ItemStateChangedEvent] - ShellyTuer changed from CLOSED to OPEN
2020-05-11 18:20:44.319 [vent.ItemStateChangedEvent] - ShellyTuer changed from OPEN to CLOSED
2020-05-11 18:21:02.093 [vent.ItemStateChangedEvent] - ShellyTuer changed from CLOSED to OPEN
2020-05-11 18:24:36.493 [vent.ItemStateChangedEvent] - ShellyTuer changed from OPEN to CLOSED
2020-05-11 18:25:05.090 [vent.ItemStateChangedEvent] - ShellyTuer changed from CLOSED to OPEN
2020-05-11 18:25:14.325 [vent.ItemStateChangedEvent] - ShellyTuer changed from OPEN to CLOSED
2020-05-11 18:27:26.620 [vent.ItemStateChangedEvent] - ShellyTuer changed from CLOSED to OPEN
2020-05-11 18:28:45.452 [vent.ItemStateChangedEvent] - ShellyTuer changed from OPEN to CLOSED

These are the debug lines:

2020-05-11 18:12:35.403 [hingStatusInfoChangedEvent] - 'shelly:shellydw:fa902a' changed from ONLINE to UNINITIALIZED
2020-05-11 18:12:35.450 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Shutting down
2020-05-11 18:12:35.452 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Shelly statusJob stopped
2020-05-11 18:12:35.476 [hingStatusInfoChangedEvent] - 'shelly:shellydw:fa902a' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-05-11 18:12:54.403 [DEBUG] [shelly.internal.ShellyHandlerFactory] - tuer - 192.168.9.17: Create new thing of type shelly:shellydw using ShellyRelayHandler
2020-05-11 18:12:54.566 [hingStatusInfoChangedEvent] - 'shelly:shellydw:fa902a' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-05-11 18:12:56.600 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Using userId ogv from bindingConfig
2020-05-11 18:12:56.605 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Device config: IP address=192.168.9.17, HTTP user/password=ogv/***, update interval=3600
2020-05-11 18:12:56.610 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Configured Events: Button: false, Switch (on/off): true, Push: true, Roller: true, Sensor: false, CoIoT: true, Enable AutoCoIoT: true
2020-05-11 18:12:56.616 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Start initializing thing tuer - 192.168.9.17, type shellydw, ip address 192.168.9.17, CoIoT: true
2020-05-11 18:12:56.627 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Starting CoAP Listener
2020-05-11 18:12:56.632 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoAP Request was canceled
2020-05-11 18:12:56.633 [hingStatusInfoChangedEvent] - 'shelly:shellydw:fa902a' changed from INITIALIZING to UNKNOWN
2020-05-11 18:12:56.797 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyswitch25-1126ea: Initializing device shellyswitch25-1126ea, type SHSW-25, Hardware: Rev: prod-2019-08, batch 0; Firmware: v1.6.0 / 20200309-104051 (43056d58)
2020-05-11 18:12:57.381 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellydw-fa902a: API Timeout, retry #1 (Device unreachable or API Timeout (GET http://192.168.9.17/shelly))
2020-05-11 18:13:00.503 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellydw-fa902a: API Timeout, retry #2 (Device unreachable or API Timeout (GET http://192.168.9.17/shelly))
2020-05-11 18:13:03.621 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Unable to initialize: Device unreachable or API Timeout (GET http://192.168.9.17/shelly), retrying later
2020-05-11 18:13:03.624 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Update status job started, interval=1200*3=3600sec.
2020-05-11 18:13:27.294 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1695): {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:13:27.304 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:13:27.305 [hingStatusInfoChangedEvent] - 'shelly:shellydw:fa902a' changed from UNKNOWN to ONLINE
2020-05-11 18:13:27.308 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoAP Request was canceled
2020-05-11 18:13:27.335 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Device Description for SHDW-1#FA902A#1: {"blk":[{"I":1, "D":"sensors"}],"sen":[{"I":66, "D":"lux", "T":"L", "R":"0/100000", "L":1},{"I":55, "D":"State", "T":"S", "R":"0/1", "L":1},{"I":77, "D":"battery", "T":"B", "R":"0/100", "L":1},{"I":88, "D":"tilt", "T":"S", "R":"0/180", "L":1},{"I":99, "D":"vibration", "T":"S", "R":"0/1", "L":1}]}
2020-05-11 18:13:27.344 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id=1: sensors
2020-05-11 18:13:27.348 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Adding 5 sensor definitions
2020-05-11 18:13:27.351 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 66: lux, Type=L, Range=0/100000, Links=1
2020-05-11 18:13:27.354 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 55: State, Type=S, Range=0/1, Links=1
2020-05-11 18:13:27.358 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 77: battery, Type=B, Range=0/100, Links=1
2020-05-11 18:13:27.361 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 88: tilt, Type=S, Range=0/180, Links=1
2020-05-11 18:13:27.364 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 99: vibration, Type=S, Range=0/1, Links=1
2020-05-11 18:13:27.368 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Device description for SHDW-1#FA902A#1 restored: {"blk":[{"I":1, "D":"sensors"}],"sen":[{"I":66, "D":"lux", "T":"L", "R":"0/100000", "L":1},{"I":55, "D":"State", "T":"S", "R":"0/1", "L":1},{"I":77, "D":"battery", "T":"B", "R":"0/100", "L":1},{"I":88, "D":"tilt", "T":"S", "R":"0/180", "L":1},{"I":99, "D":"vibration", "T":"S", "R":"0/1", "L":1}]}
2020-05-11 18:13:27.371 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:13:27.382 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel battery#batteryLevel updated with 100.0 % (type class org.eclipse.smarthome.core.library.types.QuantityType).
2020-05-11 18:13:27.387 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with CLOSED (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:13:27.393 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#vibration updated with OFF (type class org.eclipse.smarthome.core.library.types.OnOffType).
2020-05-11 18:13:27.396 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 3 channels updated from CoIoT status
2020-05-11 18:13:27.709 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=63168): {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:13:27.712 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:13:27.715 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:13:27.721 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel battery#batteryLevel updated with 100.0 % (type class org.eclipse.smarthome.core.library.types.QuantityType).
2020-05-11 18:13:27.725 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:13:29.626 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Status update triggered thing initialization
2020-05-11 18:13:29.628 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=63167): {"blk":[{"I":1, "D":"sensors"}],"sen":[{"I":66, "D":"lux", "T":"L", "R":"0/100000", "L":1},{"I":55, "D":"State", "T":"S", "R":"0/1", "L":1},{"I":77, "D":"battery", "T":"B", "R":"0/100", "L":1},{"I":88, "D":"tilt", "T":"S", "R":"0/180", "L":1},{"I":99, "D":"vibration", "T":"S", "R":"0/1", "L":1}]}
2020-05-11 18:13:29.630 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Start initializing thing tuer - 192.168.9.17, type shellydw, ip address 192.168.9.17, CoIoT: true
2020-05-11 18:13:29.631 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Device Description for SHDW-1#FA902A#1: {"blk":[{"I":1, "D":"sensors"}],"sen":[{"I":66, "D":"lux", "T":"L", "R":"0/100000", "L":1},{"I":55, "D":"State", "T":"S", "R":"0/1", "L":1},{"I":77, "D":"battery", "T":"B", "R":"0/100", "L":1},{"I":88, "D":"tilt", "T":"S", "R":"0/180", "L":1},{"I":99, "D":"vibration", "T":"S", "R":"0/1", "L":1}]}
2020-05-11 18:13:29.636 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id=1: sensors
2020-05-11 18:13:29.649 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Adding 5 sensor definitions
2020-05-11 18:13:29.653 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 66: lux, Type=L, Range=0/100000, Links=1
2020-05-11 18:13:29.657 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 55: State, Type=S, Range=0/1, Links=1
2020-05-11 18:13:29.665 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 77: battery, Type=B, Range=0/100, Links=1
2020-05-11 18:13:29.673 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 88: tilt, Type=S, Range=0/180, Links=1
2020-05-11 18:13:29.677 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a:    id 99: vibration, Type=S, Range=0/1, Links=1
2020-05-11 18:13:29.849 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Initializing device shellydw-fa902a, type SHDW-1, Hardware: Rev: , batch ; Firmware: v1.6.5 / 20200408-085810 (b075f0bf)
2020-05-11 18:13:29.854 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Shelly settings info for shellydw-fa902a: {"device":{"type":"SHDW-1","mac":"68C63AFA902A","hostname":"shellydw-FA902A","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellydw-FA902A","key":""},"wifi_sta":{"enabled":true,"ssid":"LusImollSuTiF","ipv4_method":"static","ip":"192.168.9.17","gw":"192.168.9.1","mask":"255.255.255.0","dns":null},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.9.1:1883","user":"","id":"shellydw-FA902A","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":0,"retain":false,"update_period":30},"coiot": {"update_period":15},"sntp":{"server":"time.google.com","enabled":true},"login":{"enabled":false,"unprotected":false,"username":"admin"},"pin_code":"","name":"","fw":"20200408-085810/v1.6.5@b075f0bf","discoverable":false,"build_info":{"build_id":"20200408-085810/v1.6.5@b075f0bf","build_timestamp":"2020-04-08T08:58:10Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Berlin","lat":500.000000,"lng":500.000000,"tzautodetect":true,"tz_utc_offset":0,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"dark_threshold":100,"twilight_threshold":300,"sleep_mode":{"period":6,"unit":"h"},"led_status_disable":false,"dark_url":null,"twilight_url":null,"close_url":null,"vibration_url":null,"tilt_enabled":false,"tilt_calibrated":false,"vibration_enabled":true,"reverse_open_close":false}
2020-05-11 18:13:29.857 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Device hasRelays:false (numRelays=0),isRoller:false (numRoller=0),isDimmer:false,isPlugS:false,numMeter=0,isEMeter:false),isSensor:true,isDS:true,hasBattery:true (low battery threshold=20%),isSense:false,isLight:false,isBulb:false,isDuo:false,isRGBW2:false,inColor:false,hasLEDs:false,updatePeriod:21600sec
2020-05-11 18:13:30.032 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Auto-CoIoT is enabled, disabling action urls
2020-05-11 18:13:30.035 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Starting CoIoT (autoCoIoT=true/true)
2020-05-11 18:13:30.038 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellydw-fa902a: Set Sensor Reporting URL
2020-05-11 18:13:30.047 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#uptime updated with 21 s (type class org.eclipse.smarthome.core.library.types.QuantityType).
2020-05-11 18:13:30.054 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#wifiSignal updated with 4 (type class org.eclipse.smarthome.core.library.types.DecimalType).
2020-05-11 18:13:30.087 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#alarm updated with NONE (type class org.eclipse.smarthome.core.library.types.StringType).
2020-05-11 18:13:30.090 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Thing successfully initialized.
2020-05-11 18:13:30.094 [me.event.ThingUpdatedEvent] - Thing 'shelly:shellydw:fa902a' has been updated.
2020-05-11 18:13:30.249 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#uptime updated with 21 s (type class org.eclipse.smarthome.core.library.types.QuantityType).
2020-05-11 18:13:30.255 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#wifiSignal updated with 4 (type class org.eclipse.smarthome.core.library.types.DecimalType).
2020-05-11 18:13:30.258 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Updating sensor
2020-05-11 18:13:30.430 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#wakeupReason updated with button (type class org.eclipse.smarthome.core.library.types.StringType).
2020-05-11 18:13:30.436 [WARN ] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Event erzeugt: BUTTON
2020-05-11 18:13:30.441 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#alarm updated with BUTTON (type class org.eclipse.smarthome.core.library.types.StringType).
2020-05-11 18:13:30.444 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Updating DW state with close
2020-05-11 18:13:30.443 [vent.ChannelTriggeredEvent] - shelly:shellydw:fa902a:device#alarm triggered BUTTON
2020-05-11 18:13:30.451 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with CLOSED (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:13:30.455 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#lastError updated with  (type class org.eclipse.smarthome.core.library.types.StringType).
2020-05-11 18:13:30.459 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Unable to refresh status: Status konnte nicht aktualisiert werden
2020-05-11 18:13:33.631 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#uptime updated with 24 s (type class org.eclipse.smarthome.core.library.types.QuantityType).
2020-05-11 18:13:33.642 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#wifiSignal updated with 4 (type class org.eclipse.smarthome.core.library.types.DecimalType).
2020-05-11 18:13:33.649 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Updating sensor
2020-05-11 18:13:33.809 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#wakeupReason updated with button (type class org.eclipse.smarthome.core.library.types.StringType).
2020-05-11 18:13:33.813 [WARN ] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Event erzeugt: BUTTON
2020-05-11 18:13:33.817 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel device#alarm updated with BUTTON (type class org.eclipse.smarthome.core.library.types.StringType).
2020-05-11 18:13:33.819 [vent.ChannelTriggeredEvent] - shelly:shellydw:fa902a:device#alarm triggered BUTTON
2020-05-11 18:13:33.820 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Updating DW state with close
2020-05-11 18:13:33.825 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#lastError updated with  (type class org.eclipse.smarthome.core.library.types.StringType).
2020-05-11 18:13:33.829 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Unable to refresh status: Status konnte nicht aktualisiert werden
2020-05-11 18:13:42.310 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1696): {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:13:42.313 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:13:42.318 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:13:42.327 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel battery#batteryLevel updated with 100.0 % (type class org.eclipse.smarthome.core.library.types.QuantityType).
2020-05-11 18:13:42.336 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#vibration updated with ON (type class org.eclipse.smarthome.core.library.types.OnOffType).
2020-05-11 18:13:42.341 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 2 channels updated from CoIoT status
2020-05-11 18:13:57.334 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1697): {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:13:57.340 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:13:57.344 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:13:57.352 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel battery#batteryLevel updated with 100.0 % (type class org.eclipse.smarthome.core.library.types.QuantityType).
2020-05-11 18:13:57.370 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#vibration updated with OFF (type class org.eclipse.smarthome.core.library.types.OnOffType).
2020-05-11 18:13:57.373 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 2 channels updated from CoIoT status
2020-05-11 18:14:00.205 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1698): {"G":[[0,66,58],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:00.209 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,58],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:00.214 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:14:00.223 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel battery#batteryLevel updated with 100.0 % (type class org.eclipse.smarthome.core.library.types.QuantityType).
2020-05-11 18:14:00.230 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with OPEN (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:14:00.234 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 2 channels updated from CoIoT status
2020-05-11 18:14:03.857 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellydw-fa902a: Enabling channel cache (20 updates / 60s)
2020-05-11 18:14:05.238 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1699): {"G":[[0,66,43],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:05.244 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,43],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:05.249 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:14:05.264 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with CLOSED (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:14:05.271 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:14:20.291 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1700): {"G":[[0,66,43],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:35.302 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1701): {"G":[[0,66,43],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:39.652 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1702): {"G":[[0,66,58],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:39.655 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,58],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:39.660 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:14:39.681 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with OPEN (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:14:39.690 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:14:44.591 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1703): {"G":[[0,66,71],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:44.594 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,71],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:14:44.600 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:14:44.620 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with CLOSED (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:14:44.624 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:14:59.616 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1704): {"G":[[0,66,76],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:14:59.620 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,76],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:14:59.624 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:14:59.641 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#vibration updated with ON (type class org.eclipse.smarthome.core.library.types.OnOffType).
2020-05-11 18:14:59.648 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:15:00.096 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1705): {"G":[[0,66,89],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:15:00.104 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,89],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:15:00.110 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:15:03.223 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1706): {"G":[[0,66,76],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:15:03.232 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,76],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:15:03.238 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:15:18.244 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1707): {"G":[[0,66,76],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:15:33.303 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1708): {"G":[[0,66,76],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:15:48.334 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=1709): {"G":[[0,66,76],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:15:48.337 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,76],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:15:48.343 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:15:48.357 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#vibration updated with OFF (type class org.eclipse.smarthome.core.library.types.OnOffType).
2020-05-11 18:15:48.363 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:17:10.676 [DEBUG] [discovery.ShellyDiscoveryParticipant] - ServiceInfo [ServiceInfoImpl@13263954 name: 'shellydw-FA902A._http._tcp.local.' address: '(null):80' status: 'DNS: JmDNS-/192.168.9.1 [/192.168.9.1] state: probing 1 task: null', has NO data, empty]
2020-05-11 18:19:32.739 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20906): {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:19:32.742 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:19:32.746 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:19:47.751 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20907): {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:19:47.758 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,1]]}
2020-05-11 18:19:47.762 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:19:47.777 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#vibration updated with ON (type class org.eclipse.smarthome.core.library.types.OnOffType).
2020-05-11 18:19:47.792 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:20:02.753 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20908): {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:20:02.759 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:20:02.764 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:20:02.793 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#vibration updated with OFF (type class org.eclipse.smarthome.core.library.types.OnOffType).
2020-05-11 18:20:02.808 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:20:14.467 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellydw-fa902a: Shelly device discovered: IP-Adress=192.168.9.17, type=shellydw
2020-05-11 18:20:14.685 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellydw-fa902a: Shelly settings : {"device":{"type":"SHDW-1","mac":"68C63AFA902A","hostname":"shellydw-FA902A","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellydw-FA902A","key":""},"wifi_sta":{"enabled":true,"ssid":"LusImollSuTiF","ipv4_method":"static","ip":"192.168.9.17","gw":"192.168.9.1","mask":"255.255.255.0","dns":null},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.9.1:1883","user":"","id":"shellydw-FA902A","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":0,"retain":false,"update_period":30},"coiot": {"update_period":15},"sntp":{"server":"time.google.com","enabled":true},"login":{"enabled":false,"unprotected":false,"username":"admin"},"pin_code":"","name":"","fw":"20200408-085810/v1.6.5@b075f0bf","discoverable":false,"build_info":{"build_id":"20200408-085810/v1.6.5@b075f0bf","build_timestamp":"2020-04-08T08:58:10Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Berlin","lat":500.000000,"lng":500.000000,"tzautodetect":true,"tz_utc_offset":0,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"dark_threshold":100,"twilight_threshold":300,"sleep_mode":{"period":6,"unit":"h"},"led_status_disable":false,"dark_url":null,"twilight_url":null,"close_url":null,"vibration_url":null,"tilt_enabled":false,"tilt_calibrated":false,"vibration_enabled":true,"reverse_open_close":false}
2020-05-11 18:20:14.690 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellydw-fa902a: Adding Shelly thing, UID=shelly:shellydw:fa902a
2020-05-11 18:20:17.847 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20909): {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:20:32.869 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20910): {"G":[[0,66,11],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:20:34.218 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20911): {"G":[[0,66,71],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:20:34.223 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,71],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:20:34.228 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:20:34.248 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with OPEN (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:20:34.264 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:20:44.286 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20912): {"G":[[0,66,58],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:20:44.289 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,58],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:20:44.294 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:20:44.307 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with CLOSED (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:20:44.312 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:20:59.319 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20913): {"G":[[0,66,58],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:21:02.066 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20914): {"G":[[0,66,37],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:21:02.070 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,37],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:21:02.073 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:21:02.081 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with OPEN (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:21:02.087 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:21:17.132 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20915): {"G":[[0,66,37],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:21:32.232 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20916): {"G":[[0,66,37],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:21:47.318 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=20917): {"G":[[0,66,37],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:23:20.682 [DEBUG] [discovery.ShellyDiscoveryParticipant] - ServiceInfo [ServiceInfoImpl@3280674 name: 'shellydw-FA902A._http._tcp.local.' address: '(null):80' status: 'DNS: JmDNS-/192.168.9.1 [/192.168.9.1] state: probing 1 task: null', has NO data, empty]
2020-05-11 18:24:03.551 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46967): {"G":[[0,66,15],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:24:03.555 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,15],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:24:03.560 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:24:18.436 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46968): {"G":[[0,66,15],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:24:33.467 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46969): {"G":[[0,66,15],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:24:36.454 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46970): {"G":[[0,66,71],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:24:36.460 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,71],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:24:36.464 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:24:36.476 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with CLOSED (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:24:36.484 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:24:45.155 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellydw-fa902a: Shelly device discovered: IP-Adress=192.168.9.17, type=shellydw
2020-05-11 18:24:45.340 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellydw-fa902a: Shelly settings : {"device":{"type":"SHDW-1","mac":"68C63AFA902A","hostname":"shellydw-FA902A","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellydw-FA902A","key":""},"wifi_sta":{"enabled":true,"ssid":"LusImollSuTiF","ipv4_method":"static","ip":"192.168.9.17","gw":"192.168.9.1","mask":"255.255.255.0","dns":null},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":true,"server":"192.168.9.1:1883","user":"","id":"shellydw-FA902A","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":0,"retain":false,"update_period":30},"coiot": {"update_period":15},"sntp":{"server":"time.google.com","enabled":true},"login":{"enabled":false,"unprotected":false,"username":"admin"},"pin_code":"","name":"","fw":"20200408-085810/v1.6.5@b075f0bf","discoverable":false,"build_info":{"build_id":"20200408-085810/v1.6.5@b075f0bf","build_timestamp":"2020-04-08T08:58:10Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Berlin","lat":500.000000,"lng":500.000000,"tzautodetect":true,"tz_utc_offset":0,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"dark_threshold":100,"twilight_threshold":300,"sleep_mode":{"period":6,"unit":"h"},"led_status_disable":false,"dark_url":null,"twilight_url":null,"close_url":null,"vibration_url":null,"tilt_enabled":false,"tilt_calibrated":false,"vibration_enabled":true,"reverse_open_close":false}
2020-05-11 18:24:45.344 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellydw-fa902a: Adding Shelly thing, UID=shelly:shellydw:fa902a
2020-05-11 18:24:51.509 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46971): {"G":[[0,66,71],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:25:05.055 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46972): {"G":[[0,66,56],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:25:05.060 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,56],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:25:05.064 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:25:05.078 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with OPEN (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:25:05.087 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:25:14.280 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46973): {"G":[[0,66,82],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:25:14.288 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,82],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:25:14.293 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:25:14.309 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with CLOSED (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:25:14.314 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:25:29.334 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46974): {"G":[[0,66,82],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:25:44.431 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46975): {"G":[[0,66,82],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:25:59.435 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46976): {"G":[[0,66,82],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:26:14.506 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=46977): {"G":[[0,66,82],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:27:26.579 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=7493): {"G":[[0,66,66],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:27:26.585 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,66],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:27:26.589 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:27:26.605 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with OPEN (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:27:26.610 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status
2020-05-11 18:27:50.683 [DEBUG] [discovery.ShellyDiscoveryParticipant] - ServiceInfo [ServiceInfoImpl@19958272 name: 'shellydw-FA902A._http._tcp.local.' address: '(null):80' status: 'DNS: JmDNS-/192.168.9.1 [/192.168.9.1] state: probing 1 task: null', has NO data, empty]
2020-05-11 18:28:45.403 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Message from /192.168.9.17:5683 (MID=30941): {"G":[[0,66,66],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:28:45.409 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: Duplicate serial 256 will be processed, because payload is different: {"G":[[0,66,66],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]} vs. {"G":[[0,66,66],[0,55,1],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:28:45.413 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: CoIoT Sensor data {"G":[[0,66,66],[0,55,0],[0,77,100],[0,88,-1],[0,99,0]]}
2020-05-11 18:28:45.418 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 5 CoAP sensor updates received
2020-05-11 18:28:45.432 [DEBUG] [lly.internal.util.ShellyChannelCache] - shellydw-fa902a: Channel sensors#state updated with CLOSED (type class org.eclipse.smarthome.core.library.types.OpenClosedType).
2020-05-11 18:28:45.440 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellydw-fa902a: 1 channels updated from CoIoT status

For me it seems, that the content of the “advanced” channels are gathered successful only when the device is woken by pressing the button and therefore reachable for a longer period.

When the device is woken from sleep by a sensor change, it immediately falls asleep againg after delivering the CoIoT, the advanced channels won’t get updated.

So my question:
Is the binding trying to get some data from the DW by http after receiving a CoIoT packet? Could it be, that the device simply falls asleep to quick for the additional http get?

markus7017 · May 11, 2020, 6:07pm

That’s a little bit more complex

Dynamic Channels: The thing definition for the binding only contains some basic channels when the thing is added. Each time the Thing gets ONLINE if checks the device status and auto-creates the channel for status values supported by the device. Usually this happens on initial discovery.
Only values provided by the CoAP status update could be populated to the channels. For DW those are lux, state, battery, tilt and vibration.
Values not provided by the CoAP status have to be queried using the REST API. And yes, when the device is in sleep mode there is no way to wake-it up or to send a http request.
Also the signal strength, uptime, last wakeup reason, last error etc. are only available through REST / requires that the device is awake.
When Auto-CoIoT is enabled in the thing config the Action URLs are turned off.

The idea behind this is reduced energy consumption. With CoAP

the device wakes up
sends the status packet (UDP)
and goes pack to sleep mode

that’s way more efficient than REST. On the other side a REST call requires the device to be awake and there is no option to wakeup the device remotely (e.g. WOL packet).

What you can do (not recommended with regards to energy consumption)

Disable Auto-CoIoT in the binding config
Enable CoIoT and Sensor events in the Thing configuration

This results in

get updates from CoAP message near realtime
in addition have Action URLs triggering the binding
the binding being able to issue a REST call and get the additional infos

However,

is a really a must to have the wifi signal strength? you are usually not moving the sensor, so…
who really cares on uptime when everything is working
wakeup reason is not a must, usally it’s a sensor update or the periodic wakeup
or do you care more on energy consumption and benefit from reduced battery change interval?

So works as designed within the limits set by the firmware.

curlyel · May 11, 2020, 6:23pm

Perfect. That’s what I wanted to know (or better: to understand).
Many thanks for the comprehensive explanation!

I already started thinking that I’m chasing ghosts here. But from the readme and even from the UI-based thing configuration I got the impression, that those channels should be always available to me (even in CoIoT).

No, definitely not
It’s not a must. Though, I was trying to get this together with lastupdate and #lastError to support future troubleshooting just in case. But for sure: saving juice coming out of the tiny batteries is more relevant here.

The main channels are working. Happy with that!
And happy too, that I can stop chasing ghosts now

Thanks for your patience and your support!

markus7017 · May 11, 2020, 6:27pm

lastUpdate should be updated on any change to the sensor state, otherwise it’s a bug
you could observe the trigger channel (see README for events). Again those get triggered when the binding gets aware of the event…

curlyel · May 11, 2020, 6:38pm

Mmh. I never got the sensors#lastUpdate populated
Unfortunately, I’m running out of time for today…

Let me check this tomorow again.

markus7017 · May 12, 2020, 3:38pm

I implemented a watchdog, which supervises API responses. The watchdog expires and the thing goes OFFLINE when

Sensor: After 12h+10min no update is provided by the sensor
If CoAP is enabled and no response is received within the last 3*update_period+10sec given a minimum of 70sec (a settings refresh is tried every 60sec and might restart the watchdog).
Default: after 2min+10sec

If no updates have been received the thing goes OFFLINE. If a subsequent status call is successful the thing goes back ONLINE. Tried that several times with different device types and worked fine.