search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
69.83k stars 28.94k forks source link

Issues upgrading from 0.91.4 to 0.92.2 (ASUS Tinkerboard) #23652

Closed Paul-dH closed 5 years ago

Paul-dH commented 5 years ago

Home Assistant release with the issue: 0.92.2

Last working Home Assistant release (if known): 0.91.4

Operating environment (Hass.io/Docker/Windows/etc.): ASUS Tinkerboard, TinkerOS with Hassio

Component/platform: FrondEnd not visible

Description of problem: Supervisor downloades the latest version of the image and removes the old one: homeassistant/tinker-homeassistant:latest

After this the old container is dropped and a new one with the new image is created.

When the new container starts, it stops after loading the binary sensors and nothing happens....

At this moment the installation is bricked and I decided to pull the latest working image and add the tag latest to it:

> sudo docker pull homeassistant/tinker-homeassistant:0.91.4
- In Portainer -> Add Tag: homeassistant/tinker-homeassistant:latest to image
- Remove the current bricked container
- Restart the Supervisor container or the whole system
- Then the homeassistant container gets created again with the older image version.

The latest attempt resulted in a automatic roll back to version 0.91.4 and logged a warning: Ended unfinished session (id=2 from 2019-05-03 15:47:52.695133)

> home-assistant.log

