HA does not start anymore

datamonkey · February 3, 2019, 10:44am

Hi, I know there have been a few posts like this lately. And I read most of them …

So I moved from 0.85.x to 0.86.0 and now the latest version of HA in the official docker running on unRAID.

The first upgrade was a bit of a pain and somehow I lost my login (?) and blew away the .storage folder to get back on track. I have changed all the time automations and some entity names. Even renamed all entities via GUI just to be sure. Everything was working again.
Then I restarted the docker today and it wont start. The log has no errors and it looks like it starts up nicely. But the website never comes up and there is no reaction to any button presses.

So I moved my .storage folder away and restarted. As expected I had to set up my login again and it all started again.
But that can’t be the solution. It also means I need to relink my google integration.

Any tips where I should start looking?
Am I locking myself out? Where could I find that in .storage?

Any hints would be greatly appreciated. Where do I start?

BTW, HA was running for over a year with many upgrades before this one!

datamonkey · February 3, 2019, 11:03am

this is my most recent logfile after a docker restart…

2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up http
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up recorder
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded mqtt.discovery from homeassistant.components.mqtt.discovery
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up lovelace
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up api
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up system_log
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up auth
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up frontend
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 0.2 seconds.
2019-02-03 22:00:09 WARNING (Recorder) [homeassistant.components.recorder] Ended unfinished session (id=48 from 2019-02-03 10:56:35.751792)
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 0.4 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up history
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain history took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded mqtt.switch from homeassistant.components.mqtt.switch
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded light.yeelight from homeassistant.components.light.yeelight
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded device_tracker.nmap_tracker from homeassistant.components.device_tracker.nmap_tracker
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up xiaomi_aqara
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded sonos from homeassistant.components.sonos
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up sonos
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain sonos took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up input_select
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded remote.harmony from homeassistant.components.remote.harmony
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded weather.bom from homeassistant.components.weather.bom
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded weather.darksky from homeassistant.components.weather.darksky
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up weather
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded sensor.time_date from homeassistant.components.sensor.time_date
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded sensor.template from homeassistant.components.sensor.template
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded sensor.apcupsd from homeassistant.components.sensor.apcupsd
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up sensor
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up zone
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain zone took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up apcupsd
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up sun
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded zeroconf from homeassistant.components.zeroconf
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded notify.pushover from homeassistant.components.notify.pushover
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up notify
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded binary_sensor.ping from homeassistant.components.binary_sensor.ping
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded binary_sensor.workday from homeassistant.components.binary_sensor.workday
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up binary_sensor
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up influxdb
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up group
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded tts.google from homeassistant.components.tts.google
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded cast from homeassistant.components.cast
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up cast
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain cast took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up nest
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain nest took 0.0 seconds.
2019-02-03 22:00:09 INFO (SyncWorker_17) [nest.nest] Open data stream
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up input_boolean
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.weather] Setting up weather.darksky
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.weather] Setting up weather.bom
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-02-03 22:00:09 INFO (SyncWorker_3) [homeassistant.components.xiaomi_aqara] Discovering Xiaomi Gateways (Try 1)
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.time_date
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.notify] Setting up notify.pushover
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.notify] Setting up notify.pushover
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.ping
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.workday
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded persistent_notification from homeassistant.components.persistent_notification
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain apcupsd took 0.1 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up config
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up google_assistant
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain google_assistant took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up logbook
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up tts
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.core from homeassistant.components.config.core
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.script from homeassistant.components.config.script
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.auth from homeassistant.components.config.auth
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.customize from homeassistant.components.config.customize
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.device_registry from homeassistant.components.config.device_registry
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.auth_provider_homeassistant from homeassistant.components.config.auth_provider_homeassistant
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.entity_registry from homeassistant.components.config.entity_registry
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.automation from homeassistant.components.config.automation
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.group from homeassistant.components.config.group
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.config_entries from homeassistant.components.config.config_entries
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded config.hassbian from homeassistant.components.config.hassbian
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up media_player
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain media_player took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.apcupsd
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up zeroconf
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain influxdb took 0.1 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 0.1 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded media_player.sonos from homeassistant.components.media_player.sonos
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.loader] Loaded media_player.cast from homeassistant.components.media_player.cast
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.sonos
2019-02-03 22:00:09 INFO (SyncWorker_3) [xiaomi_gateway] Xiaomi Gateway f0b429cfbc12 found at IP 192.168.2.134
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.cast
2019-02-03 22:00:09 INFO (SyncWorker_3) [xiaomi_gateway] Discovering Xiaomi Devices
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain config took 0.0 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 0.0 seconds.
2019-02-03 22:00:09 INFO (SyncWorker_3) [xiaomi_gateway] Found 9 devices
2019-02-03 22:00:09 INFO (SyncWorker_3) [xiaomi_gateway] Xiaomi Gateway 286c0785b6f3 found at IP 192.168.2.133
2019-02-03 22:00:09 INFO (SyncWorker_3) [xiaomi_gateway] Discovering Xiaomi Devices
2019-02-03 22:00:09 INFO (SyncWorker_3) [xiaomi_gateway] Found 13 devices
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain input_select took 0.3 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain input_boolean took 0.2 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new media_player.cast entity: media_player.xenia_s_speaker
2019-02-03 22:00:09 INFO (SyncWorker_18) [pychromecast] Querying device status
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain sensor took 0.3 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.3 seconds.
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up switch
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up light
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up device_tracker
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up remote
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.setup] Setting up automation
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.light] Setting up light.yeelight
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.light] Setting up light.yeelight
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.light] Setting up light.yeelight
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.light] Setting up light.yeelight
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.light] Setting up light.yeelight
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.light] Setting up light.yeelight
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.remote] Setting up remote.harmony
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.remote.harmony] Loading Harmony Platform: HarmonyHub at 192.168.2.132:5222, startup activity: Watch Plex
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.switch] Setting up switch.mqtt
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.device_tracker] Setting up device_tracker.nmap_tracker
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.device_tracker] Setting up device_tracker.nmap_tracker
2019-02-03 22:00:09 INFO (MainThread) [homeassistant.components.device_tracker] Setting up device_tracker.nmap_tracker
2019-02-03 22:00:09 INFO (SyncWorker_9) [homeassistant.components.device_tracker.nmap_tracker] Scanner initialized
2019-02-03 22:00:09 INFO (SyncWorker_14) [homeassistant.components.device_tracker.nmap_tracker] Scanner initialized
2019-02-03 22:00:09 INFO (SyncWorker_9) [homeassistant.components.device_tracker.nmap_tracker] Scanner initialized
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new media_player.cast entity: media_player.living_room_speaker
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new media_player.cast entity: media_player.chromecastdulli
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new media_player.cast entity: media_player.master_bedroom_speaker
2019-02-03 22:00:10 INFO (SyncWorker_12) [pychromecast] Querying device status
2019-02-03 22:00:10 INFO (SyncWorker_18) [pychromecast] Querying device status
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 0.2 seconds.
2019-02-03 22:00:10 INFO (SyncWorker_13) [pychromecast] Querying device status
2019-02-03 22:00:10 INFO (SyncWorker_6) [homeassistant.components.device_tracker.nmap_tracker] Scanning…
2019-02-03 22:00:10 INFO (SyncWorker_1) [homeassistant.components.device_tracker.nmap_tracker] Scanning…
2019-02-03 22:00:10 INFO (SyncWorker_5) [homeassistant.components.device_tracker.nmap_tracker] Scanning…
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new media_player.cast entity: media_player.stereo
2019-02-03 22:00:10 INFO (SyncWorker_0) [pychromecast] Querying device status
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new media_player.cast entity: media_player.felix_s_speaker
2019-02-03 22:00:10 ERROR (SyncWorker_18) [pychromecast.socket_client] Failed to connect, retrying in 5.0s
2019-02-03 22:00:10 INFO (SyncWorker_19) [pychromecast] Querying device status
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.setup] Setup of domain device_tracker took 0.4 seconds.
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new media_player.sonos entity: media_player.living_room
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.setup] Setup of domain switch took 0.5 seconds.
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.setup] Setup of domain zeroconf took 0.9 seconds.
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.setup] Setting up ios
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.setup] Setup of domain ios took 0.0 seconds.
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.loader] Loaded sensor.ios from homeassistant.components.sensor.ios
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.loader] Loaded notify.ios from homeassistant.components.notify.ios
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.ios
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.components.notify] Setting up notify.ios
2019-02-03 22:00:10 INFO (MainThread) [homeassistant.setup] Setup of domain binary_sensor took 1.3 seconds.
2019-02-03 22:00:11 INFO (MainThread) [homeassistant.setup] Setup of domain remote took 1.7 seconds.
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.setup] Setup of domain weather took 2.8 seconds.
2019-02-03 22:00:12 INFO (SyncWorker_17) [nest.nest] Event loop started.
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.loader] Loaded sensor.nest from homeassistant.components.sensor.nest
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.loader] Loaded binary_sensor.nest from homeassistant.components.binary_sensor.nest
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.loader] Loaded climate from homeassistant.components.climate
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.setup] Setting up climate
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.setup] Setup of domain climate took 0.0 seconds.
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.loader] Loaded camera from homeassistant.components.camera
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.loader] Loaded climate.nest from homeassistant.components.climate.nest
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.nest
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.nest
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.setup] Setting up camera
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.setup] Setup of domain camera took 0.0 seconds.
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.components.climate] Setting up climate.nest
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.loader] Loaded camera.nest from homeassistant.components.camera.nest
2019-02-03 22:00:12 INFO (MainThread) [homeassistant.components.camera] Setting up camera.nest
2019-02-03 22:00:13 ERROR (SyncWorker_9) [homeassistant.components.light.yeelight] Failed to connect to bulb 192.168.2.85, Felix Light: A socket error occurred when sending the command.
2019-02-03 22:00:14 INFO (SyncWorker_3) [xiaomi_gateway] Gateway discovery finished in 5 seconds
2019-02-03 22:00:14 INFO (SyncWorker_3) [xiaomi_gateway] Creating Multicast Socket
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.loader] Loaded cover from homeassistant.components.cover
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.loader] Loaded lock from homeassistant.components.lock
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.loader] Loaded binary_sensor.xiaomi_aqara from homeassistant.components.binary_sensor.xiaomi_aqara
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.xiaomi_aqara
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.loader] Loaded sensor.xiaomi_aqara from homeassistant.components.sensor.xiaomi_aqara
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.xiaomi_aqara
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.loader] Loaded switch.xiaomi_aqara from homeassistant.components.switch.xiaomi_aqara
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.components.switch] Setting up switch.xiaomi_aqara
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.setup] Setting up cover
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.setup] Setup of domain cover took 0.0 seconds.
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.setup] Setting up lock
2019-02-03 22:00:14 INFO (MainThread) [homeassistant.setup] Setup of domain lock took 0.0 seconds.
2019-02-03 22:00:15 INFO (MainThread) [homeassistant.loader] Loaded cover.xiaomi_aqara from homeassistant.components.cover.xiaomi_aqara
2019-02-03 22:00:15 INFO (MainThread) [homeassistant.components.cover] Setting up cover.xiaomi_aqara
2019-02-03 22:00:15 INFO (MainThread) [homeassistant.loader] Loaded lock.xiaomi_aqara from homeassistant.components.lock.xiaomi_aqara
2019-02-03 22:00:15 INFO (MainThread) [homeassistant.components.lock] Setting up lock.xiaomi_aqara
2019-02-03 22:00:15 INFO (MainThread) [homeassistant.setup] Setup of domain xiaomi_aqara took 5.6 seconds.
2019-02-03 22:00:16 INFO (SyncWorker_1) [homeassistant.components.device_tracker.nmap_tracker] nmap scan successful
2019-02-03 22:00:19 ERROR (SyncWorker_8) [homeassistant.components.light.yeelight] Failed to connect to bulb 192.168.2.80, Laundry Light: A socket error occurred when sending the command.
2019-02-03 22:00:19 INFO (SyncWorker_6) [homeassistant.components.device_tracker.nmap_tracker] nmap scan successful
2019-02-03 22:00:19 WARNING (MainThread) [homeassistant.components.light] Setup of platform yeelight is taking over 10 seconds.
2019-02-03 22:00:19 INFO (SyncWorker_5) [homeassistant.components.device_tracker.nmap_tracker] No MAC address found for 192.168.2.130
2019-02-03 22:00:19 INFO (SyncWorker_5) [homeassistant.components.device_tracker.nmap_tracker] nmap scan successful
2019-02-03 22:00:22 INFO (MainThread) [homeassistant.setup] Setup of domain light took 12.7 seconds.
2019-02-03 22:00:22 INFO (MainThread) [homeassistant.loader] Loaded light.xiaomi_aqara from homeassistant.components.light.xiaomi_aqara
2019-02-03 22:00:22 INFO (MainThread) [homeassistant.components.light] Setting up light.xiaomi_aqara

