Shelly Binding

markus7017 · December 3, 2020, 7:31pm

Could you please provide a TRACE log (ideally Shelly 1 only) with firmware 1.9.3rc3. I’m in contact with Alterco. From their point of view the problem should be fixed with this beta, if not we need additional information.

rickitaly · December 3, 2020, 8:00pm

Since 20:55:00.000 i’v been continuously switching the relay from mqtt and from phisical switch, this is all i have in the log TRACE for that specific Shelly1

2020-12-03 20:55:16.949 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Updating status
2020-12-03 20:55:16.952 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status
2020-12-03 20:55:17.057 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx","ip":"192.168.1.86","rssi":-60},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"20:55","unixtime":1607028917,"serial":57,"has_update":true,"mac":"xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":39188,"fs_size":233681,"fs_free":145580,"uptime":94470}
2020-12-03 20:55:17.060 [TRACE] [.internal.handler.ShellyRelayHandler] - shelly1-xxxxxx: Updating 1 relay(s)
2020-12-03 20:55:17.063 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status/relay/0
2020-12-03 20:55:17.210 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx","ip":"192.168.1.86","rssi":-60},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"20:55","unixtime":1607028917,"serial":57,"has_update":true,"mac":"xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":38980,"fs_size":233681,"fs_free":145580,"uptime":94470}

markus7017 · December 3, 2020, 10:34pm

I need the complete initialization

rickitaly · December 4, 2020, 7:06am

Since reboot this is it.
I tried to switch lights from both mqtt and switch.

2020-12-04 07:51:02.335 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Paris'.
2020-12-04 07:51:02.387 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'it_IT'.
2020-12-04 07:51:14.323 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'studio.items'
2020-12-04 07:51:16.279 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'shelly.things'
2020-12-04 07:51:16.419 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'brokers.things'
2020-12-04 07:51:19.559 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2020-12-04 07:51:19.962 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'lights.rules'
2020-12-04 07:51:24.289 [INFO ] [org.openhab.ui.internal.UIService   ] - Started UI on port 8080
2020-12-04 07:51:25.487 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Started HABPanel at /habpanel
2020-12-04 07:51:25.736 [DEBUG] [shelly.internal.ShellyHandlerFactory] - Activate Shelly HandlerFactory
2020-12-04 07:51:25.906 [DEBUG] [shelly.internal.ShellyHandlerFactory] - Using OH HTTP port 8080
2020-12-04 07:51:26.440 [DEBUG] [shelly.internal.ShellyHandlerFactory] - Shelly 1 Studio: Create new thing of type shelly:shelly1 using ShellyRelayHandler
2020-12-04 07:51:26.774 [DEBUG] [elly.internal.api.ShellyEventServlet] - ShellyEventServlet started at '/shelly/event'
2020-12-04 07:51:26.795 [DEBUG] [discovery.ShellyDiscoveryParticipant] - Activating ShellyDiscovery service
2020-12-04 07:51:27.099 [TRACE] [discovery.ShellyDiscoveryParticipant] - shelly1-xxxxxx: Shelly device discovered with empty IP address (service-name=[ServiceInfoImpl@6238859 name: 'shelly1-xxxxxx._http._tcp.local.' address: '(null):0' status: 'DNS: raspberrypi-lan.local. [raspberrypi.lan/192.168.1.192] state: probing 1 task: null', has NO data
	shelly1-xxxxxx._http._tcp.local.: ])