2019-05-03 14:59:04 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for custom_updater which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2019-05-03 14:59:04 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.
2019-05-03 14:59:04 INFO (MainThread) [homeassistant.setup] Setting up http
2019-05-03 14:59:04 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.0 seconds.
2019-05-03 14:59:04 INFO (MainThread) [homeassistant.setup] Setting up system_log
2019-05-03 14:59:04 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up recorder
2019-05-03 14:59:05 WARNING (Recorder) [homeassistant.components.recorder] Ended unfinished session (id=105 from 2019-05-03 12:55:07.769160)
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 0.6 seconds.
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up sun
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.0 seconds.
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up shell_command
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setup of domain shell_command took 0.0 seconds.
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up zone
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setup of domain zone took 0.0 seconds.
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up deconz
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setup of domain deconz took 0.0 seconds.
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up custom_updater
2019-05-03 14:59:05 INFO (MainThread) [custom_components.custom_updater] if you have ANY issues with this, please report them here: https://github.com/custom-components/custom_updater
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setup of domain custom_updater took 0.0 seconds.
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up lovelace
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.0 seconds.
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up speedtestdotnet
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setup of domain speedtestdotnet took 0.0 seconds.
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up input_select
2019-05-03 14:59:05 INFO (SyncWorker_14) [homeassistant.loader] Loaded sensor from custom_components.ovapi
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setting up zwave
2019-05-03 14:59:05 INFO (MainThread) [homeassistant.setup] Setup of domain zwave took 0.0 seconds.
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.zwave] Z-Wave USB path is /dev/ttyACM0
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up discovery
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain discovery took 0.1 seconds.
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up group
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up cast
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain cast took 0.0 seconds.
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up updater
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain updater took 0.0 seconds.
2019-05-03 14:59:07 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for ovapi which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2019-05-03 14:59:07 INFO (SyncWorker_19) [homeassistant.loader] Loaded smtp from homeassistant.components.smtp
2019-05-03 14:59:07 INFO (SyncWorker_17) [homeassistant.loader] Loaded ping from homeassistant.components.ping
2019-05-03 14:59:07 INFO (SyncWorker_3) [homeassistant.loader] Loaded darksky from homeassistant.components.darksky
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up auth
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds.
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up webhook
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain webhook took 0.0 seconds.
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up config
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up system_health
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain system_health took 0.0 seconds.
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up api
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up mqtt
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain mqtt took 0.0 seconds.
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up notify
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up binary_sensor
2019-05-03 14:59:07 INFO (SyncWorker_6) [homeassistant.loader] Loaded systemmonitor from homeassistant.components.systemmonitor
2019-05-03 14:59:07 INFO (SyncWorker_12) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up weather
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.notify] Setting up notify.smtp
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.notify] Setting up notify.smtp
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.ping
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.ping
2019-05-03 14:59:07 INFO (SyncWorker_17) [homeassistant.loader] Loaded todoist from homeassistant.components.todoist
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.ping
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.ping
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.ping
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.2 seconds.
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.weather] Setting up weather.darksky
2019-05-03 14:59:07 INFO (SyncWorker_15) [homeassistant.loader] Loaded google_translate from homeassistant.components.google_translate
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setup of domain config took 0.3 seconds.
2019-05-03 14:59:07 INFO (SyncWorker_10) [homeassistant.loader] Loaded lock from homeassistant.components.lock
2019-05-03 14:59:07 INFO (SyncWorker_8) [homeassistant.loader] Loaded cover from homeassistant.components.cover
2019-05-03 14:59:07 INFO (SyncWorker_4) [homeassistant.loader] Loaded light from homeassistant.components.light
2019-05-03 14:59:07 INFO (SyncWorker_13) [homeassistant.loader] Loaded fan from homeassistant.components.fan
2019-05-03 14:59:07 INFO (SyncWorker_17) [homeassistant.loader] Loaded climate from homeassistant.components.climate
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up calendar
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.setup] Setting up tts
2019-05-03 14:59:07 INFO (MainThread) [homeassistant.components.calendar] Setting up calendar.todoist
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.setup] Setting up climate
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.setup] Setup of domain climate took 0.0 seconds.
2019-05-03 14:59:08 INFO (SyncWorker_9) [homeassistant.loader] Loaded pi_hole from homeassistant.components.pi_hole
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.components.climate] Setting up climate.zwave
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.setup] Setting up media_player
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.setup] Setup of domain media_player took 0.0 seconds.
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 0.1 seconds.
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.cast
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.setup] Setup of domain input_select took 2.2 seconds.
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 0.2 seconds.
2019-05-03 14:59:08 INFO (MainThread) [homeassistant.setup] Setting up frontend
2019-05-03 14:59:09 INFO (SyncWorker_17) [homeassistant.loader] Loaded cert_expiry from homeassistant.components.cert_expiry
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.components.climate] Setting up climate.deconz
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 1.7 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain group took 2.1 seconds.
2019-05-03 14:59:09 INFO (SyncWorker_2) [homeassistant.loader] Loaded scene from homeassistant.components.scene
2019-05-03 14:59:09 INFO (SyncWorker_9) [homeassistant.loader] Loaded dnsip from homeassistant.components.dnsip
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 1.1 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain weather took 1.8 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up scene
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain scene took 0.0 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up automation
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up script
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up lock
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain lock took 0.0 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up cover
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain cover took 0.0 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up light
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up fan
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain fan took 0.0 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.components.scene] Setting up scene.deconz
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up logbook
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up panel_custom
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setting up map
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.components.lock] Setting up lock.zwave
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.components.cover] Setting up cover.zwave
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.components.cover] Setting up cover.deconz
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.components.fan] Setting up fan.zwave
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain light took 0.1 seconds.
2019-05-03 14:59:09 INFO (SyncWorker_17) [homeassistant.loader] Loaded rest from homeassistant.components.rest
2019-05-03 14:59:09 ERROR (MainThread) [homeassistant.components.panel_custom] Unable to find webcomponent for hassio-main: /config/panels/hassio-main.html
2019-05-03 14:59:09 INFO (SyncWorker_4) [homeassistant.loader] Loaded template from homeassistant.components.template
2019-05-03 14:59:09 INFO (SyncWorker_6) [homeassistant.loader] Loaded tile from homeassistant.components.tile
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain panel_custom took 0.1 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.setup] Setup of domain script took 0.2 seconds.
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.components.light] Setting up light.zwave
2019-05-03 14:59:09 INFO (MainThread) [homeassistant.components.light] Setting up light.deconz
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setting up device_tracker
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setting up hassio
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 0.4 seconds.
2019-05-03 14:59:10 INFO (SyncWorker_15) [homeassistant.loader] Loaded time_date from homeassistant.components.time_date
2019-05-03 14:59:10 INFO (SyncWorker_10) [homeassistant.loader] Loaded wake_on_lan from homeassistant.components.wake_on_lan
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.device_tracker] Setting up device_tracker.tile
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setting up sensor
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setting up switch
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.rest
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.dnsip
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.time_date
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.systemmonitor
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.pi_hole
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.cert_expiry
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.dnsip
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setting up wake_on_lan
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setup of domain wake_on_lan took 0.0 seconds.
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setting up owntracks
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setup of domain owntracks took 0.0 seconds.
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.switch] Setting up switch.wake_on_lan
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.switch] Setting up switch.wake_on_lan
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.device_tracker] Setting up device_tracker.owntracks
2019-05-03 14:59:10 INFO (MainThread) [hole] Response from *hole: 200
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setup of domain hassio took 0.4 seconds.
2019-05-03 14:59:11 INFO (MainThread) [homeassistant.setup] Setup of domain switch took 1.2 seconds.
2019-05-03 14:59:11 INFO (MainThread) [homeassistant.components.switch] Setting up switch.zwave
2019-05-03 14:59:11 INFO (MainThread) [homeassistant.components.switch] Setting up switch.deconz
2019-05-03 14:59:11 INFO (MainThread) [homeassistant.setup] Setup of domain device_tracker took 1.6 seconds.
2019-05-03 14:59:11 INFO (MainThread) [homeassistant.components.device_tracker] Setting up device_tracker.owntracks
2019-05-03 14:59:12 INFO (MainThread) [homeassistant.setup] Setup of domain calendar took 4.8 seconds.
2019-05-03 14:59:16 INFO (MainThread) [homeassistant.setup] Setup of domain binary_sensor took 8.9 seconds.
2019-05-03 14:59:16 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.zwave
2019-05-03 14:59:16 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.deconz
awarecan commented 5 years ago