I can’t see where I a m going wrong

finity · February 4, 2019, 2:49am

Which flavor of HA? Hassio or not?

How are you actually doing the upgrade? Procedure?

datamonkey · February 4, 2019, 5:31am

Thanks for looking at this.
It is not Hass.io, I am using “normal” home assistant in a docker.
As for the upgrade, I just downloaded the new docker and run it.
Fixed all the breaking changes
And it seems to work.
But then it refuses to restart.
When I remove .storage it starts and does the on boarding. I can even put the core.entity_registry file back and it keeps working and I can reboot.
All good. Then it breaks. Not sure what does it.
So I would like to investigate what is going on, but am not sure where to start.

I assume that my config is ok, as it all works after a new on boarding…

nickrout · February 4, 2019, 8:11am

Vague, tell us exactly what you did, command for command.

That tells us nothing about what you actually did.

Yes but it will leave a log, what does the log say?

cf0 · February 4, 2019, 8:45am

I actually had a very similar issue yesterday. HA (docker install on a QNAP NAS) didn’t start anymore after I updated NAS and router firmwares.

Not sure if you have the same issue, but in my case the problem was apparently caused by a chromecast device which was not reachable (NVIDIA shield with chromecast built-in had failed to re-connect after the router upgrade). You have the same error message in your log I had:

