Shelly Binding

marian.tudor · December 7, 2020, 6:33pm

Hello,
I kindly ask you to have a look on the following log messages related to the OH boot time period and let me know what possible issue(s) create an non proper communication:
2020-12-07 13:52:14.778 [hingStatusInfoChangedEvent] - ‘shelly:shellyht:f36cf3’ changed from UNINITIALIZED to INITIALIZING

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

2020-12-07 13:52:14.831 [DEBUG] [shelly.internal.ShellyHandlerFactory] - shellyht-f2b672 - Birouri: Create new thing of type shelly:shellyht using ShellyRelayHandler

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

2020-12-07 13:52:14.901 [hingStatusInfoChangedEvent] - 'shelly:shellyht:f2b672' changed from UNINITIALIZED to INITIALIZING

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

2020-12-07 13:52:14.918 [DEBUG] [shelly.internal.ShellyHandlerFactory] - shellyht-f2b670-Ferolli: Create new thing of type shelly:shellyht using ShellyRelayHandler

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

2020-12-07 13:52:14.971 [hingStatusInfoChangedEvent] - 'shelly:shellyht:f2b670' changed from UNINITIALIZED to INITIALIZING

2020-12-07 13:52:16.833 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f36cf3: Device config: IP address=10.0.5.242, HTTP user/password=admin/***, update interval=3600

2020-12-07 13:52:16.839 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f36cf3: Configured Events: Button: false, Switch (on/off): true, Push: true, Roller: true, Sensor: false, CoIoT: true, Enable AutoCoIoT: true

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

2020-12-07 13:52:16.847 [hingStatusInfoChangedEvent] - 'shelly:shellyht:f36cf3' changed from INITIALIZING to UNKNOWN

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

2020-12-07 13:52:16.847 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f36cf3: Start initializing thing shellyht-f36cf3 - Hala, type shellyht, ip address 10.0.5.242, CoIoT: true

2020-12-07 13:52:16.856 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyht-f36cf3: Starting CoAP Listener

2020-12-07 13:52:16.860 [DEBUG] [helly.internal.coap.ShellyCoapServer] - Initializing CoIoT listener (local IP=10.0.5.230:5683)

2020-12-07 13:52:16.920 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b672: Device config: IP address=10.0.5.241, HTTP user/password=admin/***, update interval=3600

2020-12-07 13:52:16.922 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b672: Configured Events: Button: false, Switch (on/off): true, Push: true, Roller: true, Sensor: false, CoIoT: true, Enable AutoCoIoT: true

2020-12-07 13:52:16.927 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b672: Start initializing thing shellyht-f2b672 - Birouri, type shellyht, ip address 10.0.5.241, CoIoT: true

2020-12-07 13:52:16.929 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyht-f2b672: Starting CoAP Listener

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

2020-12-07 13:52:16.928 [hingStatusInfoChangedEvent] - 'shelly:shellyht:f2b672' changed from INITIALIZING to UNKNOWN

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

2020-12-07 13:52:16.991 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b670: Device config: IP address=10.0.5.240, HTTP user/password=admin/***, update interval=3600

2020-12-07 13:52:16.993 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b670: Configured Events: Button: false, Switch (on/off): true, Push: true, Roller: true, Sensor: false, CoIoT: true, Enable AutoCoIoT: true

2020-12-07 13:52:16.996 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b670: Start initializing thing shellyht-f2b670-Ferolli, type shellyht, ip address 10.0.5.240, CoIoT: true

2020-12-07 13:52:16.999 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyht-f2b670: Starting CoAP Listener

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

2020-12-07 13:52:17.002 [hingStatusInfoChangedEvent] - 'shelly:shellyht:f2b670' changed from INITIALIZING to UNKNOWN

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

2020-12-07 13:52:17.050 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f36cf3: HTTP GET for http://10.0.5.242/shelly

2020-12-07 13:52:17.052 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: HTTP GET for http://10.0.5.240/shelly

2020-12-07 13:52:17.059 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b672: HTTP GET for http://10.0.5.241/shelly

2020-12-07 13:52:17.498 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to '127.0.0.1' with clientid openHAB2


2020-12-07 13:52:20.210 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b672: API Timeout, retry #1 (Device unreachable or API Timeout (GET http://10.0.5.241/shelly))

2020-12-07 13:52:20.210 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: API Timeout, retry #1 (Device unreachable or API Timeout (GET http://10.0.5.240/shelly))

2020-12-07 13:52:20.213 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b672: HTTP GET for http://10.0.5.241/shelly

2020-12-07 13:52:20.213 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: HTTP GET for http://10.0.5.240/shelly

2020-12-07 13:52:20.215 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f36cf3: API Timeout, retry #1 (Device unreachable or API Timeout (GET http://10.0.5.242/shelly))

2020-12-07 13:52:20.217 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f36cf3: HTTP GET for http://10.0.5.242/shelly

2020-12-07 13:52:23.322 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f36cf3: API Timeout, retry #2 (Device unreachable or API Timeout (GET http://10.0.5.242/shelly))

2020-12-07 13:52:23.323 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b672: API Timeout, retry #2 (Device unreachable or API Timeout (GET http://10.0.5.241/shelly))

2020-12-07 13:52:23.324 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f36cf3: HTTP GET for http://10.0.5.242/shelly

2020-12-07 13:52:23.325 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b672: HTTP GET for http://10.0.5.241/shelly

2020-12-07 13:52:23.493 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: API Timeout, retry #2 (Device unreachable or API Timeout (GET http://10.0.5.240/shelly))

2020-12-07 13:52:23.495 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: HTTP GET for http://10.0.5.240/shelly

2020-12-07 13:52:26.443 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b672: API Timeout, retry #3 (Device unreachable or API Timeout (GET http://10.0.5.241/shelly))

2020-12-07 13:52:26.444 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f36cf3: API Timeout, retry #3 (Device unreachable or API Timeout (GET http://10.0.5.242/shelly))

2020-12-07 13:52:26.444 [DEBUG] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: API Timeout, retry #3 (Device unreachable or API Timeout (GET http://10.0.5.240/shelly))

2020-12-07 13:52:26.447 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f36cf3: Unable to initialize: Device unreachable or API Timeout (), retrying later

2020-12-07 13:52:26.447 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b670: Unable to initialize: Device unreachable or API Timeout (), retrying later

2020-12-07 13:52:26.447 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b672: Unable to initialize: Device unreachable or API Timeout (), retrying later

2020-12-07 13:52:26.453 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f36cf3: Update status job started, interval=1200*3=3600sec.

2020-12-07 13:52:26.453 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b670: Update status job started, interval=1200*3=3600sec.

2020-12-07 13:52:26.456 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b672: Update status job started, interval=1200*3=3600sec.



2020-12-07 13:53:25.457 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f36cf3: Enabling channel cache (20 updates / 60s)

2020-12-07 13:53:25.457 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b672: Enabling channel cache (20 updates / 60s)

2020-12-07 13:53:25.459 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyht-f2b670: Enabling channel cache (20 updates / 60s)

2020-12-07 13:53:34.497 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyht-f2b672: CoAP Request timed out

2020-12-07 13:53:36.362 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyht-f36cf3: CoAP Request timed out

2020-12-07 13:53:43.239 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shellyht-f2b670: CoAP Request timed out

2020-12-07 13:53:56.852 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b672: Shelly device discovered with empty IP address (service-name=[ServiceInfoImpl@29722936 name: 'shellyht-F2B672._http._tcp.local.' address: '(null):0' status: 'DNS: JmDNS-/10.0.5.230 [/10.0.5.230] state: probing 1 task: null', has NO data

	shellyht-F2B672._http._tcp.local.: ])

2020-12-07 13:53:56.863 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b672: Shelly device discovered: IP-Adress=10.0.5.241, type=shellyht

2020-12-07 13:53:56.868 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b672: HTTP GET for http://10.0.5.241/settings

2020-12-07 13:53:57.025 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b672: HTTP Response 200: {"device":{"type":"SHHT-1","mac":"98F4ABF2B672","hostname":"shellyht-F2B672","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellyht-F2B672","key":""},"wifi_sta":{"enabled":true,"ssid":"magnicom","ipv4_method":"static","ip":"10.0.5.241","gw":"10.0.5.1","mask":"255.255.255.0","dns":"10.0.5.1"},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyht-F2B672","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":true,"unprotected":false,"username":"admin"},"pin_code":"","name":"Angelescu_TEMP_Sensor","fw":"20201124-091711/v1.9.0@57ac4ad8","discoverable":true,"build_info":{"build_id":"20201124-091711/v1.9.0@57ac4ad8","build_timestamp":"2020-11-24T09:17:11Z","build_version":"1.0"},"cloud":{"enabled":true,"connected":false},"timezone":"","lat":,"lng":26.079000,"tzautodetect":true,"tz_utc_offset":7200,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"actions":{"active":false,"names":["report_url","temp_over_url","temp_under_url","hum_over_url","hum_under_url"]},"sensors":{"temperature_threshold":1.0,"temperature_unit":"C","humidity_threshold":5.0},"sleep_mode":{"period":12,"unit":"h"},"external_power":0,"temperature_offset":0.0,"humidity_offset":0.0}

2020-12-07 13:53:57.122 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b672: Shelly settings : {"device":{"type":"SHHT-1","mac":"98F4ABF2B672","hostname":"shellyht-F2B672","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellyht-F2B672","key":""},"wifi_sta":{"enabled":true,"ssid":"magnicom","ipv4_method":"static","ip":"10.0.5.241","gw":"10.0.5.1","mask":"255.255.255.0","dns":"10.0.5.1"},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyht-F2B672","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":true,"unprotected":false,"username":"admin"},"pin_code":"","name":"Angelescu_TEMP_Sensor","fw":"20201124-091711/v1.9.0@57ac4ad8","discoverable":true,"build_info":{"build_id":"20201124-091711/v1.9.0@57ac4ad8","build_timestamp":"2020-11-24T09:17:11Z","build_version":"1.0"},"cloud":{"enabled":true,"connected":false},"timezone":"","lat":,"lng":,"tzautodetect":true,"tz_utc_offset":7200,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"actions":{"active":false,"names":["report_url","temp_over_url","temp_under_url","hum_over_url","hum_under_url"]},"sensors":{"temperature_threshold":1.0,"temperature_unit":"C","humidity_threshold":5.0},"sleep_mode":{"period":12,"unit":"h"},"external_power":0,"temperature_offset":0.0,"humidity_offset":0.0}

2020-12-07 13:53:57.126 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b672: thingType=shellyht, deviceType=SHHT-1, mode=<standard>, symbolic name=Angelescu_TEMP_Sensor

2020-12-07 13:53:57.134 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b672: Adding Shelly Angelescu_TEMP_Sensor, UID=shelly:shellyht:f2b672

2020-12-07 13:54:04.220 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: Shelly device discovered with empty IP address (service-name=[ServiceInfoImpl@25895758 name: 'shellyht-F2B670._http._tcp.local.' address: '(null):0' status: 'DNS: JmDNS-/10.0.5.230 [/10.0.5.230] state: probing 1 task: null', has NO data

	shellyht-F2B670._http._tcp.local.: ])

2020-12-07 13:54:04.227 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: Shelly device discovered: IP-Adress=10.0.5.240, type=shellyht

2020-12-07 13:54:04.231 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: HTTP GET for http://10.0.5.240/settings

2020-12-07 13:54:04.360 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: HTTP Response 200: {"device":{"type":"SHHT-1","mac":"98F4ABF2B670","hostname":"shellyht-F2B670","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellyht-F2B670","key":""},"wifi_sta":{"enabled":true,"ssid":"magnicom","ipv4_method":"static","ip":"10.0.5.240","gw":"10.0.5.1","mask":"255.255.255.0","dns":"10.0.5.1"},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyht-F2B670","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":true,"unprotected":false,"username":"admin"},"pin_code":"","name":null,"fw":"20201124-091711/v1.9.0@57ac4ad8","discoverable":true,"build_info":{"build_id":"20201124-091711/v1.9.0@57ac4ad8","build_timestamp":"2020-11-24T09:17:11Z","build_version":"1.0"},"cloud":{"enabled":true,"connected":false},"timezone":"Europe/Bucharest","lat":,"lng":,"tzautodetect":false,"tz_utc_offset":7200,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"actions":{"active":false,"names":["report_url","temp_over_url","temp_under_url","hum_over_url","hum_under_url"]},"sensors":{"temperature_threshold":1.0,"temperature_unit":"C","humidity_threshold":50.0},"sleep_mode":{"period":12,"unit":"h"},"external_power":0,"temperature_offset":1.0,"humidity_offset":0.0}

2020-12-07 13:54:04.385 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: Shelly settings : {"device":{"type":"SHHT-1","mac":"98F4ABF2B670","hostname":"shellyht-F2B670","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellyht-F2B670","key":""},"wifi_sta":{"enabled":true,"ssid":"magnicom","ipv4_method":"static","ip":"10.0.5.240","gw":"10.0.5.1","mask":"255.255.255.0","dns":"10.0.5.1"},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyht-F2B670","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":true,"unprotected":false,"username":"admin"},"pin_code":"","name":null,"fw":"20201124-091711/v1.9.0@57ac4ad8","discoverable":true,"build_info":{"build_id":"20201124-091711/v1.9.0@57ac4ad8","build_timestamp":"2020-11-24T09:17:11Z","build_version":"1.0"},"cloud":{"enabled":true,"connected":false},"timezone":"","lat":,"lng":,"tzautodetect":false,"tz_utc_offset":7200,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"actions":{"active":false,"names":["report_url","temp_over_url","temp_under_url","hum_over_url","hum_under_url"]},"sensors":{"temperature_threshold":1.0,"temperature_unit":"C","humidity_threshold":50.0},"sleep_mode":{"period":12,"unit":"h"},"external_power":0,"temperature_offset":1.0,"humidity_offset":0.0}

2020-12-07 13:54:04.388 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: thingType=shellyht, deviceType=SHHT-1, mode=<standard>, symbolic name=

2020-12-07 13:54:04.391 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: Adding Shelly , UID=shelly:shellyht:f2b670

2020-12-07 13:54:27.061 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyht-f36cf3: Shelly device discovered with empty IP address (service-name=[ServiceInfoImpl@2662925 name: 'shellyht-F36CF3._http._tcp.local.' address: '(null):0' status: 'DNS: JmDNS-/10.0.5.230 [/10.0.5.230] state: probing 1 task: null', has NO data

	shellyht-F36CF3._http._tcp.local.: ])

2020-12-07 13:54:27.068 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f36cf3: Shelly device discovered: IP-Adress=10.0.5.242, type=shellyht

2020-12-07 13:54:27.073 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f36cf3: HTTP GET for http://10.0.5.242/settings

2020-12-07 13:54:27.224 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f36cf3: HTTP Response 200: {"device":{"type":"SHHT-1","mac":"98F4ABF36CF3","hostname":"shellyht-F36CF3","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellyht-F36CF3","key":""},"wifi_sta":{"enabled":true,"ssid":"magnicom","ipv4_method":"static","ip":"10.0.5.242","gw":"10.0.5.1","mask":"255.255.255.0","dns":"10.0.5.1"},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyht-F36CF3","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":true,"unprotected":false,"username":"admin"},"pin_code":"","name":"Hala_TEMP_Sensors","fw":"20201124-091711/v1.9.0@57ac4ad8","discoverable":false,"build_info":{"build_id":"20201124-091711/v1.9.0@57ac4ad8","build_timestamp":"2020-11-24T09:17:11Z","build_version":"1.0"},"cloud":{"enabled":true,"connected":false},"timezone":"","lat":,"lng":,"tzautodetect":true,"tz_utc_offset":7200,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"actions":{"active":false,"names":["report_url","temp_over_url","temp_under_url","hum_over_url","hum_under_url"]},"sensors":{"temperature_threshold":1.0,"temperature_unit":"C","humidity_threshold":0.0},"sleep_mode":{"period":12,"unit":"h"},"external_power":0,"temperature_offset":0.0,"humidity_offset":0.0}

2020-12-07 13:54:27.244 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f36cf3: Shelly settings : {"device":{"type":"SHHT-1","mac":"98F4ABF36CF3","hostname":"shellyht-F36CF3","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellyht-F36CF3","key":""},"wifi_sta":{"enabled":true,"ssid":"magnicom","ipv4_method":"static","ip":"10.0.5.242","gw":"10.0.5.1","mask":"255.255.255.0","dns":"10.0.5.1"},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyht-F36CF3","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":true,"unprotected":false,"username":"admin"},"pin_code":"","name":"Hala_TEMP_Sensors","fw":"20201124-091711/v1.9.0@57ac4ad8","discoverable":false,"build_info":{"build_id":"20201124-091711/v1.9.0@57ac4ad8","build_timestamp":"2020-11-24T09:17:11Z","build_version":"1.0"},"cloud":{"enabled":true,"connected":false},"timezone":"","lat":,"lng":,"tzautodetect":true,"tz_utc_offset":7200,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"actions":{"active":false,"names":["report_url","temp_over_url","temp_under_url","hum_over_url","hum_under_url"]},"sensors":{"temperature_threshold":1.0,"temperature_unit":"C","humidity_threshold":0.0},"sleep_mode":{"period":12,"unit":"h"},"external_power":0,"temperature_offset":0.0,"humidity_offset":0.0}

2020-12-07 13:54:27.246 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyht-f36cf3: thingType=shellyht, deviceType=SHHT-1, mode=<standard>, symbolic name=Hala_TEMP_Sensors

2020-12-07 13:54:27.249 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f36cf3: Adding Shelly Hala_TEMP_Sensors, UID=shelly:shellyht:f36cf3

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

2020-12-07 13:54:53.357 [ome.event.ItemCommandEvent] - Item 'Modul_Ales' received command 2

2020-12-07 13:54:53.391 [vent.ItemStateChangedEvent] - Modul_Ales changed from NULL to 2

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

2020-12-07 13:57:56.689 [DEBUG] [discovery.ShellyDiscoveryParticipant] - ServiceInfo [ServiceInfoImpl@19135688 name: 'shellyht-F2B672._http._tcp.local.' address: '(null):80' status: 'DNS: JmDNS-/10.0.5.230 [/10.0.5.230] state: probing 1 task: null', has NO data, empty]

2020-12-07 13:58:26.681 [DEBUG] [discovery.ShellyDiscoveryParticipant] - ServiceInfo [ServiceInfoImpl@11373935 name: 'shellyht-F36CF3._http._tcp.local.' address: '(null):80' status: 'DNS: JmDNS-/10.0.5.230 [/10.0.5.230] state: probing 1 task: null', has NO data, empty]

2020-12-07 13:59:56.681 [DEBUG] [discovery.ShellyDiscoveryParticipant] - ServiceInfo [ServiceInfoImpl@11859534 name: 'shellyht-F2B670._http._tcp.local.' address: '(null):80' status: 'DNS: JmDNS-/10.0.5.230 [/10.0.5.230] state: probing 1 task: null', has NO data, empty]

2020-12-07 14:01:00.382 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: Shelly device discovered with empty IP address (service-name=[ServiceInfoImpl@16404914 name: 'shellyht-F2B670._http._tcp.local.' address: '(null):0' status: 'DNS: JmDNS-/10.0.5.230 [/10.0.5.230] state: probing 1 task: null', has NO data

	shellyht-F2B670._http._tcp.local.: ])

2020-12-07 14:01:00.391 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: Shelly device discovered: IP-Adress=10.0.5.240, type=shellyht

2020-12-07 14:01:00.396 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: HTTP GET for http://10.0.5.240/settings

2020-12-07 14:01:00.538 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shellyht-f2b670: HTTP Response 200: {"device":{"type":"SHHT-1","mac":"98F4ABF2B670","hostname":"shellyht-F2B670","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellyht-F2B670","key":""},"wifi_sta":{"enabled":true,"ssid":"magnicom","ipv4_method":"static","ip":"10.0.5.240","gw":"10.0.5.1","mask":"255.255.255.0","dns":"10.0.5.1"},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyht-F2B670","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":true,"unprotected":false,"username":"admin"},"pin_code":"","name":null,"fw":"20201124-091711/v1.9.0@57ac4ad8","discoverable":true,"build_info":{"build_id":"20201124-091711/v1.9.0@57ac4ad8","build_timestamp":"2020-11-24T09:17:11Z","build_version":"1.0"},"cloud":{"enabled":true,"connected":false},"timezone":"","lat":,"lng":,"tzautodetect":false,"tz_utc_offset":7200,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"actions":{"active":false,"names":["report_url","temp_over_url","temp_under_url","hum_over_url","hum_under_url"]},"sensors":{"temperature_threshold":1.0,"temperature_unit":"C","humidity_threshold":50.0},"sleep_mode":{"period":12,"unit":"h"},"external_power":0,"temperature_offset":1.0,"humidity_offset":0.0}

2020-12-07 14:01:00.565 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: Shelly settings : {"device":{"type":"SHHT-1","mac":"98F4ABF2B670","hostname":"shellyht-F2B670","sleep_mode":true},"wifi_ap":{"enabled":false,"ssid":"shellyht-F2B670","key":""},"wifi_sta":{"enabled":true,"ssid":"magnicom","ipv4_method":"static","ip":"10.0.5.240","gw":"10.0.5.1","mask":"255.255.255.0","dns":"10.0.5.1"},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyht-F2B670","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":true,"unprotected":false,"username":"admin"},"pin_code":"","name":null,"fw":"20201124-091711/v1.9.0@57ac4ad8","discoverable":true,"build_info":{"build_id":"20201124-091711/v1.9.0@57ac4ad8","build_timestamp":"2020-11-24T09:17:11Z","build_version":"1.0"},"cloud":{"enabled":true,"connected":false},"timezone":"","lat":,"lng":,"tzautodetect":false,"tz_utc_offset":7200,"tz_dst":false,"tz_dst_auto":true,"time":"","unixtime":0,"actions":{"active":false,"names":["report_url","temp_over_url","temp_under_url","hum_over_url","hum_under_url"]},"sensors":{"temperature_threshold":1.0,"temperature_unit":"C","humidity_threshold":50.0},"sleep_mode":{"period":12,"unit":"h"},"external_power":0,"temperature_offset":1.0,"humidity_offset":0.0}

2020-12-07 14:01:00.568 [TRACE] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: thingType=shellyht, deviceType=SHHT-1, mode=<standard>, symbolic name=

2020-12-07 14:01:00.572 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shellyht-f2b670: Adding Shelly , UID=shelly:shellyht:f2b670

Thank you in advance!

markus7017 · December 7, 2020, 7:14pm

Please describe the problem

Rondal · December 7, 2020, 8:02pm

Funny issue with that button-1 I did a little bit of testing now. Lets get to the data first, then my observations and last my speculations and questions

DATA
The collected data was once again posted to pastebin.com, as I’m still not allowed to post that much data right here. The pastes do not expire and do not need a password, so should be fine.

The of the binding I used were 2.5.11.202011291921 (referenced as ‘old’ or Nov 29) and 2.5.11.202012041416 (referenced as ‘new’ or Dec 04).

Comments on the data are inline.
Overview: https://pastebin.com/8T2eQZhZ
Trace, old version, dups: https://pastebin.com/D9fCSyBz
Trace, new version, no dups?: https://pastebin.com/Q7mUCKeJ

Observations
That was fun. With the old version of the binding I always got the events two times if the button goes to sleep in between. If it is still awake only once. After upgrading to the new version things got a little bit strange. I sometimes saw duplications after the device was discovered but I was not able to catch that in a Trace. Other times the first press after discovery was not shown at all (only updates, no event).

After those initial hurdles it worked fine. One press -> One event. I captured one of those series as a trace. No dups seen, but the initial keypress was missed. In the log you see the change from UNKNOWN to ONLINE. That was initiated by a keypress.

Sidenote: I do not see duplicate events on MQTT. But I do see the event-counter resetting:
12/07/2020 8:57:26 PM {“event”:“S”,“event_cnt”:1}
// Sleep
12/07/2020 8:57:56 PM {“event”:“S”,“event_cnt”:1}
12/07/2020 8:57:58 PM {“event”:“S”,“event_cnt”:2}

As expected button events for other devices (for example one of my SHSW-1) do work with the old version, but not the new one. For that reason I downgraded to the November version for the moment and will try to filter out the dup events by rules

Speculations
I have no proof for it but I suspect the strange behavior after initial discovery to also happen if the thing got into the UNKNOWN or OFFLINE state again. This happens with those buttons if not used for a longer while. That would explain why I was under the impression (yesterday) that your fix was not working.

Questions
Not sure if I really understood the problem (still trying to figure out your code; I’m not that much of a Java guy). You said that the serial is increased whenever there was a change to a ‘state’. So basically: First press -> Serial 1 / Just a status update -> Still serial 1 / Another press -> Serial 2. It returns to serial 1 when sleeping.

So basically everthing sent by the Button-1 has serial 1, because it usually got to sleep in between? And sometimes it sends events more than once, right? In that case there is something seriously broken with that firmware

Completely different topic: You are aware that the 1L implementation is still lacking the second switch channel, right?

And the last question: Is there a useful way to catch CoIoT packets for debugging purposes? Haven’t found a tool yet.

Thanks,
Stefan

Rondal · December 7, 2020, 8:06pm

Hi Marian,

do you mean that your device seems to be stuck in the Initializing/Unknown state? That probably would be related to the H&T usually sleeping and only sending updates periodically. There is no way for OH to reach the device. It has to wait for the H&T to send a message, at which point it would change it’s state to ONLINE.

But I might be on the wrong track here.

markus7017 · December 7, 2020, 11:06pm

ok, I updated the DEV build, button triggers should work again for Button-1, but also other devices
please verify

markus7017 · December 7, 2020, 11:11pm

yep, you re right

serial and event count always reset to 1 when the device wakes up
there are usually 2 identical packets after wake-up - that’s makes it hard to detect

what is the “second switch channel”? The 1L has 1 Relay, correct? (still not having one on my table)

CoIoT is based on CoAP, which uses UDP multicast. Capture data on your network, then you could filter on multicast ip 224.0.1.187, UDP:5683

marian.tudor · December 8, 2020, 8:50am

Thank you for your feedback.
@markus7017 Please find below the issue that I counted:

I didn’t got the temp value (via CoIoT) when I’m going with the RPI and 3xShelly H&T on other location that has the same subnet netw. address as one that I tested on my house.
My Home network config is:
RT1 (192.168.1.0/24) ------- RT2 (192.168.1.0/24) ------- RT3 (192.168.1.0/24) and 10.0.5.0 for RPI3 /Shelly Sensors
Second Location:
RT1 (10.0.5.0/24) ---------- SW - RPI3 (10.0.5.230) and AP (10.0.5.248) used for Shelly Sensors
all the configured IPs for RPI and Shelly Sensors are static config…

Please let me know:

It’s recommended to get via DHCP the IPs since maybe are necessary others parameters to be proper configured than IP and Address MAsk ?
Do you see other possible issues ?

Thank you

Rondal · December 8, 2020, 10:34am

The 1L does have 1 Relay and 2 Switch Inputs. See below screenshot from MQTT Log.

The documentation on this (Welcome to Shelly Technical Documentation | Shelly Technical Documentation) is not really explicit about that fact.

As for the new DEV build: I’ll test later on.

RafalO · December 8, 2020, 6:37pm

If you have working Shelly H&T in one location (IP address) and not in another location (another IP) this shall not be related to shelly binding itsellf but rather general network setting/knowledge

I am not a network expert and your address (10.0.5…) seems a bit strange for me, but:
The static IP address is recommended for Shelly H&T, since this device is going into sleep mode then from time to time loging in to the network and shall have the same IP address as specified in the binding.

You can configure static IP in Shelly H&T (or router) in which you need to set IP address (ex 192.168.0.x) Network mask and Gateway. (I am using shelly H&T only as Wifi client and NOT as AP)

markus7017 · December 8, 2020, 11:17pm

Are the Shellys and OH on the dame subnet/network interface? You may need to set the network interface in the OH system settings.

CoIoT requires Multicast IP with UDP traffic.

Do you have a firewall in between?

marian.tudor · December 9, 2020, 5:20am

Hi @markus7017. Thank you again for you answer.
Both Shelly and OH are in the same subnet network as I specified in my previous messages (10.0.5.0/24).
The network interface of OH was set from PAPER UI - System - Network Interface.
The network administrator told me that no firewall or filter are active for the internal traffic.
My last request for him was to allocate via DHCP server the static IPs for Shelly sensors and OH.
I’ll check again the next days since the location is not close to my home.
Unfortunately I’m not the administrator of that network and only I need to trust the admin.
Please let me know if do you know a way to check if it’s a network issue (something is blocking the Multicast IP with UDP traffic).
Thanks again

RafalO · December 9, 2020, 12:08pm

as a first think remeber to check if in your router for the subnet (the one not transferring UDP trafic or if you use port forwarding - options depends on routers you have) you have UDP protocol enabled

manswiss · December 9, 2020, 12:10pm

I had the same Problem. By me was that “Enable Switch Events” was not set:

With this settings it works perfect.

By one switch it not works. After I also had activate “Enable Buttons-Events” -> Only then it works.

Jade_Baxter · December 10, 2020, 7:07am

I might be early.

But I’m trialing oh3 m5

But binding doesn’t seem to register the URL and enable output change active in Shelly device.
Tried both Shelly pm and Shelly dimmer 1.

Devices goes online and can turn on and off lights.

manswiss · December 10, 2020, 8:31am

I must correct me:
After Updating Firmware to 1.9.x the status of the device will not updated every time. Sometime it works, sometime not.
I have activated “Enable Button Events” “Enable Switch Events” and “Enable ColoT Events”.

Thanks for your help.

My Installation: openHAB 2.5.10 Release Build

markus7017 · December 10, 2020, 11:07am

What do you mean with “URL and enable output change”?
Action URLs are only set if CoIoT is disabled
a) Auto-CoIoT is set to false in the binding config - affects all things (default)
b) Enable CoIoT on the thing level is disabled

Usually you don’t need Action URLs when using CoIoT. CoIoT has various benefits, see README

markus7017 · December 10, 2020, 11:08am

What does that mean, please be more specific. I now that Alterco is still working to fix some issues in CoIoT, wich will be released soon (1.9.3), 1.9.3rc3 is available as a beta.

markus7017 · December 10, 2020, 11:28am

fyi: The current DEV build for 2.5 and 3.0 are in sync featurewise. However, I’m re-factoring the input handling, which also brings some other changes. First version is uploaded, @igor and @Rondal will help with testing. This will bring

Relay Channels will created dynamically based on features provided by the firmware level. This will dynamically adapt to number of relays (1 vs 2 vs 4 depending on device) and number of input channels (standard 1, Dimmer/1L have 2, iX3 has 3). This will also bring the lastEvent and eventCount channels for all devices running firmware 1.8+ (the channels will not be created for older firmwares)
The input channel handling itself (input channel, trigger channel, lastEvent, eventCount, lastUpdate) is re-factored to support the dynamic structure
There is one breaking change for Dimmer 1+2: Matching the number of inputs there are now 2 trigger channels (button1+button2), because each input channel specifically triggers events.
A work-around for Button-1 to suppress duplicate event triggers has been implemented. I’m still in contact with Alterco how to implement a clean solution, but current status is better than nothing. The problem has also confirmed by other projetcs.
Shelly UNI and 1L are now supported, maybe I’ll also add the G10 color bulb

I did initial testing, but of course the number of changes has bug potential. I would highly appreciate if more people could test with existing installations.

Status and timeline for 3.0 does not allow to bring in those changes in the initial 3.0 release. 3.0 will not include those features/changes. AFAIK feature freeze for OH 3.0 is planned end of the week. This means: No chance to run those changes through the PR process until then. Triggering the PR will be the next step, I expect a merge until end of the year, so this would be part of version 3.1 end of January.

manswiss · December 10, 2020, 12:01pm

Sorry, I’m trying to be more precise. I had the following two behaviors in the last 15 minutes:

shelly 1 (Current version: 20201124-091217/v1.9.0@57ac4ad8), has a trigger that 6 seconds after power on, it turns off again. After the command is sent from OpenHAB to switch on, this status remains in OpenHAB (switched on).
shelly 2.5 (Current version: 20201128-102046/v1.9.2@e83f7025). When the light is turned on with the wall switch, the status in OpenHAB remains off (and no events are fired).

I hope this is enough information.

manswiss · December 10, 2020, 12:04pm

Thank you very, very much for your big work.