Please post your configuration and log as the issue template requested.

Especially around

it stops after loading the binary sensors

Paul-dH commented 5 years ago

I've added the home-assistant log to the issue, but I don't think that there is much info in there :(

If I can provide any other information please let me know.

Adding my configuration would be pointless since it works in 0.91.4 right? Is there a way to combine all files or to print the whole running config, if you'd still like to see my config??

awarecan commented 5 years ago

Since we changed how the components be loaded and set up, it is possible some combination of config may cause dead lock (we fixed serval in 0.92.1), so that knowing your config definitely will help.

Setup of domain binary_sensor took 8.9 seconds

This takes too long. Can you at least post all config about your binary sensors?

awarecan commented 5 years ago

Another possible, some thing blocked the event loop caused the slowness even dead lock.

Which components output this log, pi-hole?

[hole] Response from *hole: 200

Paul-dH commented 5 years ago

Hi @awarecan, thanks for you fast reply!

I have only 6 these binary sensors:

  - platform: ping 
    name: nas_powerstate 
    host: ip_address
    count: 2 
    scan_interval: 30

The Pihole config is like this part:

 - platform: pi_hole
    host: ip_address
    monitored_conditions:
      - ads_blocked_today
      - ads_percentage_today
      - dns_queries_today
      - domains_being_blocked
      - queries_cached
      - queries_forwarded
      - unique_clients
      - unique_domains
      - clients_ever_seen
awarecan commented 5 years ago

Then binary_sensor is fine, ping take time to update its status.

I think the problem may caused by one of your sensor. I cleaned up your log, removed all paired log that indicated the domain setup finished, and removed the log message written by zwave and deconz which was loaded after component setup. The left part is

2019-05-03 14:59:10 INFO (MainThread) [homeassistant.setup] Setting up sensor
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.rest
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.dnsip
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.time_date
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.systemmonitor
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.pi_hole
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.cert_expiry
2019-05-03 14:59:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.dnsip
2019-05-03 14:59:10 INFO (MainThread) [hole] Response from *hole: 200

There should be a message like

Setup of domain sensor took xx seconds.

but I cant find it, that means sensor domain setup is not finished.

I would suggest you try to disable those sensors and see which one caused the issue.

Paul-dH commented 5 years ago

Hi @awarecan,

Today I finally got the time to try the upgrade again with some different scenario's. I started to disable just the list of sensors you wrote. This sadly made no difference. Then I disabled almost everything I could find instead some core options. Removed the container and followed the recovery method I wrote down in post https://github.com/home-assistant/home-assistant/issues/23652#issue-440121579.

I really have no clue what to do next, I also renamed my home-assistant_v2.db to home-assistant_v2.db.bak once. That also didn't make a difference.

I've collected some logs during my attempts:

> hassio_supervisor, container logs...
19-05-13 18:44:23 INFO (SyncWorker_19) [hassio.docker.interface] Pull image homeassistant/tinker-homeassistant tag 0.92.2.,
19-05-13 18:50:49 INFO (SyncWorker_19) [hassio.docker.interface] Cleanup images: ['homeassistant/tinker-homeassistant:0.91.4'],
19-05-13 18:49:45 INFO (SyncWorker_19) [hassio.docker.interface] Tag image homeassistant/tinker-homeassistant with version 0.92.2 as latest,
19-05-13 18:49:45 INFO (MainThread) [hassio.api.proxy] Home Assistant WebSocket API error: Received message 8:1000 is not str,
19-05-13 18:49:45 INFO (MainThread) [hassio.api.proxy] Home Assistant WebSocket API connection is closed,
19-05-13 18:49:50 INFO (MainThread) [hassio.api.proxy] Home Assistant WebSocket API request initialize,
19-05-13 18:44:23 INFO (SyncWorker_19) [hassio.docker.interface] Update image homeassistant/tinker-homeassistant:0.91.4 to homeassistant/tinker-homeassistant:0.92.2,
19-05-13 18:44:23 INFO (MainThread) [hassio.homeassistant] Update Home Assistant to version 0.92.2,
19-05-13 18:49:45 INFO (SyncWorker_19) [hassio.docker.interface] Stop homeassistant application,
19-05-13 18:50:16 ERROR (MainThread) [hassio.api.proxy] Client error on WebSocket API Cannot connect to host 172.30.32.1:8123 ssl:False [Connection refused].

> hassio_supervisor, container logs...
19-05-13 19:06:57 INFO (MainThread) [hassio.homeassistant] Detect a running Home Assistant instance,
19-05-13 18:44:23 INFO (MainThread) [hassio.homeassistant] Update Home Assistant to version 0.92.2,
19-05-13 19:01:16 CRITICAL (MainThread) [hassio.homeassistant] HomeAssistant update fails -> rollback!,
19-05-13 19:01:16 WARNING (MainThread) [hassio.homeassistant] Don't wait anymore of Home Assistant startup!,
19-05-13 19:01:16 INFO (MainThread) [hassio.homeassistant] Update Home Assistant to version 0.91.4
> home-assistant.log, first attempt
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setting up http
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.0 seconds.
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setting up system_log
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setting up recorder
2019-05-13 21:18:27 WARNING (Recorder) [homeassistant.components.recorder] Ended unfinished session (id=17 from 2019-05-13 19:14:17.358426)
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 0.7 seconds.
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setting up cast
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setup of domain cast took 0.0 seconds.
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setting up zwave
2019-05-13 21:18:27 INFO (MainThread) [homeassistant.setup] Setup of domain zwave took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.components.zwave] Z-Wave USB path is /dev/ttyACM0
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up deconz
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain deconz took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up sun
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up zone
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain zone took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up group
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up lovelace
2019-05-13 21:18:29 INFO (SyncWorker_11) [homeassistant.loader] Loaded sensor from custom_components.ovapi
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up discovery
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain discovery took 0.0 seconds.
2019-05-13 21:18:29 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for ovapi which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2019-05-13 21:18:29 INFO (SyncWorker_13) [homeassistant.loader] Loaded smtp from homeassistant.components.smtp
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up api
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up system_health
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain system_health took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up webhook
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain webhook took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up mqtt
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain mqtt took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up auth
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up config
2019-05-13 21:18:29 INFO (SyncWorker_3) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player
2019-05-13 21:18:29 INFO (SyncWorker_7) [homeassistant.loader] Loaded systemmonitor from homeassistant.components.systemmonitor
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up notify
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.3 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.components.notify] Setting up notify.smtp
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.components.notify] Setting up notify.smtp
2019-05-13 21:18:29 INFO (SyncWorker_9) [homeassistant.loader] Loaded template from homeassistant.components.template
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain config took 0.2 seconds.
2019-05-13 21:18:29 INFO (SyncWorker_16) [homeassistant.loader] Loaded climate from homeassistant.components.climate
2019-05-13 21:18:29 INFO (SyncWorker_15) [homeassistant.loader] Loaded cover from homeassistant.components.cover
2019-05-13 21:18:29 INFO (SyncWorker_19) [homeassistant.loader] Loaded lock from homeassistant.components.lock
2019-05-13 21:18:29 INFO (SyncWorker_13) [homeassistant.loader] Loaded binary_sensor from homeassistant.components.binary_sensor
2019-05-13 21:18:29 INFO (SyncWorker_14) [homeassistant.loader] Loaded fan from homeassistant.components.fan
2019-05-13 21:18:29 INFO (SyncWorker_16) [homeassistant.loader] Loaded light from homeassistant.components.light
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up switch
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up climate
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain climate took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setting up binary_sensor
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.setup] Setup of domain binary_sensor took 0.0 seconds.
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template
2019-05-13 21:18:29 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setting up media_player
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setup of domain media_player took 0.0 seconds.
2019-05-13 21:18:30 INFO (SyncWorker_18) [homeassistant.loader] Loaded time_date from homeassistant.components.time_date
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.climate] Setting up climate.zwave
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.zwave
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.cast
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 0.1 seconds.
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setting up sensor
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setting up cover
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setup of domain cover took 0.0 seconds.
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setting up lock
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setup of domain lock took 0.0 seconds.
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setting up fan
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setup of domain fan took 0.0 seconds.
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setting up light
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.deconz
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.climate] Setting up climate.deconz
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.cover] Setting up cover.deconz
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.time_date
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.systemmonitor
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.cover] Setting up cover.zwave
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.lock] Setting up lock.zwave
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.components.fan] Setting up fan.zwave
2019-05-13 21:18:30 INFO (MainThread) [homeassistant.setup] Setting up frontend
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 1.7 seconds.
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setup of domain light took 1.1 seconds.
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.components.light] Setting up light.zwave
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.components.light] Setting up light.deconz
2019-05-13 21:18:31 INFO (SyncWorker_15) [homeassistant.loader] Loaded scene from homeassistant.components.scene
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setup of domain switch took 1.7 seconds.
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.components.switch] Setting up switch.zwave
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.components.switch] Setting up switch.deconz
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 1.1 seconds.
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setting up scene
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setup of domain scene took 0.0 seconds.
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.components.scene] Setting up scene.deconz
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setting up panel_custom
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setup of domain panel_custom took 0.0 seconds.
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setting up logbook
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setting up map
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds.
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setting up hassio
2019-05-13 21:18:31 INFO (MainThread) [homeassistant.setup] Setup of domain hassio took 0.1 seconds.
> home-assistant.log, seccond attempt
2019-05-13 21:33:25 INFO (SyncWorker_2) [openzwave] ZWave configuration written to user directory.,
2019-05-13 21:33:25 INFO (SyncWorker_2) [openzwave] Stop Openzwave network.,
2019-05-13 21:33:25 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.2920862544] Connection closed by client,
2019-05-13 21:33:25 INFO (SyncWorker_2) [homeassistant.components.zwave] Stopping Z-Wave network,
2019-05-13 21:33:25 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.2921080144] Connection closed by client,
2019-05-13 21:33:25 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.2931325040] Connection closed by client,
2019-05-13 21:28:32 INFO (MainThread) [homeassistant.loader] Loaded openuv from homeassistant.components.openuv,
2019-05-13 21:28:32 INFO (MainThread) [homeassistant.loader] Loaded simplisafe from homeassistant.components.simplisafe,
2019-05-13 21:28:32 INFO (MainThread) [homeassistant.loader] Loaded mobile_app from homeassistant.components.mobile_app,
2019-05-13 21:28:32 INFO (MainThread) [homeassistant.loader] Loaded smhi from homeassistant.components.smhi,
2019-05-13 21:28:32 INFO (MainThread) [homeassistant.loader] Loaded ios from homeassistant.components.ios,
2019-05-13 21:28:32 INFO (MainThread) [homeassistant.loader] Loaded tradfri from homeassistant.components.tradfri,
2019-05-13 21:28:32 INFO (MainThread) [homeassistant.loader] Loaded geofency from homeassistant.components.geofency,
2019-05-13 21:28:32 INFO (MainThread) [homeassistant.loader] Loaded sonos from homeassistant.components.sonos,
2019-05-13 21:33:25 INFO (MainThread) [pydeconz] Shutting down connections to deCONZ.
> home-assistant.log, third attempt
2019-05-13 21:35:01 INFO (SyncWorker_0) [homeassistant.config] Upgrading configuration directory from 0.91.4 to 0.92.2,
2019-05-13 21:35:03 INFO (SyncWorker_4) [homeassistant.loader] Loaded sensor from homeassistant.components.sensor,
2019-05-13 21:35:03 INFO (SyncWorker_7) [homeassistant.loader] Loaded switch from homeassistant.components.switch,
2019-05-13 21:35:03 INFO (SyncWorker_4) [homeassistant.loader] Loaded frontend from homeassistant.components.frontend,
2019-05-13 21:35:03 INFO (SyncWorker_13) [homeassistant.loader] Loaded config from homeassistant.components.config,
2019-05-13 21:35:03 INFO (SyncWorker_5) [homeassistant.loader] Loaded logbook from homeassistant.components.logbook,
2019-05-13 21:35:03 INFO (SyncWorker_1) [homeassistant.loader] Loaded discovery from homeassistant.components.discovery,
2019-05-13 21:35:03 INFO (SyncWorker_3) [homeassistant.loader] Loaded map from homeassistant.components.map,
2019-05-13 21:35:03 INFO (SyncWorker_4) [homeassistant.loader] Loaded system_health from homeassistant.components.system_health,
2019-05-13 21:35:03 INFO (SyncWorker_15) [homeassistant.loader] Loaded zone from homeassistant.components.zone,
2019-05-13 21:35:03 INFO (SyncWorker_14) [homeassistant.loader] Loaded http from homeassistant.components.http,
2019-05-13 21:35:03 INFO (SyncWorker_10) [homeassistant.loader] Loaded notify from homeassistant.components.notify,
2019-05-13 21:35:03 INFO (SyncWorker_13) [homeassistant.loader] Loaded logger from homeassistant.components.logger,
2019-05-13 21:35:03 INFO (SyncWorker_6) [homeassistant.loader] Loaded sun from homeassistant.components.sun,
2019-05-13 21:35:03 INFO (SyncWorker_11) [homeassistant.loader] Loaded recorder from homeassistant.components.recorder,
2019-05-13 21:35:03 INFO (SyncWorker_17) [homeassistant.loader] Loaded group from homeassistant.components.group,
2019-05-13 21:35:03 INFO (SyncWorker_8) [homeassistant.loader] Loaded homeassistant from homeassistant.components.homeassistant,
2019-05-13 21:35:03 INFO (SyncWorker_0) [homeassistant.loader] Loaded owntracks from homeassistant.components.owntracks,
2019-05-13 21:35:03 INFO (SyncWorker_2) [homeassistant.loader] Loaded deconz from homeassistant.components.deconz,
2019-05-13 21:35:03 INFO (SyncWorker_5) [homeassistant.loader] Loaded persistent_notification from homeassistant.components.persistent_notification,
2019-05-13 21:35:03 INFO (SyncWorker_18) [homeassistant.loader] Loaded hassio from homeassistant.components.hassio,
2019-05-13 21:35:03 INFO (SyncWorker_7) [homeassistant.loader] Loaded zwave from homeassistant.components.zwave,
2019-05-13 21:35:03 INFO (SyncWorker_12) [homeassistant.loader] Loaded api from homeassistant.components.api,
2019-05-13 21:35:03 INFO (SyncWorker_16) [homeassistant.loader] Loaded cast from homeassistant.components.cast,
2019-05-13 21:35:03 INFO (MainThread) [homeassistant.setup] Setting up homeassistant,
2019-05-13 21:35:03 INFO (MainThread) [homeassistant.setup] Setup of domain homeassistant took 0.0 seconds.,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setting up persistent_notification,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setup of domain persistent_notification took 0.0 seconds.,
2019-05-13 21:35:04 INFO (SyncWorker_3) [homeassistant.loader] Loaded webhook from homeassistant.components.webhook,
2019-05-13 21:35:04 INFO (SyncWorker_15) [homeassistant.loader] Loaded panel_custom from homeassistant.components.panel_custom,
2019-05-13 21:35:04 INFO (SyncWorker_14) [homeassistant.loader] Loaded auth from homeassistant.components.auth,
2019-05-13 21:35:04 INFO (SyncWorker_11) [homeassistant.loader] Loaded lovelace from homeassistant.components.lovelace,
2019-05-13 21:35:04 INFO (SyncWorker_2) [homeassistant.loader] Loaded onboarding from homeassistant.components.onboarding,
2019-05-13 21:35:04 INFO (SyncWorker_12) [homeassistant.loader] Loaded system_log from homeassistant.components.system_log,
2019-05-13 21:35:04 INFO (SyncWorker_9) [homeassistant.loader] Loaded websocket_api from homeassistant.components.websocket_api,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setting up logger,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setting up http,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.0 seconds.,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setting up system_log,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setting up recorder,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 0.8 seconds.,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setting up discovery,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setup of domain discovery took 0.1 seconds.,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setting up lovelace,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.0 seconds.,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setting up zone,
2019-05-13 21:35:04 INFO (MainThread) [homeassistant.setup] Setup of domain zone took 0.0 seconds.,
2019-05-13 21:35:05 INFO (MainThread) [homeassistant.setup] Setting up group,
2019-05-13 21:35:05 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.0 seconds.,
2019-05-13 21:35:05 INFO (MainThread) [homeassistant.setup] Setting up sun,
2019-05-13 21:35:05 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.1 seconds.,
2019-05-13 21:35:05 INFO (MainThread) [homeassistant.setup] Setting up cast,
2019-05-13 21:35:05 INFO (MainThread) [homeassistant.setup] Setup of domain cast took 0.0 seconds.,
2019-05-13 21:35:05 INFO (SyncWorker_11) [homeassistant.loader] Loaded sensor from custom_components.ovapi,
2019-05-13 21:35:05 INFO (MainThread) [homeassistant.setup] Setting up zwave,
2019-05-13 21:35:05 INFO (MainThread) [homeassistant.setup] Setup of domain zwave took 0.0 seconds.,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.components.zwave] Z-Wave USB path is /dev/ttyACM0,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up deconz,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain deconz took 0.0 seconds.,
2019-05-13 21:35:07 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for ovapi which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.,
2019-05-13 21:35:07 INFO (SyncWorker_2) [homeassistant.loader] Loaded smtp from homeassistant.components.smtp,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up config,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up system_health,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain system_health took 0.0 seconds.,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up websocket_api,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up webhook,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain webhook took 0.0 seconds.,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up api,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up auth,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds.,
2019-05-13 21:35:07 INFO (SyncWorker_15) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up notify,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.components.notify] Setting up notify.smtp,
2019-05-13 21:35:07 INFO (SyncWorker_10) [homeassistant.loader] Loaded systemmonitor from homeassistant.components.systemmonitor,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.components.notify] Setting up notify.smtp,
2019-05-13 21:35:07 INFO (SyncWorker_14) [homeassistant.loader] Loaded template from homeassistant.components.template,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.3 seconds.,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain config took 0.4 seconds.,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up onboarding,
2019-05-13 21:35:07 INFO (SyncWorker_7) [homeassistant.loader] Loaded binary_sensor from homeassistant.components.binary_sensor,
2019-05-13 21:35:07 INFO (SyncWorker_18) [homeassistant.loader] Loaded climate from homeassistant.components.climate,
2019-05-13 21:35:07 INFO (SyncWorker_12) [homeassistant.loader] Loaded lock from homeassistant.components.lock,
2019-05-13 21:35:07 INFO (SyncWorker_2) [homeassistant.loader] Loaded cover from homeassistant.components.cover,
2019-05-13 21:35:07 INFO (SyncWorker_19) [homeassistant.loader] Loaded light from homeassistant.components.light,
2019-05-13 21:35:07 INFO (SyncWorker_16) [homeassistant.loader] Loaded fan from homeassistant.components.fan,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up switch,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.components.switch] Setting up switch.template,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up media_player,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain media_player took 0.0 seconds.,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up binary_sensor,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain binary_sensor took 0.0 seconds.,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setting up climate,
2019-05-13 21:35:07 INFO (MainThread) [homeassistant.setup] Setup of domain climate took 0.0 seconds.,
2019-05-13 21:35:07 INFO (SyncWorker_13) [homeassistant.loader] Loaded time_date from homeassistant.components.time_date,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.cast,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.zwave,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.climate] Setting up climate.zwave,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 0.6 seconds.,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.deconz,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.climate] Setting up climate.deconz,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setting up sensor,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setting up lock,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setup of domain lock took 0.0 seconds.,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setting up cover,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setup of domain cover took 0.0 seconds.,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setting up light,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setting up fan,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setup of domain fan took 0.0 seconds.,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 1.2 seconds.,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.systemmonitor,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.time_date,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.lock] Setting up lock.zwave,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.cover] Setting up cover.zwave,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.cover] Setting up cover.deconz,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.components.fan] Setting up fan.zwave,
2019-05-13 21:35:08 INFO (MainThread) [homeassistant.setup] Setup of domain light took 0.1 seconds.,
2019-05-13 21:35:09 INFO (MainThread) [homeassistant.setup] Setting up frontend,
2019-05-13 21:35:10 INFO (SyncWorker_12) [homeassistant.loader] Loaded scene from homeassistant.components.scene,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.components.light] Setting up light.zwave,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.components.light] Setting up light.deconz,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setting up scene,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setup of domain scene took 0.0 seconds.,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setup of domain switch took 2.5 seconds.,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.components.scene] Setting up scene.deconz,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.components.switch] Setting up switch.zwave,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.components.switch] Setting up switch.deconz,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 1.5 seconds.,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setting up logbook,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setting up map,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds.,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setting up panel_custom,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setup of domain panel_custom took 0.0 seconds.,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setting up hassio,
2019-05-13 21:35:10 INFO (MainThread) [homeassistant.setup] Setup of domain hassio took 0.1 seconds.,
awarecan commented 5 years ago