2019-02-03 22:00:10 ERROR (SyncWorker_18) [pychromecast.socket_client] Failed to connect, retrying in 5.0s

So possibly you have the same issue. I resolved it first by deleting the google cast related entries from core.config_entries (while the docker container was stopped) and HA worked again. After re-adding the chromecast integration through the UI HA stopped working again after a reboot. Only then I actually checked the NVIDIA shield and noticed that it was disconnected - after re-connecting it, everything is working fine again now.

This also seems to be the same problem as in this thread: No GUI after update - pychromecast error

So you might want to check your google cast devices and maybe this helps resolving your issue.

datamonkey · February 4, 2019, 10:23am

@nickrout thanks for reading.
Unfortunately I did not keep exactly track on how I upgraded. But since the config worked for a while I thought I was OK.

@cf0 thanks for the hint. I saw that error and since it said retrying in 5s I was thinking it should be OK. But you are right. I removed the cast entry from core.config_entries and it restarted again.

discovery is now disabled and I need to check what I am going to do with the cast devices.

Thanks for your help

boggiano · February 4, 2019, 10:34am

Same here on xiaomi_aqara!
I left it for 10 minutes but it was really stuck!
Right now, this happens 2 times on 10 when I restart HA.

Anyway, giving another “systemctl restart HA” it goes on!

This started when I upgraded to 0.86.x.

lizeroy · March 23, 2019, 6:55pm

Seems to still be happening to me on some boots as well