Troubleshooting "HomeAssistant update fails"

naamah75 · February 8, 2020, 8:53am

I’m running Home Assistant on a docker container on a linux system (Ubuntu 18.04.4 LTS).
I falled in the problem of the HomeAssistant update fails, but in the logs there’s no indications on how and why the update fail, so, how I can debug?
Current supervisor version is 199 and core versione is 0.104.3 (I can’t upgrade to 0.105.2).
I have another Home Assistant server in the same network (that manage other things) running in a virtualbox with HassOS 3.9, and this haven’t no problem to update.

My log:

20-02-08 08:03:21 WARNING (MainThread) [hassio.homeassistant] Don't wait anymore of Home Assistant startup!
20-02-08 08:03:21 CRITICAL (MainThread) [hassio.homeassistant] HomeAssistant update fails -> rollback!
20-02-08 08:03:21 INFO (MainThread) [hassio.homeassistant] Update Home Assistant to version 0.104.3
20-02-08 08:03:21 INFO (SyncWorker_9) [hassio.docker.interface] Update image homeassistant/qemux86-64-homeassistant:0.105.2 to homeassistant/qemux86-64-homeassistant:0.104.3
20-02-08 08:03:21 INFO (SyncWorker_9) [hassio.docker.interface] Pull image homeassistant/qemux86-64-homeassistant tag 0.104.3.
20-02-08 08:03:24 INFO (SyncWorker_9) [hassio.docker.interface] Stop homeassistant application
20-02-08 08:03:26 INFO (SyncWorker_9) [hassio.docker.interface] Clean homeassistant application
20-02-08 08:03:28 INFO (SyncWorker_0) [hassio.docker.homeassistant] Start homeassistant homeassistant/qemux86-64-homeassistant with version 0.104.3
20-02-08 08:04:03 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-08 08:07:35 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-08 08:07:45 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-08 08:08:30 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-08 08:08:37 INFO (MainThread) [hassio.host.info] Update local host information
20-02-08 08:08:37 INFO (MainThread) [hassio.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-02-08 08:08:38 INFO (MainThread) [hassio.host.services] Update service information
20-02-08 08:08:38 INFO (MainThread) [hassio.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-02-08 08:08:39 INFO (MainThread) [hassio.host.network] Update local network DNS information
20-02-08 08:08:39 INFO (MainThread) [hassio.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager
20-02-08 08:08:40 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-08 08:12:21 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-08 08:13:26 INFO (MainThread) [hassio.homeassistant] Detect a running Home Assistant instance
20-02-08 08:13:26 INFO (MainThread) [hassio.homeassistant] Successful run Home Assistant 0.104.3

DavidFW1960 · February 8, 2020, 9:05am

What is in the log before the first line you posted? The first line is saying it can’t start and so is rolling back so your posted log is pretty much useless… it’s already given up…

naamah75 · February 8, 2020, 9:45am

20-02-08 07:37:55 INFO (MainThread) [hassio.homeassistant] Update Home Assistant to version 0.105.2
20-02-08 07:37:55 INFO (SyncWorker_11) [hassio.docker.interface] Update image homeassistant/qemux86-64-homeassistant:0.104.3 to homeassistant/qemux86-64-homeassistant:0.105.2
20-02-08 07:37:55 INFO (SyncWorker_11) [hassio.docker.interface] Pull image homeassistant/qemux86-64-homeassistant tag 0.105.2.
20-02-08 07:43:37 INFO (SyncWorker_11) [hassio.docker.interface] Stop homeassistant application
20-02-08 07:44:39 INFO (SyncWorker_11) [hassio.docker.interface] Clean homeassistant application
20-02-08 07:44:41 INFO (SyncWorker_13) [hassio.docker.homeassistant] Start homeassistant homeassistant/qemux86-64-homeassistant with version 0.105.2
20-02-08 07:45:16 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-08 07:48:42 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-08 07:48:47 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-08 07:49:38 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-08 07:49:43 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-08 07:53:19 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done

… then the rest of the log as above

esbenr · February 8, 2020, 3:06pm

Removing Netatmo from the configuration fixed this for me.

In 0.1.5.x Netatmo is an integration not a configuration. Removing the configuration from configuration.yaml and restarting before updating fixed the problem with updating to 0.105.2 for me.

naamah75 · February 8, 2020, 4:44pm

I don’t have Netatmo in this server, but I have it in the other server that, instead, upgraded with no hassle.
Anyway tried to remove (almost) all from the configuration but the problem still remain.

tmjpugh · February 8, 2020, 10:18pm

How are you updating? The UI or update docker image?

Look like UI. If so this is wrong update method for docker.

naamah75 · February 9, 2020, 6:43am

Ehm… I guess I’m updating the UI. So, what’s the correct way to update the UI under a docker?

tmjpugh · February 9, 2020, 6:53am

Stop running container and basically rerun command you use to Install.

naamah75 · February 9, 2020, 6:57am

Now I’m googling about my problem and based upon your advice I found this Updating Home Assistant.

Seems that the simply way is to pull a new docker image by sudo docker pull homeassistant/home-assistant:latest

It’s right? I must do a snapshot or the config will be mantained?

tmjpugh · February 9, 2020, 7:07am

Yes, the update method you have above should basically work.

You don’t need snapshot but honestly I worry how you install so just in case do snapshot and save in safe location.

When you install HA docker you should have /config folder mounted so this should not be lost unless you did something strange at first install.

naamah75 · February 9, 2020, 7:09am

Ok! many thanks now I will try… I’m a very newbie on a “docker systems”. I must learn about it.

DavidFW1960 · February 9, 2020, 7:25am

If you are running Home Assistant Supervised (Hassio) You CANNOT do a docker pull to update. You must update via the Supervisor Side panel (hassio side panel) OR use ssh to update… (and unless you are on HA-Supervised/Hassio there is no such thing as a snapshot)

naamah75 · February 9, 2020, 7:32am

This is my system…

… so, How I can update?

DavidFW1960 · February 9, 2020, 8:10am

There should be an update button on the dashboard. (You are showing the system tab)
Or use the ssh addon…
Issue this command…

hassio ha update --version=0.105.2

naamah75 · February 9, 2020, 8:14am

Yes, updated until now with the “Update button” but I’m still facing with the problem from the post above.
Tried to pull docker image as @tmjpugh adviced but with no luck.

DavidFW1960 · February 9, 2020, 8:17am

No luck because as I stated you CAN’T!

DavidFW1960 · February 9, 2020, 8:19am

The problem is you posted 2 different HA startups with your logs in 2 posts so they are not really helping. Please post ONE contiguous log after you try and update. Post the supervisor log as well as the home assistant log… from the ONE startup.

naamah75 · February 9, 2020, 8:42am

This is my entiere log:

20-02-09 07:37:33 INFO (MainThread) [hassio.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager
20-02-09 07:37:33 INFO (MainThread) [hassio.host.apparmor] Load AppArmor Profiles: {'hassio-supervisor'}
20-02-09 07:37:33 INFO (MainThread) [hassio.host.services] Reload local service hassio-apparmor.service
20-02-09 07:37:33 INFO (MainThread) [hassio.utils.gdbus] Call org.freedesktop.systemd1.Manager.ReloadOrRestartUnit on /org/freedesktop/systemd1
20-02-09 07:37:33 INFO (SyncWorker_2) [hassio.docker.interface] Attach to homeassistant/amd64-hassio-dns with version 1
20-02-09 07:37:33 INFO (MainThread) [hassio.dns] Start CoreDNS plugin
20-02-09 07:37:33 INFO (MainThread) [hassio.misc.forwarder] Start DNS port forwarding to 172.30.32.3
20-02-09 07:37:33 INFO (SyncWorker_2) [hassio.docker.interface] Clean hassio_dns application
20-02-09 07:37:42 INFO (SyncWorker_2) [hassio.docker.dns] Start DNS homeassistant/amd64-hassio-dns with version 1 - 172.30.32.3
20-02-09 07:37:42 INFO (SyncWorker_5) [hassio.docker.interface] Attach to homeassistant/qemux86-64-homeassistant with version 0.104.3
20-02-09 07:37:50 INFO (MainThread) [hassio.store.git] Load add-on /data/addons/git/6872d8d8 repository
20-02-09 07:37:50 INFO (MainThread) [hassio.store.git] Load add-on /data/addons/git/15ef4d2f repository
20-02-09 07:37:50 INFO (MainThread) [hassio.store.git] Load add-on /data/addons/core repository
20-02-09 07:37:50 INFO (MainThread) [hassio.store.git] Load add-on /data/addons/git/a0d7b954 repository
20-02-09 07:37:50 INFO (MainThread) [hassio.store] Load add-ons from store: 66 all - 66 new - 0 remove
20-02-09 07:37:50 INFO (MainThread) [hassio.addons] Found 15 installed add-ons
20-02-09 07:37:51 INFO (SyncWorker_6) [hassio.docker.interface] Attach to homeassistant/amd64-addon-ssh with version 8.2
20-02-09 07:37:51 INFO (SyncWorker_2) [hassio.docker.interface] Attach to samccauley/amd64-googlebackup with version 1.7.2
20-02-09 07:37:51 INFO (SyncWorker_8) [hassio.docker.interface] Attach to hassioaddons/ide-amd64 with version 2.2.0
20-02-09 07:37:51 INFO (SyncWorker_13) [hassio.docker.interface] Attach to hassioaddons/sonweb-amd64 with version 0.9.0
20-02-09 07:37:51 INFO (SyncWorker_15) [hassio.docker.interface] Attach to hassioaddons/grafana-amd64 with version 4.1.0
20-02-09 07:37:51 INFO (SyncWorker_14) [hassio.docker.interface] Attach to homeassistant/amd64-addon-mariadb with version 1.3
20-02-09 07:37:51 INFO (SyncWorker_17) [hassio.docker.interface] Attach to hassioaddons/log-viewer-amd64 with version 0.7.0
20-02-09 07:37:51 INFO (SyncWorker_2) [hassio.docker.interface] Attach to homeassistant/amd64-addon-configurator with version 4.2
20-02-09 07:37:51 INFO (SyncWorker_10) [hassio.docker.interface] Attach to homeassistant/amd64-addon-samba with version 9.0
20-02-09 07:37:51 INFO (SyncWorker_16) [hassio.docker.interface] Attach to hassioaddons/influxdb-amd64 with version 3.5.1
20-02-09 07:37:51 INFO (SyncWorker_19) [hassio.docker.interface] Attach to hassioaddons/ftp-amd64 with version 3.2.0
20-02-09 07:37:51 INFO (SyncWorker_1) [hassio.docker.interface] Attach to hassioaddons/node-red-amd64 with version 6.0.4
20-02-09 07:37:51 INFO (SyncWorker_6) [hassio.docker.interface] Attach to homeassistant/amd64-addon-mosquitto with version 5.1
20-02-09 07:37:51 INFO (SyncWorker_8) [hassio.docker.interface] Attach to homeassistant/amd64-addon-duckdns with version 1.10
20-02-09 07:37:51 INFO (SyncWorker_11) [hassio.docker.interface] Attach to esphome/esphome-hassio-amd64 with version 1.14.3
20-02-09 07:37:51 INFO (MainThread) [hassio.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-02-09 07:37:52 INFO (MainThread) [hassio.snapshots] Found 1 snapshot files
20-02-09 07:37:52 INFO (MainThread) [hassio.discovery] Load 1 messages
20-02-09 07:37:52 INFO (MainThread) [hassio.ingress] Load 0 ingress session
20-02-09 07:37:52 INFO (MainThread) [hassio.secrets] Load Home Assistant secrets: 16
20-02-09 07:37:52 INFO (MainThread) [__main__] Run Hass.io
20-02-09 07:37:52 INFO (MainThread) [hassio.api] Start API on 172.30.32.2
20-02-09 07:37:52 INFO (MainThread) [hassio.addons] Phase 'initialize' start 0 add-ons
20-02-09 07:37:52 INFO (MainThread) [hassio.addons] Phase 'system' start 3 add-ons
20-02-09 07:38:00 INFO (SyncWorker_6) [hassio.docker.addon] Start Docker add-on homeassistant/amd64-addon-mosquitto with version 5.1
20-02-09 07:38:01 INFO (SyncWorker_3) [hassio.docker.addon] Start Docker add-on homeassistant/amd64-addon-mariadb with version 1.3
20-02-09 07:38:02 INFO (SyncWorker_12) [hassio.docker.addon] Start Docker add-on hassioaddons/sonweb-amd64 with version 0.9.0
20-02-09 07:38:07 INFO (MainThread) [hassio.addons] Phase 'services' start 6 add-ons
20-02-09 07:38:09 INFO (MainThread) [hassio.services.modules.mqtt] Set core_mosquitto as service provider for mqtt
20-02-09 07:38:21 INFO (SyncWorker_16) [hassio.docker.addon] Start Docker add-on homeassistant/amd64-addon-samba with version 9.0
20-02-09 07:38:21 INFO (SyncWorker_19) [hassio.docker.addon] Start Docker add-on hassioaddons/ftp-amd64 with version 3.2.0
20-02-09 07:38:26 INFO (SyncWorker_5) [hassio.docker.addon] Start Docker add-on homeassistant/amd64-addon-duckdns with version 1.10
20-02-09 07:38:30 INFO (SyncWorker_17) [hassio.docker.addon] Start Docker add-on hassioaddons/influxdb-amd64 with version 3.5.1
20-02-09 07:38:30 INFO (SyncWorker_10) [hassio.docker.addon] Start Docker add-on samccauley/amd64-googlebackup with version 1.7.2
20-02-09 07:38:31 INFO (SyncWorker_6) [hassio.docker.addon] Start Docker add-on homeassistant/amd64-addon-ssh with version 8.2
20-02-09 07:38:33 INFO (MainThread) [hassio.api.security] /host/info access from a0d7b954_ftp
20-02-09 07:38:36 INFO (SyncWorker_2) [hassio.docker.interface] Start homeassistant/qemux86-64-homeassistant
20-02-09 07:38:52 INFO (MainThread) [hassio.api.security] /host/info access from a0d7b954_influxdb
20-02-09 07:38:58 INFO (MainThread) [hassio.api.security] /dns/info access from a0d7b954_influxdb
20-02-09 07:39:27 INFO (MainThread) [hassio.api.security] /host/info access from a0d7b954_sonweb
20-02-09 07:40:16 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-09 07:41:57 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-09 07:42:12 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-09 07:42:57 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-09 07:43:47 INFO (MainThread) [hassio.homeassistant] Detect a running Home Assistant instance
20-02-09 07:43:47 INFO (MainThread) [hassio.addons] Phase 'application' start 2 add-ons
20-02-09 07:43:54 INFO (SyncWorker_13) [hassio.docker.addon] Start Docker add-on esphome/esphome-hassio-amd64 with version 1.14.3
20-02-09 07:43:56 INFO (SyncWorker_15) [hassio.docker.addon] Start Docker add-on homeassistant/amd64-addon-configurator with version 4.2
20-02-09 07:44:01 INFO (MainThread) [hassio.tasks] All core tasks are scheduled
20-02-09 07:44:01 INFO (MainThread) [hassio.core] Hass.io is up and running
20-02-09 07:44:15 INFO (MainThread) [hassio.api.security] /host/info access from 15ef4d2f_esphome
20-02-09 07:47:25 INFO (SyncWorker_0) [hassio.docker.interface] Restart homeassistant/qemux86-64-homeassistant
20-02-09 07:48:00 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-09 07:48:56 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-09 07:49:11 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-09 07:50:01 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-09 07:50:46 INFO (MainThread) [hassio.homeassistant] Detect a running Home Assistant instance
20-02-09 08:00:19 INFO (MainThread) [hassio.homeassistant] Update Home Assistant to version 0.105.2
20-02-09 08:00:19 INFO (SyncWorker_9) [hassio.docker.interface] Update image homeassistant/qemux86-64-homeassistant:0.104.3 to homeassistant/qemux86-64-homeassistant:0.105.2
20-02-09 08:00:19 INFO (SyncWorker_9) [hassio.docker.interface] Pull image homeassistant/qemux86-64-homeassistant tag 0.105.2.
20-02-09 08:00:26 INFO (SyncWorker_9) [hassio.docker.interface] Stop homeassistant application
20-02-09 08:01:28 INFO (SyncWorker_9) [hassio.docker.interface] Clean homeassistant application
20-02-09 08:01:31 INFO (SyncWorker_7) [hassio.docker.homeassistant] Start homeassistant homeassistant/qemux86-64-homeassistant with version 0.105.2
20-02-09 08:02:21 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-09 08:06:12 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-09 08:06:17 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-09 08:10:48 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-09 08:20:51 WARNING (MainThread) [hassio.homeassistant] Don't wait anymore of Home Assistant startup!
20-02-09 08:20:51 CRITICAL (MainThread) [hassio.homeassistant] HomeAssistant update fails -> rollback!
20-02-09 08:20:51 INFO (MainThread) [hassio.homeassistant] Update Home Assistant to version 0.104.3
20-02-09 08:20:51 INFO (SyncWorker_2) [hassio.docker.interface] Update image homeassistant/qemux86-64-homeassistant:0.105.2 to homeassistant/qemux86-64-homeassistant:0.104.3
20-02-09 08:20:51 INFO (SyncWorker_2) [hassio.docker.interface] Pull image homeassistant/qemux86-64-homeassistant tag 0.104.3.
20-02-09 08:20:53 INFO (SyncWorker_2) [hassio.docker.interface] Stop homeassistant application
20-02-09 08:20:55 INFO (SyncWorker_2) [hassio.docker.interface] Clean homeassistant application
20-02-09 08:20:57 INFO (SyncWorker_12) [hassio.docker.homeassistant] Start homeassistant homeassistant/qemux86-64-homeassistant with version 0.104.3
20-02-09 08:21:32 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-09 08:24:58 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-09 08:25:13 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-09 08:25:58 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-09 08:26:08 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation in progress
20-02-09 08:29:44 INFO (MainThread) [hassio.homeassistant] Home Assistant pip installation done
20-02-09 08:30:14 INFO (MainThread) [hassio.homeassistant] Detect a running Home Assistant instance
20-02-09 08:30:14 INFO (MainThread) [hassio.homeassistant] Successful run Home Assistant 0.104.3
20-02-09 08:30:15 INFO (SyncWorker_14) [hassio.docker.interface] Cleanup images: ['homeassistant/qemux86-64-homeassistant:0.105.2']

DavidFW1960 · February 9, 2020, 8:51am

Ok… so seems there is some breaking change between 104.3 and 105.2. Did you check the breaking changes? That log is the supervisor log. What about the homa assistant log?

naamah75 · February 9, 2020, 9:32am

Already tried to disable almost all in the config, but the problem is still here… is there any way to completely reset the home assistant? (like factory reset)