As I said before, I still cannot find follow log.

Setup of domain sensor took xx seconds.

Please disable all of your sensors.

Paul-dH commented 5 years ago

I've found the issue, it was in a custom component created by me and 3 others Sensor-OvApi. The weird thing is that after loading the component the warning is displayed as usual, but no further messages or errors related to the component are being displayed.

You are using a custom integration for ovapi which has not been tested by Home Assistant.

I had to disable everything and enable them one by one to trace it down.

I did make a number of changes related to the init file and the manifest but maybee I missed something...

awarecan commented 5 years ago

Did I forget to say that you should first disable all custom components?

Regarding your custom components

https://github.com/Paul-dH/Home-Assisant-Sensor-OvApi/blob/3edac290b6e5423dd89ec160e74fcde64ad0723d/custom_components/ovapi/manifest.json#L2 domain should be ovapi, not the url

https://github.com/Paul-dH/Home-Assisant-Sensor-OvApi/blob/3edac290b6e5423dd89ec160e74fcde64ad0723d/custom_components/ovapi/sensor.py#L75 You are using requests lib, not aiohttp lib, this statement is unused, remove it.

https://github.com/Paul-dH/Home-Assisant-Sensor-OvApi/blob/3edac290b6e5423dd89ec160e74fcde64ad0723d/custom_components/ovapi/sensor.py#L310 Since you are using requests, you should change this function to 

def update(self):

https://github.com/Paul-dH/Home-Assisant-Sensor-OvApi/blob/3edac290b6e5423dd89ec160e74fcde64ad0723d/custom_components/ovapi/sensor.py#L205

def update(self):

Also https://github.com/Paul-dH/Home-Assisant-Sensor-OvApi/blob/3edac290b6e5423dd89ec160e74fcde64ad0723d/custom_components/ovapi/sensor.py#L66

def setup_platform(hass, config, add_entities, discovery_info=None):

At last https://github.com/Paul-dH/Home-Assisant-Sensor-OvApi/blob/3edac290b6e5423dd89ec160e74fcde64ad0723d/custom_components/ovapi/sensor.py#L317 requests does not have default timeout set, you should always pass in a reasonable timeout parameter

awarecan commented 5 years ago

I am going to close the issue since it is caused by custom component

Paul-dH commented 5 years ago

I think I misunderstood the hint to disable the custom component, maybe also since it was not generating any errors.

Thank you for your code review, I made the code changes and the new version works great! I also had to remove the await bits in the setup_platform and update functions.

And I've added the timeout parameter at lines 314 and 323:

response = http.client.HTTPConnection(self._resource, timeout=1)

Thanks again for all the effort!