2020-12-04 07:51:27.111 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shelly1-xxxxxx: Shelly device discovered: IP-Adress=192.168.1.86, type=shelly1
2020-12-04 07:51:27.161 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/settings
2020-12-04 07:51:27.465 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to '192.168.1.221' with clientid fa1ceca3-1387-4a89-9b1b-8701d0ef0d0f
2020-12-04 07:51:27.890 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"device":{"type":"SHSW-1","mac":"CC50E3xxxxxx","hostname":"shelly1-xxxxxx","num_outputs":1},"wifi_ap":{"enabled":false,"ssid":"shelly1-xxxxxx","key":""},"wifi_sta":{"enabled":true,"ssid":"xxxxxx-xxxxxx","ipv4_method":"static","ip":"192.168.1.86","gw":"192.168.1.254","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.1.221:1883","user":"rick","id":"shelly1-xxxxxx","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":2,"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":null,"fw":"20201202-135844/v1.9.3-rc3@50c6ab57","factory_reset_from_switch":true,"discoverable":true,"build_info":{"build_id":"20201202-135844/v1.9.3-rc3@50c6ab57","build_timestamp":"2020-12-02T13:58:44Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Rome","lat":xx.xxxxxx,"lng":yy.yyyyyy,"tzautodetect":true,"tz_utc_offset":3600,"tz_dst":false,"tz_dst_auto":true,"time":"07:51","unixtime":1607068288,"ext_switch_enable":false,"ext_switch_reverse":false,"ext_switch":{"0":{"relay_num":-1}},"actions":{"active":false,"names":["btn_on_url","btn_off_url","longpush_url","shortpush_url","out_on_url","out_off_url","lp_on_url","lp_off_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_hum_over_url","ext_hum_under_url"]},"hwinfo":{"hw_revision":"prod-2018-08", "batch_id":2},"mode" :"relay","longpush_time":800,"relays":[{"name":null,"appliance_type":"General","ison":true,"has_timer":false,"default_state":"last","btn_type":"edge","btn_reverse":0,"auto_on":0.00,"auto_off":0.00,"power":0.00,"schedule":false,"schedule_rules":[]}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{}}
2020-12-04 07:51:27.922 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shelly1-xxxxxx: Shelly settings : {"device":{"type":"SHSW-1","mac":"CC50E3xxxxxx","hostname":"shelly1-xxxxxx","num_outputs":1},"wifi_ap":{"enabled":false,"ssid":"shelly1-xxxxxx","key":""},"wifi_sta":{"enabled":true,"ssid":"xxxxxx-xxxxxx","ipv4_method":"static","ip":"192.168.1.86","gw":"192.168.1.254","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.1.221:1883","user":"rick","id":"shelly1-xxxxxx","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":2,"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":null,"fw":"20201202-135844/v1.9.3-rc3@50c6ab57","factory_reset_from_switch":true,"discoverable":true,"build_info":{"build_id":"20201202-135844/v1.9.3-rc3@50c6ab57","build_timestamp":"2020-12-02T13:58:44Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Rome","lat":xx.xxxxxx,"lng":yy.yyyyyy,"tzautodetect":true,"tz_utc_offset":3600,"tz_dst":false,"tz_dst_auto":true,"time":"07:51","unixtime":1607068288,"ext_switch_enable":false,"ext_switch_reverse":false,"ext_switch":{"0":{"relay_num":-1}},"actions":{"active":false,"names":["btn_on_url","btn_off_url","longpush_url","shortpush_url","out_on_url","out_off_url","lp_on_url","lp_off_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_hum_over_url","ext_hum_under_url"]},"hwinfo":{"hw_revision":"prod-2018-08", "batch_id":2},"mode" :"relay","longpush_time":800,"relays":[{"name":null,"appliance_type":"General","ison":true,"has_timer":false,"default_state":"last","btn_type":"edge","btn_reverse":0,"auto_on":0.00,"auto_off":0.00,"power":0.00,"schedule":false,"schedule_rules":[]}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{}}
2020-12-04 07:51:27.925 [TRACE] [discovery.ShellyDiscoveryParticipant] - shelly1-xxxxxx: thingType=shelly1, deviceType=SHSW-1, mode=relay, symbolic name=
2020-12-04 07:51:27.929 [DEBUG] [discovery.ShellyDiscoveryParticipant] - shelly1-xxxxxx: Adding Shelly , UID=shelly:shelly1:xxxxxx
2020-12-04 07:51:28.515 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Thing name derived from UID shelly:shelly1:xxxxxx
2020-12-04 07:51:28.519 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Device config: IP address=192.168.1.86, HTTP user/password=<non>/<none>, update interval=60
2020-12-04 07:51:28.521 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Configured Events: Button: false, Switch (on/off): false, Push: false, Roller: true, Sensor: true, CoIoT: true, Enable AutoCoIoT: true
2020-12-04 07:51:28.529 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Start initializing thing Shelly 1 Studio, type shelly1, ip address 192.168.1.86, CoIoT: true
2020-12-04 07:51:28.534 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/shelly
2020-12-04 07:51:28.561 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"type":"SHSW-1","mac":"CC50E3xxxxxx","auth":false,"fw":"20201202-135844/v1.9.3-rc3@50c6ab57","num_outputs":1}
2020-12-04 07:51:28.564 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/settings
2020-12-04 07:51:28.797 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"device":{"type":"SHSW-1","mac":"CC50E3xxxxxx","hostname":"shelly1-xxxxxx","num_outputs":1},"wifi_ap":{"enabled":false,"ssid":"shelly1-xxxxxx","key":""},"wifi_sta":{"enabled":true,"ssid":"xxxxxx-xxxxxx","ipv4_method":"static","ip":"192.168.1.86","gw":"192.168.1.254","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.1.221:1883","user":"rick","id":"shelly1-xxxxxx","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":2,"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":null,"fw":"20201202-135844/v1.9.3-rc3@50c6ab57","factory_reset_from_switch":true,"discoverable":true,"build_info":{"build_id":"20201202-135844/v1.9.3-rc3@50c6ab57","build_timestamp":"2020-12-02T13:58:44Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Rome","lat":xx.xxxxxx,"lng":yy.yyyyyy,"tzautodetect":true,"tz_utc_offset":3600,"tz_dst":false,"tz_dst_auto":true,"time":"07:51","unixtime":1607068289,"ext_switch_enable":false,"ext_switch_reverse":false,"ext_switch":{"0":{"relay_num":-1}},"actions":{"active":false,"names":["btn_on_url","btn_off_url","longpush_url","shortpush_url","out_on_url","out_off_url","lp_on_url","lp_off_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_hum_over_url","ext_hum_under_url"]},"hwinfo":{"hw_revision":"prod-2018-08", "batch_id":2},"mode" :"relay","longpush_time":800,"relays":[{"name":null,"appliance_type":"General","ison":true,"has_timer":false,"default_state":"last","btn_type":"edge","btn_reverse":0,"auto_on":0.00,"auto_off":0.00,"power":0.00,"schedule":false,"schedule_rules":[]}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{}}
2020-12-04 07:51:28.814 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Initializing device shelly1-xxxxxx, type SHSW-1, Hardware: Rev: prod-2018-08, batch 2; Firmware: v1.9.3-rc3 / 20201202-135844 (50c6ab57)
2020-12-04 07:51:28.816 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Shelly settings info for shelly1-xxxxxx: {"device":{"type":"SHSW-1","mac":"CC50E3xxxxxx","hostname":"shelly1-xxxxxx","num_outputs":1},"wifi_ap":{"enabled":false,"ssid":"shelly1-xxxxxx","key":""},"wifi_sta":{"enabled":true,"ssid":"xxxxxx-xxxxxx","ipv4_method":"static","ip":"192.168.1.86","gw":"192.168.1.254","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.1.221:1883","user":"rick","id":"shelly1-xxxxxx","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":2,"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":null,"fw":"20201202-135844/v1.9.3-rc3@50c6ab57","factory_reset_from_switch":true,"discoverable":true,"build_info":{"build_id":"20201202-135844/v1.9.3-rc3@50c6ab57","build_timestamp":"2020-12-02T13:58:44Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Rome","lat":xx.xxxxxx,"lng":yy.yyyyyy,"tzautodetect":true,"tz_utc_offset":3600,"tz_dst":false,"tz_dst_auto":true,"time":"07:51","unixtime":1607068289,"ext_switch_enable":false,"ext_switch_reverse":false,"ext_switch":{"0":{"relay_num":-1}},"actions":{"active":false,"names":["btn_on_url","btn_off_url","longpush_url","shortpush_url","out_on_url","out_off_url","lp_on_url","lp_off_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_hum_over_url","ext_hum_under_url"]},"hwinfo":{"hw_revision":"prod-2018-08", "batch_id":2},"mode" :"relay","longpush_time":800,"relays":[{"name":null,"appliance_type":"General","ison":true,"has_timer":false,"default_state":"last","btn_type":"edge","btn_reverse":0,"auto_on":0.00,"auto_off":0.00,"power":0.00,"schedule":false,"schedule_rules":[]}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{}}
2020-12-04 07:51:28.819 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Device hasRelays:true (numRelays=1),isRoller:false (numRoller=0),isDimmer:false,numMeter=0,isEMeter:false),isSensor:false,isDS:false,hasBattery:false,isSense:false,isLight:false,isBulb:false,isDuo:false,isRGBW2:false,inColor:false,updatePeriod:70sec
2020-12-04 07:51:28.821 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status
2020-12-04 07:51:28.929 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Updated serrviceName to shelly1-xxxxxx
2020-12-04 07:51:28.953 [INFO ] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Device is running a Beta version: v1.9.3-rc3/20201202-135844 (50c6ab57),make sure this is newer than v1.5.7 release build.
2020-12-04 07:51:28.957 [INFO ] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: INFO: New firmware available: current version: 20201202-135844/v1.9.3-rc3@50c6ab57, new version: 20201124-091217/v1.9.0@57ac4ad8
2020-12-04 07:51:28.959 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Auto-CoIoT is enabled, disabling action urls
2020-12-04 07:51:28.963 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Updating channel definitions, 2 channels
2020-12-04 07:51:28.966 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Adding channel device#updateAvailable
2020-12-04 07:51:28.969 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Adding channel device#heartBeat
2020-12-04 07:51:28.987 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Channel definitions updated
2020-12-04 07:51:29.012 [DEBUG] [lly.internal.util.ShellyChannelCache] - shelly1-xxxxxx: Channel device#alarm updated with NONE (type class org.openhab.core.library.types.StringType).
2020-12-04 07:51:29.016 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Starting CoIoT (autoCoIoT=true/true)
2020-12-04 07:51:29.019 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx: Starting CoAP Listener
2020-12-04 07:51:29.021 [DEBUG] [helly.internal.coap.ShellyCoapServer] - Initializing CoIoT listener (local IP=192.168.1.192:5683)
2020-12-04 07:51:29.140 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Thing successfully initialized.
2020-12-04 07:51:29.170 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Update status job started, interval=20*3=60sec.
2020-12-04 07:51:29.217 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx: CoIoT Message from /192.168.1.86:5683 (MID=13052): {"blk":[{"I":1,"D":"relay_0"},{"I":2,"D":"sensor_0"},{"I":3,"D":"sensor_1"},{"I":4,"D":"sensor_2"},{"I":5,"D":"device"}],"sen":[{"I":9103,"T":"EVC","D":"cfgChanged","R":"U16","L":5},{"I":1101,"T":"S","D":"output","R":"0/1","L":1},{"I":2101,"T":"S","D":"input","R":"0/1","L":1},{"I":2102,"T":"EV","D":"inputEvent","R":["S/L",""],"L":1},{"I":2103,"T":"EVC","D":"inputEventCnt","R":"U16","L":1},{"I":3101,"T":"T","D":"extTemp","U":"C","R":["-55/125","999"],"L":2},{"I":3102,"T":"T","D":"extTemp","U":"F","R":["-67/257","999"],"L":2},{"I":3201,"T":"T","D":"extTemp","U":"C","R":["-55/125","999"],"L":3},{"I":3202,"T":"T","D":"extTemp","U":"F","R":["-67/257","999"],"L":3},{"I":3301,"T":"T","D":"extTemp","U":"C","R":["-55/125","999"],"L":4},{"I":3302,"T":"T","D":"extTemp","U":"F","R":["-67/257","999"],"L":4},{"I":3103,"T":"H","D":"humidity","R":["0/100","999"],"L":2},{"I":3117,"T":"S","D":"extInput","R":"0/1","L":2}]}
2020-12-04 07:51:29.227 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Properties updated
2020-12-04 07:51:29.231 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx: CoIoT Version 2 detected
2020-12-04 07:51:29.236 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx: CoIoT Device Description for SHSW-1#xxxxxx#2: {"blk":[{"I":1,"D":"relay_0"},{"I":2,"D":"sensor_0"},{"I":3,"D":"sensor_1"},{"I":4,"D":"sensor_2"},{"I":5,"D":"device"}],"sen":[{"I":9103,"T":"EVC","D":"cfgChanged","R":"U16","L":5},{"I":1101,"T":"S","D":"output","R":"0/1","L":1},{"I":2101,"T":"S","D":"input","R":"0/1","L":1},{"I":2102,"T":"EV","D":"inputEvent","R":["S/L",""],"L":1},{"I":2103,"T":"EVC","D":"inputEventCnt","R":"U16","L":1},{"I":3101,"T":"T","D":"extTemp","U":"C","R":["-55/125","999"],"L":2},{"I":3102,"T":"T","D":"extTemp","U":"F","R":["-67/257","999"],"L":2},{"I":3201,"T":"T","D":"extTemp","U":"C","R":["-55/125","999"],"L":3},{"I":3202,"T":"T","D":"extTemp","U":"F","R":["-67/257","999"],"L":3},{"I":3301,"T":"T","D":"extTemp","U":"C","R":["-55/125","999"],"L":4},{"I":3302,"T":"T","D":"extTemp","U":"F","R":["-67/257","999"],"L":4},{"I":3103,"T":"H","D":"humidity","R":["0/100","999"],"L":2},{"I":3117,"T":"S","D":"extInput","R":"0/1","L":2}]}
2020-12-04 07:51:29.240 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id=1: relay_0
2020-12-04 07:51:29.241 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id=2: sensor_0
2020-12-04 07:51:29.243 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id=3: sensor_1
2020-12-04 07:51:29.244 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id=4: sensor_2
2020-12-04 07:51:29.245 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id=5: device
2020-12-04 07:51:29.253 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Properties updated
2020-12-04 07:51:29.254 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx: Adding 13 sensor definitions
2020-12-04 07:51:29.255 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 9103: cfgChanged, Type=EVC, Range=U16, Links=5
2020-12-04 07:51:29.256 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 1101: output, Type=S, Range=0/1, Links=1
2020-12-04 07:51:29.257 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 2101: input, Type=S, Range=0/1, Links=1
2020-12-04 07:51:29.259 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 2102: inputEvent, Type=EV, Range=S/L;, Links=1
2020-12-04 07:51:29.260 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 2103: inputEventCnt, Type=EVC, Range=U16, Links=1
2020-12-04 07:51:29.261 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 3101: extTemp, Type=T, Range=-55/125;999, Links=2
2020-12-04 07:51:29.262 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 3102: extTemp, Type=T, Range=-67/257;999, Links=2
2020-12-04 07:51:29.263 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 3201: extTemp, Type=T, Range=-55/125;999, Links=3
2020-12-04 07:51:29.264 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 3202: extTemp, Type=T, Range=-67/257;999, Links=3
2020-12-04 07:51:29.265 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 3301: extTemp, Type=T, Range=-55/125;999, Links=4
2020-12-04 07:51:29.266 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 3302: extTemp, Type=T, Range=-67/257;999, Links=4
2020-12-04 07:51:29.267 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 3103: humidity, Type=H, Range=0/100;999, Links=2
2020-12-04 07:51:29.268 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:    id 3117: extInput, Type=S, Range=0/1, Links=2
2020-12-04 07:51:29.439 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx: CoIoT Message from /192.168.1.86:5683 (MID=13053): {"G":[[0,9103,1],[0,1101,1],[0,2101,0],[0,2102,""],[0,2103,0]]}
2020-12-04 07:51:29.441 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx: CoIoT Sensor data {"G":[[0,9103,1],[0,1101,1],[0,2101,0],[0,2102,""],[0,2103,0]]} (serial=15872)
2020-12-04 07:51:29.442 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx: 5 CoAP sensor updates received
2020-12-04 07:51:29.443 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:  Sensor value[0]: id=9103, Value=1.0 (cfgChanged, Type=EVC, Range=U16, Link=5: device)
2020-12-04 07:51:29.445 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:  Sensor value[1]: id=1101, Value=1.0 (output, Type=S, Range=0/1, Link=1: relay_0)
2020-12-04 07:51:29.446 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:  Sensor value[2]: id=2101, Value=0.0 (input, Type=S, Range=0/1, Link=1: relay_0)
2020-12-04 07:51:29.447 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:  Sensor value[3]: id=2102, Value=-1.0 (inputEvent, Type=EV, Range=S/L;, Link=1: relay_0)
2020-12-04 07:51:29.449 [TRACE] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx:  Sensor value[4]: id=2103, Value=0.0 (inputEventCnt, Type=EVC, Range=U16, Link=1: relay_0)
2020-12-04 07:51:29.459 [DEBUG] [lly.internal.util.ShellyChannelCache] - shelly1-xxxxxx: Channel relay#output updated with ON (type class org.openhab.core.library.types.OnOffType).
2020-12-04 07:51:29.460 [DEBUG] [elly.internal.coap.ShellyCoapHandler] - shelly1-xxxxxx: 1 channels updated from CoIoT status, serial=15872
2020-12-04 07:51:31.167 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/settings
2020-12-04 07:51:31.401 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"device":{"type":"SHSW-1","mac":"CC50E3xxxxxx","hostname":"shelly1-xxxxxx","num_outputs":1},"wifi_ap":{"enabled":false,"ssid":"shelly1-xxxxxx","key":""},"wifi_sta":{"enabled":true,"ssid":"xxxxxx-xxxxxx","ipv4_method":"static","ip":"192.168.1.86","gw":"192.168.1.254","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.1.221:1883","user":"rick","id":"shelly1-xxxxxx","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":2,"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":null,"fw":"20201202-135844/v1.9.3-rc3@50c6ab57","factory_reset_from_switch":true,"discoverable":true,"build_info":{"build_id":"20201202-135844/v1.9.3-rc3@50c6ab57","build_timestamp":"2020-12-02T13:58:44Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Rome","lat":xx.xxxxxx,"lng":yy.yyyyyy,"tzautodetect":true,"tz_utc_offset":3600,"tz_dst":false,"tz_dst_auto":true,"time":"07:51","unixtime":1607068292,"ext_switch_enable":false,"ext_switch_reverse":false,"ext_switch":{"0":{"relay_num":-1}},"actions":{"active":false,"names":["btn_on_url","btn_off_url","longpush_url","shortpush_url","out_on_url","out_off_url","lp_on_url","lp_off_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_temp_over_url","ext_temp_under_url","ext_hum_over_url","ext_hum_under_url"]},"hwinfo":{"hw_revision":"prod-2018-08", "batch_id":2},"mode" :"relay","longpush_time":800,"relays":[{"name":null,"appliance_type":"General","ison":true,"has_timer":false,"default_state":"last","btn_type":"edge","btn_reverse":0,"auto_on":0.00,"auto_off":0.00,"power":0.00,"schedule":false,"schedule_rules":[]}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{}}
2020-12-04 07:51:31.426 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Updating status
2020-12-04 07:51:31.438 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status
2020-12-04 07:51:31.532 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-60},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:51","unixtime":1607068292,"serial":62,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":38652,"fs_size":233681,"fs_free":145580,"uptime":133843}
2020-12-04 07:51:31.537 [TRACE] [.internal.handler.ShellyRelayHandler] - shelly1-xxxxxx: Updating 1 relay(s)
2020-12-04 07:51:31.540 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status/relay/0
2020-12-04 07:51:31.691 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-59},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:51","unixtime":1607068292,"serial":62,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":38652,"fs_size":233681,"fs_free":145580,"uptime":133843}
2020-12-04 07:51:31.710 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: 3 more updates requested
2020-12-04 07:51:34.712 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Updating status
2020-12-04 07:51:34.714 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status
2020-12-04 07:51:34.818 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-60},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:51","unixtime":1607068295,"serial":62,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":38616,"fs_size":233681,"fs_free":145580,"uptime":133847}
2020-12-04 07:51:34.822 [TRACE] [.internal.handler.ShellyRelayHandler] - shelly1-xxxxxx: Updating 1 relay(s)
2020-12-04 07:51:34.824 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status/relay/0
2020-12-04 07:51:34.968 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-60},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:51","unixtime":1607068295,"serial":62,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":38648,"fs_size":233681,"fs_free":145580,"uptime":133847}
2020-12-04 07:51:34.971 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: 2 more updates requested
2020-12-04 07:51:37.973 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Updating status
2020-12-04 07:51:37.975 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status
2020-12-04 07:51:38.079 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-59},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:51","unixtime":1607068298,"serial":62,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":38656,"fs_size":233681,"fs_free":145580,"uptime":133850}
2020-12-04 07:51:38.083 [TRACE] [.internal.handler.ShellyRelayHandler] - shelly1-xxxxxx: Updating 1 relay(s)
2020-12-04 07:51:38.086 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status/relay/0
2020-12-04 07:51:38.209 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-60},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:51","unixtime":1607068298,"serial":62,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":38640,"fs_size":233681,"fs_free":145580,"uptime":133850}
2020-12-04 07:51:38.215 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: 1 more updates requested
2020-12-04 07:51:41.217 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Updating status
2020-12-04 07:51:41.218 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status
2020-12-04 07:51:41.318 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-62},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:51","unixtime":1607068302,"serial":62,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":38652,"fs_size":233681,"fs_free":145580,"uptime":133853}
2020-12-04 07:51:41.320 [TRACE] [.internal.handler.ShellyRelayHandler] - shelly1-xxxxxx: Updating 1 relay(s)
2020-12-04 07:51:41.322 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status/relay/0
2020-12-04 07:51:41.431 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-62},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:51","unixtime":1607068302,"serial":62,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":38636,"fs_size":233681,"fs_free":145580,"uptime":133853}
2020-12-04 07:51:41.442 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: 0 more updates requested
2020-12-04 07:52:29.451 [TRACE] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Updating status
2020-12-04 07:52:29.453 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status
2020-12-04 07:52:29.553 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-67},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:52","unixtime":1607068350,"serial":66,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":39228,"fs_size":233681,"fs_free":145580,"uptime":133901}
2020-12-04 07:52:29.558 [TRACE] [.internal.handler.ShellyRelayHandler] - shelly1-xxxxxx: Updating 1 relay(s)
2020-12-04 07:52:29.560 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP GET for http://192.168.1.86/status/relay/0
2020-12-04 07:52:29.720 [TRACE] [ng.shelly.internal.api.ShellyHttpApi] - shelly1-xxxxxx: HTTP Response 200: {"wifi_sta":{"connected":true,"ssid":"xxxxxx-xxxxxx","ip":"192.168.1.86","rssi":-66},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"07:52","unixtime":1607068350,"serial":66,"has_update":true,"mac":"CC50E3xxxxxx","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"mqtt"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"pending","has_update":true,"new_version":"20201124-091217/v1.9.0@57ac4ad8","old_version":"20201202-135844/v1.9.3-rc3@50c6ab57","beta_version":"20201202-135844/v1.9.3-rc3@50c6ab57"},"ram_total":50736,"ram_free":39028,"fs_size":233681,"fs_free":145580,"uptime":133901}
2020-12-04 07:52:29.727 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shelly1-xxxxxx: Enabling channel cache (20 updates / 60s)

markus7017 · December 4, 2020, 2:20pm

I updated the DEV build implementing a work around for duplicate Button-1 events (##9216) when the button is waking up from sleep mode and sends an event. I also opened a ticket with Alterco, because this is a firmware bug from my point of view. Check latest DEV build

markus7017 · December 4, 2020, 2:29pm

What I see

Device gets discoverd
Coap initialization, discovery response is received
1 Coap status update
nor more Coap status updates!

Where you able to test with firmware 1.9.2 (it might not be offered by the device UI) or 1.9.3rc3?

rickitaly · December 4, 2020, 3:14pm

i was switching several times
This is 1.9.3rc3, i can’t find the string to force 1.9.2 update (actually not offered on any shelly1 on my network).

marian.tudor · December 4, 2020, 7:53pm

Hello,
I kindly ask you for some help regarding the integration of Shelly H&T sensors. I’m using RPI3B with openhab release openHAB 2.5.10-1. Shelly H&T firmware ver. (v1.9.0/20201124-091711). After I followed the steps presented below:

Upgrade to OH 2.5.10, do not install the Shelly binding
Install the DEV build (2.5.11 see below)
Upgrade the HT to firmware 1.9
The device must be waked up to run inclusion, press the button, LED starts to blink
Run the discovery in OH, HT should show up in the Inbox
Add the think and link the channels as you like
I got the values of sensors temperature thought the items but I didn’t received after that any updates.
Also I counted a strange behavior after a reboot of OH. If I will press the weak up button immediately after the Shelly OH thinks will be loaded I can see some messages coming from Shelly sensors. But this “windows time of communication” it’s about less then 1 min. If I’ll miss it no messages I will see if I will press the button again. You can see below some of the messages:

2020-12-04 13:26:57.706 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘shelly:shellyht:f2b670’ to inbox.
2020-12-04 13:26:57.707 [home.event.InboxAddedEvent] - Discovery Result with UID ‘shelly:shellyht:f2b670’ has been added.
2020-12-04 14:26:11.630 [me.event.InboxRemovedEvent] - Discovery Result with UID ‘shelly:shellyht:f2b670’ has been removed.
2020-12-04 14:26:11.714 [hingStatusInfoChangedEvent] - ‘shelly:shellyht:f2b670’ changed from UNINITIALIZED to INITIALIZING
2020-12-04 14:26:13.764 [hingStatusInfoChangedEvent] - ‘shelly:shellyht:f2b670’ changed from INITIALIZING to UNKNOWN
2020-12-04 14:26:13.814 [me.event.ThingUpdatedEvent] - Thing ‘shelly:shellyht:f2b670’ has been updated.
2020-12-04 14:26:13.820 [hingStatusInfoChangedEvent] - ‘shelly:shellyht:f2b670’ changed from UNKNOWN to ONLINE
2020-12-04 14:26:13.839 [me.event.ThingUpdatedEvent] - Thing ‘shelly:shellyht:f2b670’ has been updated.
2020-12-04 14:26:13.908 [vent.ChannelTriggeredEvent] - shelly:shellyht:f2b670:device#alarm triggered BUTTON
2020-12-04 14:26:14.017 [me.event.ThingUpdatedEvent] - Thing ‘shelly:shellyht:f2b670’ has been updated.
2020-12-04 14:26:14.036 [me.event.ThingUpdatedEvent] - Thing ‘shelly:shellyht:f2b670’ has been updated.
2020-12-04 14:26:14.054 [me.event.ThingUpdatedEvent] - Thing ‘shelly:shellyht:f2b670’ has been updated.
2020-12-04 14:26:16.364 [me.event.ThingUpdatedEvent] - Thing ‘shelly:shellyht:f2b670’ has been updated.
2020-12-04 14:26:16.388 [vent.ChannelTriggeredEvent] - shelly:shellyht:f2b670:device#alarm triggered BUTTON
2020-12-04 14:26:19.515 [vent.ChannelTriggeredEvent] - shelly:shellyht:f2b670:device#alarm triggered BUTTON
2020-12-04 14:32:49.620 [.ItemChannelLinkAddedEvent] - Link ‘ShellyHT_Ferolli_Temp-shelly:shellyht:f2b670:sensors#temperature’ has been added.
2020-12-04 14:32:49.629 [.ItemChannelLinkAddedEvent] - Link ‘ShellyHT_Ferolli_Humid-shelly:shellyht:f2b670:sensors#humidity’ has been added.
2020-12-04 14:32:49.654 [.ItemChannelLinkAddedEvent] - Link ‘ShellyHT_Ferolli_Batt-shelly:shellyht:f2b670:battery#batteryLevel’ has been added.
2020-12-04 14:34:29.241 [.ItemChannelLinkAddedEvent] - Link ‘ShellyHT_Container_Temp-shelly:shellyht:f2b670:sensors#temperature’ has been added.
2020-12-04 14:34:29.244 [.ItemChannelLinkAddedEvent] - Link ‘ShellyHT_Container_Humid-shelly:shellyht:f2b670:sensors#humidity’ has been added.
2020-12-04 14:34:29.253 [.ItemChannelLinkAddedEvent] - Link ‘ShellyHT_Container_Batt-shelly:shellyht:f2b670:battery#batteryLevel’ has been added.
2020-12-04 16:10:20.813 [hingStatusInfoChangedEvent] - ‘shelly:shellyht:f2b670’ changed from UNINITIALIZED to INITIALIZING
2020-12-04 16:10:22.830 [hingStatusInfoChangedEvent] - ‘shelly:shellyht:f2b670’ changed from INITIALIZING to UNKNOWN
2020-12-04 16:10:56.783 [hingStatusInfoChangedEvent] - ‘shelly:shellyht:f2b670’ changed from UNKNOWN to ONLINE
2020-12-04 16:10:56.897 [vent.ChannelTriggeredEvent] - shelly:shellyht:f2b670:device#alarm triggered BUTTON
2020-12-04 16:10:58.435 [me.event.ThingUpdatedEvent] - Thing ‘shelly:shellyht:f2b670’ has been updated.
2020-12-04 16:10:58.474 [me.event.ThingUpdatedEvent] - Thing ‘shelly:shellyht:f2b670’ has been updated.
2020-12-04 16:10:58.699 [vent.ChannelTriggeredEvent] - shelly:shellyht:f2b670:device#alarm triggered BUTTON
2020-12-04 16:11:01.846 [vent.ChannelTriggeredEvent] - shelly:shellyht:f2b670:device#alarm triggered BUTTON

Thank you in advance!

RafalO · December 4, 2020, 9:31pm

I have exactly the same configuration but with installed shelly binding (why not to install from repository/Paper UI?) using ColoT and my Shelly H&T has been working since a few weeks. Bear in mind that as Shelly H&T is powerd by battery (unless you are using USB?) an udpate might be only when temperature or humidity changes within set threshold. Check if you have the same values in OH2 and Shelly app.

I am planning to upgrade to 2.5.11…we will see then

RafalO · December 4, 2020, 9:47pm

Hi Markus,
Thanks to your binding I have bought and I am using Shelly 1, Shelly 2,5 (switch), Shelly H&T and Shelly Door Window. All work correctly OH 2.5.10-1 and recent shelly firmware
Interesting to notice that in case of Shelly Door Window your binding is better than shelly app for Android
I have set up a fixed IP address for Shelly Door Window which precludes shelly cloud. In consequence I can not read any data in Shelly for Android (it is empty not updated) but it works since the data is updated in Openhab . Seems like a bug in Alterco software for which a workaround is the usage of openhab.

markus7017 · December 4, 2020, 11:00pm

marian.tudor · December 5, 2020, 6:35am

Hi Rafal,

Thank you for your prompt answer. I omitted to write in the first post that the same test I done on 2 RPIs with the same HDD and OH release an is working on one (received messages when I press the bottom and when temperature is changing) but not on the one with the presented symptoms…

rickitaly · December 5, 2020, 5:31pm

I continue to investigate on my issue, it seems that after i reboot the device, as soon as i get the initialization event messages, the coap events are working for few seconds (i switch the light from mqtt and the item get updated). I can do 3 times switch and then it stops triggering the event.
This is the boot sequence messages, taken from Event Monitor (very interesting!)

After this happens, i have just few seconds where the item is getting updated correctly with the status, and it switch max 3 times.
After that, the update stops in a state and gets update only with 1min frequency (no more events).

markus7017 · December 6, 2020, 8:10am

Are you on OH3?

rickitaly · December 6, 2020, 9:21am

Yes I wrote it un my first post about this problem, it’s an OH3 test environment.

Rondal · December 6, 2020, 8:38pm

Hi Markus,

thanks for your amazing work so far I’ve upgraded to your recent 2.5.11 DEV build today to see if the 4 Shelly 1L I got my hands on work with that. But after upgrading I noticed that I’m no longer receiving Button events from any of the Shellies I have in use (there are others beside the 1L).

I tried to debug that a little bit but I’m at a loss of ideas what’s wrong. No one else seems to experience this.

Attached you’ll find a log of me removing one of the Shelly 1 (not in the log), then letting autodiscover find it (begin of log), inclusion, 2 minutes of stabilizing and then me hitting one of the attached buttons (short and long presses).

I’m happy to open a bug or contribute any more info. Just not sure if I should open it on the openhab repo or the one where you share the DEV build.

LOG…
Sorry, I just discovered that I’m not allowed to post it here. Well… here it is: https://pastebin.com/UcELnvyQ

Something I noticed that it seems to be ignoring much events with the same serial. But that serial isn’t changing very often.

Thanks for any input!

markus7017 · December 7, 2020, 1:56pm

Hi Stefan,

thanks for contributing. Please keep in mind: The DEV build is a snapshot of the latest development.
The good: You get access to the latest updates, esp. supporting new devices and firmware versions
The bad: Making a change here, may break a change there

Some insights

Per spec it’s defined that 2 packet with the same serial are duplicates. Therefore the binding uses this as a filter to discard those (as required by the specification). The serial is increased every time the device detected a change to the status data, it stays the same when “nothing happens”. In this case it’s kind of a keep-alive signal (so you could detect dead devices).
The Shelly Button-1 has the problem that it “looses” the serial and event count information when going to sleep mode. After wake-up it always starts with serial 1 and event count 1. For whatever reasons it sends a duplicate and I need to find a work around detecting those.
I just made a change there, this might broke the general trigger handling (button channel) for non Button-1 devices. It’s a petty. I’m already in contact with Alterco to convince them that this is from my point of view a firmware bug. It seems that it has something to do with Cloud functionality, but I have disabled the Cloud feature on all my devices and expect purely local communication conforming the spec.

Another problem: I’m prepare the PR to get the updated code in the 3.0 distro (which will not work for the 1st 3.0 release), which requires code changes related to changes in 3.0. Again a chance to break working functionality. Not nice, but we have to go through this as a team. You could help with testing, I need to do the work

Long story short: I’ll look into you log and do some testing myself. Maybe it’s a small change to bring the functionality back.

Does someone else experience the same problem?

Rondal · December 7, 2020, 2:27pm

Hi Markus,

thanks for letting me know. Not sure if it helps you with debugging, but I have devices where the events arrive as usual.

Shelly 1, Shelly 2.5, Shelly 1L -> Affected by the described problem
Shelly Plug(S) -> Not affected
Shelly Button 1 -> Not affected but the fix for the wake-up problem doesn’t seem to work in my environment

As for the Button 1: I noticed that behavior also yesterday but was unsure if the fix from https://github.com/markus7017/openhab-addons/commit/6301dbacd2611a682cd9533a97ee2a8eac3a7750 was already in your 2 day (now 3 day) old DEV build. I’ll try to confirm if the fix is working or not and create some logs if necessary.

If you can provide the dev build one version older (with Shelly 1L, without button fix) I could verify that the observed behavior was introduced by that. <-- Edit: Nevermind. That was just me not thinking about using the version control features of a version control system. I’ll try with the Nov 29 version.

Edit/Update:

If I can help with troubleshooting I’ll happily do. Below a list of available devices in use with OH. Beside that I’ve a couple of S1, S1L, S1D for testing purposes lying around.

shelly:shelly1pm:76e00b (Type=Thing, Status=UNKNOWN, Label=Shelly 1PM 1, Bridge=null)
shelly:shelly25-roller:68c63af9be56 (Type=Thing, Status=ONLINE, Label=Shelly - S25-2, Bridge=null)
shelly:shellyplugs:9a4136 (Type=Thing, Status=ONLINE, Label=Shelly - SPS-1, Bridge=null)
shelly:shelly1:c4ed5e (Type=Thing, Status=ONLINE, Label=Shelly - S1-1, Bridge=null)
shelly:shelly1:40f520014507 (Type=Thing, Status=ONLINE, Label=Shelly - S1-4, Bridge=null)
shelly:shellybutton1:483fda6f9e8f (Type=Thing, Status=ONLINE, Label=Shelly - SB-1, Bridge=null)
shelly:shellyht:9558b4 (Type=Thing, Status=ONLINE, Label=Shelly - SHT-2, Bridge=null)
shelly:shellyplugs:168049 (Type=Thing, Status=ONLINE, Label=Shelly - SPS-4, Bridge=null)
shelly:shelly25-roller:40f5200561d9 (Type=Thing, Status=ONLINE, Label=Shelly - S25-1, Bridge=null)
shelly:shelly1l:84cca8ad9f78 (Type=Thing, Status=ONLINE, Label=Shelly - S1L-3, Bridge=null)
shelly:shellybutton1:483fda6f1f60 (Type=Thing, Status=ONLINE, Label=Shelly - SB-2, Bridge=null)
shelly:shelly1:40f520169316 (Type=Thing, Status=ONLINE, Label=Shelly - S1-3, Bridge=null)
shelly:shellyplug:6cc6f8 (Type=Thing, Status=ONLINE, Label=Shelly - SP-1, Bridge=null)
shelly:shelly1l:84cca8ad9d5d (Type=Thing, Status=ONLINE, Label=Shelly - S1L-4, Bridge=null)
shelly:shellyplugs:9dd3aa (Type=Thing, Status=ONLINE, Label=Shelly - SPS-3, Bridge=null)
shelly:shellyht:91e268 (Type=Thing, Status=ONLINE, Label=Shelly - SHT-1, Bridge=null)
shelly:shellyplugs:977516 (Type=Thing, Status=ONLINE, Label=Shelly - SPS-2, Bridge=null)
shelly:shelly1:a4cf12f465dd (Type=Thing, Status=ONLINE, Label=Shelly - S1-2, Bridge=null)

Rondal · December 7, 2020, 4:05pm

Just checked. Nov 29 version working like a charm

2020-12-07 15:58:10.794 [vent.ChannelTriggeredEvent] - shelly:shelly1:40f520169316:relay#button triggered LONG_PRESSED

I’ll try to pinpoint my Button-1 issues a little more.

markus7017 · December 7, 2020, 4:48pm

I’m so upset on this issue. If you get Button-1 work you break the others and vice versa
I need to debug that crap again. Please check the latest build with Button-1