Hass.io supervisor update 185 to 188 problem on docker over Ubuntu Server 18.04

Installation Home Assistant OS
1

Hello,
I’m very new to HA/Hass.io world.

I’m having this issue with the update of supervisor:

19-09-16 16:16:24 INFO (MainThread) [__main__] Run Hass.io


19-09-16 16:16:24 INFO (MainThread) [hassio.api] Start API on 172.30.32.2


19-09-16 16:16:24 INFO (MainThread) [hassio.supervisor] Update Supervisor to version 188


19-09-16 16:16:24 INFO (SyncWorker_1) [hassio.docker.interface] Update image homeassistant/amd64-hassio-supervisor:185 to homeassistant/amd64-hassio-supervisor:188


19-09-16 16:16:24 INFO (SyncWorker_1) [hassio.docker.interface] Pull image homeassistant/amd64-hassio-supervisor tag 188.


19-09-16 16:16:45 INFO (SyncWorker_1) [hassio.docker.interface] Tag image homeassistant/amd64-hassio-supervisor with version 188 as latest


19-09-16 16:16:45 INFO (SyncWorker_1) [hassio.docker.interface] Stop hassio_supervisor application


19-09-16 16:16:46 INFO (MainThread) [__main__] Stopping Hass.io


19-09-16 16:16:46 INFO (MainThread) [hassio.misc.forwarder] Stop DNS forwarding


19-09-16 16:16:46 INFO (MainThread) [hassio.api] Stop API on 172.30.32.2


19-09-16 16:16:46 INFO (MainThread) [hassio.core] Hass.io is down


19-09-16 16:16:46 INFO (MainThread) [__main__] Close Hass.io

I’ve took this log from portainer, after this, even if I try to restart supervisor, it repeat the same update procedure.

Thanks for any help.

2

That’s normal on start-up there’s always a pull for newest image

3

yes but it is always the same, the update process stop after the last line

4 
carlo@beebox:~$ journalctl -fu hassio-supervisor
-- Logs begin at Wed 2019-09-04 00:03:08 CEST. --
Sep 16 20:30:44 beebox systemd[1]: Starting Hass.io supervisor...
Sep 16 20:30:44 beebox docker[19079]: cannot change profile for the next exec call: No such file or directory
Sep 16 20:30:44 beebox docker[19079]: snap-update-ns failed with code 1: File exists
Sep 16 20:30:44 beebox systemd[1]: Started Hass.io supervisor.
Sep 16 20:30:44 beebox systemd[1]: Stopping Hass.io supervisor...
Sep 16 20:30:45 beebox docker[19141]: cannot change profile for the next exec call: No such file or directory
Sep 16 20:30:45 beebox docker[19141]: snap-update-ns failed with code 1: File exists
Sep 16 20:30:45 beebox systemd[1]: Stopped Hass.io supervisor.
Sep 16 20:30:45 beebox systemd[1]: Dependency failed for Hass.io supervisor.
Sep 16 20:30:45 beebox systemd[1]: hassio-supervisor.service: Job hassio-supervisor.service/start failed with result 'dependency'.

From portainer I’m seeing that also hassioaddons/ssh-amd64:6.3.3 is down

2019-09-16T15:38:42.566245372Z [s6-init] making user provided files available at /var/run/s6/etc...exited 0.,
2019-09-16T15:38:42.726117417Z [s6-init] ensuring user provided files have correct perms...exited 0.,
2019-09-16T15:38:42.731265776Z [fix-attrs.d] applying ownership & permissions fixes...,
2019-09-16T15:38:42.737216810Z [fix-attrs.d] permissions: applying... ,
2019-09-16T15:38:42.872575708Z [fix-attrs.d] permissions: exited 0.,
2019-09-16T15:38:42.873720011Z [fix-attrs.d] done.,
2019-09-16T15:38:42.875735847Z [cont-init.d] executing container initialization scripts...,
2019-09-16T15:38:42.879409188Z [cont-init.d] 00-banner.sh: executing... ,
2019-09-16T15:38:46.025438839Z curl: (7) Failed to connect to hassio port 80: Host is unreachable,
2019-09-16T15:38:46.042610024Z [17:38:46] ERROR: Something went wrong contacting the API,
2019-09-16T15:38:46.046037117Z [cont-init.d] 00-banner.sh: exited 0.,
2019-09-16T15:38:46.049870384Z [cont-init.d] 01-log-level.sh: executing... ,
2019-09-16T15:38:46.259766865Z [cont-init.d] 01-log-level.sh: exited 0.,
2019-09-16T15:38:46.264254859Z [cont-init.d] docker.sh: executing... ,
2019-09-16T15:38:49.096468813Z curl: (7) Failed to connect to hassio port 80: Host is unreachable,
2019-09-16T15:38:49.108254293Z [17:38:49] ERROR: Something went wrong contacting the API,
2019-09-16T15:38:49.262314660Z [cont-init.d] docker.sh: exited 0.,
2019-09-16T15:38:49.267515645Z [cont-init.d] nginx.sh: executing... ,
2019-09-16T15:38:52.170343999Z curl: (7) Failed to connect to hassio port 80: Host is unreachable,
2019-09-16T15:38:52.179771950Z [17:38:52] ERROR: Something went wrong contacting the API,
2019-09-16T15:38:52.331581901Z [cont-init.d] nginx.sh: exited 0.,
2019-09-16T15:38:52.335614338Z [cont-init.d] ssh.sh: executing... ,
2019-09-16T15:38:52.570692016Z [cont-init.d] ssh.sh: exited 0.,
2019-09-16T15:38:52.574822627Z [cont-init.d] user.sh: executing... ,
2019-09-16T15:38:52.652170132Z ln: /root/addons/addons: File exists,
2019-09-16T15:38:52.658871228Z [17:38:52] WARNING: Failed linking common directory: addons,
2019-09-16T15:38:52.662378544Z ln: /root/backup/backup: File exists,
2019-09-16T15:38:52.668255612Z [17:38:52] WARNING: Failed linking common directory: backup,
2019-09-16T15:38:52.671771003Z ln: /root/config/config: File exists,
2019-09-16T15:38:52.677329072Z [17:38:52] WARNING: Failed linking common directory: config,
2019-09-16T15:38:52.680703811Z ln: /root/share/share: File exists,
2019-09-16T15:38:52.686455389Z [17:38:52] WARNING: Failed linking common directory: share,
2019-09-16T15:38:52.688719879Z ln: /root/ssl/ssl: File exists,
2019-09-16T15:38:52.694678605Z [17:38:52] WARNING: Failed linking common directory: ssl,
2019-09-16T15:38:52.708777189Z ln: /root/.ssh/.ssh: File exists,
2019-09-16T15:38:52.712174080Z [cont-init.d] user.sh: exited 1.,
2019-09-16T15:38:52.746652434Z [cont-finish.d] executing container finish scripts...,
2019-09-16T15:38:52.752996290Z [cont-finish.d] 99-message.sh: executing... ,
2019-09-16T15:38:52.817986544Z -----------------------------------------------------------,
2019-09-16T15:38:52.818324581Z                 Oops! Something went wrong.,
2019-09-16T15:38:52.818597299Z ,
2019-09-16T15:38:52.818894545Z  We are so sorry, but something went terribly wrong when,
2019-09-16T15:38:52.819212568Z  starting or running this add-on.,
2019-09-16T15:38:52.819516652Z  ,
2019-09-16T15:38:52.819847964Z  Be sure to check the log above, line by line, for hints.,
2019-09-16T15:38:52.820136608Z -----------------------------------------------------------,
2019-09-16T15:38:52.822829958Z [cont-finish.d] 99-message.sh: exited 0.,
2019-09-16T15:38:52.825005788Z [cont-finish.d] done.,
2019-09-16T15:38:52.826207885Z [s6-finish] waiting for services.,
2019-09-16T15:38:53.047756253Z [s6-finish] sending all processes the TERM signal.,
2019-09-17T16:32:47.531903153Z [s6-init] making user provided files available at /var/run/s6/etc...exited 0.,
2019-09-17T16:32:47.702935762Z [s6-init] ensuring user provided files have correct perms...exited 0.,
2019-09-17T16:32:47.708716982Z [fix-attrs.d] applying ownership & permissions fixes...,
2019-09-17T16:32:47.717579442Z [fix-attrs.d] permissions: applying... ,
2019-09-17T16:32:47.902165959Z [fix-attrs.d] permissions: exited 0.,
2019-09-17T16:32:47.904238527Z [fix-attrs.d] done.,
2019-09-17T16:32:47.910164501Z [cont-init.d] executing container initialization scripts...,
2019-09-17T16:32:47.915471586Z [cont-init.d] 00-banner.sh: executing... ,
2019-09-17T16:32:51.082033980Z curl: (7) Failed to connect to hassio port 80: Host is unreachable,
2019-09-17T16:32:51.088407288Z [18:32:51] ERROR: Something went wrong contacting the API,
2019-09-17T16:32:51.094282436Z [cont-init.d] 00-banner.sh: exited 0.,
2019-09-17T16:32:51.098567488Z [cont-init.d] 01-log-level.sh: executing... ,
2019-09-17T16:32:51.313665408Z [cont-init.d] 01-log-level.sh: exited 0.,
2019-09-17T16:32:51.318485059Z [cont-init.d] docker.sh: executing... ,
2019-09-17T16:32:54.149742821Z curl: (7) Failed to connect to hassio port 80: Host is unreachable,
2019-09-17T16:32:54.170937352Z [18:32:54] ERROR: Something went wrong contacting the API,
2019-09-17T16:32:54.305820698Z [cont-init.d] docker.sh: exited 0.,
2019-09-17T16:32:54.308495216Z [cont-init.d] nginx.sh: executing... ,
2019-09-17T16:32:57.222228998Z curl: (7) Failed to connect to hassio port 80: Host is unreachable,
2019-09-17T16:32:57.234962565Z [18:32:57] ERROR: Something went wrong contacting the API,
2019-09-17T16:32:57.373464563Z [cont-init.d] nginx.sh: exited 0.,
2019-09-17T16:32:57.378537557Z [cont-init.d] ssh.sh: executing... ,
2019-09-17T16:32:57.623148739Z [cont-init.d] ssh.sh: exited 0.,
2019-09-17T16:32:57.627993878Z [cont-init.d] user.sh: executing... ,
2019-09-17T16:32:57.709194102Z ln: /root/addons/addons: File exists,
2019-09-17T16:32:57.716337902Z [18:32:57] WARNING: Failed linking common directory: addons,
2019-09-17T16:32:57.720641842Z ln: /root/backup/backup: File exists,
2019-09-17T16:32:57.726145957Z [18:32:57] WARNING: Failed linking common directory: backup,
2019-09-17T16:32:57.729781583Z ln: /root/config/config: File exists,
2019-09-17T16:32:57.735757320Z [18:32:57] WARNING: Failed linking common directory: config,
2019-09-17T16:32:57.738943412Z ln: /root/share/share: File exists,
2019-09-17T16:32:57.745009827Z [18:32:57] WARNING: Failed linking common directory: share,
2019-09-17T16:32:57.747200460Z ln: /root/ssl/ssl: File exists,
2019-09-17T16:32:57.752675700Z [18:32:57] WARNING: Failed linking common directory: ssl,
2019-09-17T16:32:57.767754165Z ln: /root/.ssh/.ssh: File exists,
2019-09-17T16:32:57.770567362Z [cont-init.d] user.sh: exited 1.,
2019-09-17T16:32:57.804929981Z [cont-finish.d] executing container finish scripts...,
2019-09-17T16:32:57.811195637Z [cont-finish.d] 99-message.sh: executing... ,
2019-09-17T16:32:57.876962688Z -----------------------------------------------------------,
2019-09-17T16:32:57.877418936Z                 Oops! Something went wrong.,
2019-09-17T16:32:57.877788018Z ,
2019-09-17T16:32:57.878170814Z  We are so sorry, but something went terribly wrong when,
2019-09-17T16:32:57.878543509Z  starting or running this add-on.,
2019-09-17T16:32:57.878909042Z  ,
2019-09-17T16:32:57.879288400Z  Be sure to check the log above, line by line, for hints.,
2019-09-17T16:32:57.879755072Z -----------------------------------------------------------,
2019-09-17T16:32:57.883691792Z [cont-finish.d] 99-message.sh: exited 0.,
2019-09-17T16:32:57.884421745Z [cont-finish.d] done.,
2019-09-17T16:32:57.886528389Z [s6-finish] waiting for services.,
2019-09-17T16:32:58.107637867Z [s6-finish] sending all processes the TERM signal.,
5

new version, 189, same result.
In order to use again Homeassistant I’ve had to reinstall again and start from zero.
Is it possible that no one using a NUC with ubuntu server, docker-ce and hass.io is having this problem?

carlo@beebox:~$ journalctl -fu hassio-supervisor
-- Logs begin at Wed 2019-09-04 00:03:08 CEST. --
Sep 25 21:12:41 beebox docker[5805]: snap-update-ns failed with code 1: File exists
Sep 25 21:12:41 beebox systemd[1]: Started Hass.io supervisor.
Sep 25 21:12:42 beebox hassio-supervisor[5860]: Error: No such object: homeassistant/amd64-hassio-supervisor
Sep 25 21:12:42 beebox systemd[1]: hassio-supervisor.service: Main process exited, code=exited, status=1/FAILURE
Sep 25 21:12:42 beebox systemd[1]: hassio-supervisor.service: Failed with result 'exit-code'.
Sep 25 21:12:47 beebox systemd[1]: hassio-supervisor.service: Service hold-off time over, scheduling restart.
Sep 25 21:12:47 beebox systemd[1]: hassio-supervisor.service: Scheduled restart job, restart counter is at 2.
Sep 25 21:12:47 beebox systemd[1]: Stopped Hass.io supervisor.
Sep 25 21:12:47 beebox systemd[1]: Dependency failed for Hass.io supervisor.
Sep 25 21:12:47 beebox systemd[1]: hassio-supervisor.service: Job hassio-supervisor.service/start failed with result 'dependency'.

and from portainer I’m seeing:

19-09-25 19:12:39 INFO (MainThread) [__main__] Initialize Hass.io setup


19-09-25 19:12:40 INFO (SyncWorker_0) [hassio.docker.supervisor] Attach to Supervisor homeassistant/amd64-hassio-supervisor with version 188


19-09-25 19:12:40 INFO (SyncWorker_1) [hassio.docker.interface] Cleanup images: ['homeassistant/amd64-hassio-supervisor:189', 'homeassistant/amd64-hassio-supervisor:latest']


19-09-25 19:12:40 INFO (MainThread) [__main__] Setup HassIO


19-09-25 19:12:40 INFO (MainThread) [hassio.utils.gdbus] Connect to dbus: org.freedesktop.systemd1 - /org/freedesktop/systemd1


19-09-25 19:12:40 INFO (MainThread) [hassio.utils.gdbus] Connect to dbus: org.freedesktop.hostname1 - /org/freedesktop/hostname1


19-09-25 19:12:40 WARNING (MainThread) [hassio.dbus.rauc] Host has no rauc support. OTA updates have been disabled.


19-09-25 19:12:41 INFO (MainThread) [hassio.utils.gdbus] Connect to dbus: org.freedesktop.NetworkManager - /org/freedesktop/NetworkManager/DnsManager


19-09-25 19:12:41 INFO (MainThread) [hassio.host.info] Update local host information


19-09-25 19:12:41 INFO (MainThread) [hassio.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1


19-09-25 19:12:41 INFO (MainThread) [hassio.host.services] Update service information


19-09-25 19:12:41 INFO (MainThread) [hassio.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1


19-09-25 19:12:41 INFO (MainThread) [hassio.host.network] Update local network DNS information


19-09-25 19:12:41 INFO (MainThread) [hassio.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager


19-09-25 19:12:41 INFO (MainThread) [hassio.host.apparmor] Load AppArmor Profiles: {'hassio-supervisor'}


19-09-25 19:12:41 INFO (MainThread) [hassio.host.services] Reload local service hassio-apparmor.service


19-09-25 19:12:41 INFO (MainThread) [hassio.utils.gdbus] Call org.freedesktop.systemd1.Manager.ReloadOrRestartUnit on /org/freedesktop/systemd1


19-09-25 19:12:41 INFO (SyncWorker_0) [hassio.docker.interface] Attach to homeassistant/amd64-hassio-dns with version 1


19-09-25 19:12:41 INFO (MainThread) [hassio.dns] Update resolv from Supervisor


19-09-25 19:12:41 INFO (MainThread) [hassio.misc.forwarder] Start DNS port forwarding to 172.30.32.3


19-09-25 19:12:41 INFO (SyncWorker_0) [hassio.docker.interface] Stop hassio_dns application


19-09-25 19:12:42 INFO (SyncWorker_0) [hassio.docker.interface] Clean hassio_dns application


19-09-25 19:12:42 INFO (MainThread) [hassio.dns] Start CoreDNS plugin


19-09-25 19:12:45 INFO (SyncWorker_5) [hassio.docker.dns] Start DNS homeassistant/amd64-hassio-dns with version 1


19-09-25 19:12:45 INFO (SyncWorker_5) [hassio.docker.interface] Attach to homeassistant/intel-nuc-homeassistant with version 0.99.2


19-09-25 19:12:45 INFO (MainThread) [hassio.store.git] Load add-on /data/addons/git/a0d7b954 repository


19-09-25 19:12:45 INFO (MainThread) [hassio.store.git] Load add-on /data/addons/core repository


19-09-25 19:12:45 INFO (MainThread) [hassio.store] Load add-ons from store: 60 all - 60 new - 0 remove


19-09-25 19:12:45 INFO (MainThread) [hassio.addons] Found 9 installed add-ons


19-09-25 19:12:45 INFO (SyncWorker_6) [hassio.docker.interface] Attach to hassioaddons/grafana-amd64 with version 2.3.0


19-09-25 19:12:45 INFO (SyncWorker_0) [hassio.docker.interface] Attach to hassioaddons/mqtt with version v1.1.0


19-09-25 19:12:45 INFO (SyncWorker_1) [hassio.docker.interface] Attach to hassioaddons/log-viewer-amd64 with version 0.6.3


19-09-25 19:12:45 INFO (SyncWorker_5) [hassio.docker.interface] Attach to hassioaddons/node-red-amd64 with version 4.2.0


19-09-25 19:12:45 INFO (SyncWorker_2) [hassio.docker.interface] Attach to homeassistant/amd64-addon-configurator with version 3.5


19-09-25 19:12:45 INFO (SyncWorker_3) [hassio.docker.interface] Attach to hassioaddons/vscode-amd64 with version 0.6.5


19-09-25 19:12:45 INFO (SyncWorker_4) [hassio.docker.interface] Attach to hassioaddons/ssh-amd64 with version 6.3.3


19-09-25 19:12:45 INFO (SyncWorker_8) [hassio.docker.interface] Attach to hassioaddons/influxdb-amd64 with version 3.3.0


19-09-25 19:12:45 INFO (SyncWorker_7) [hassio.docker.interface] Attach to homeassistant/amd64-addon-check_config with version 3.0


19-09-25 19:12:46 INFO (MainThread) [hassio.updater] Fetch update data from https://version.home-assistant.io/stable.json


19-09-25 19:12:46 INFO (MainThread) [hassio.snapshots] Found 0 snapshot files


19-09-25 19:12:46 INFO (MainThread) [hassio.discovery] Load 0 messages


19-09-25 19:12:46 INFO (MainThread) [hassio.ingress] Load 2 ingress session


19-09-25 19:12:46 INFO (MainThread) [hassio.secrets] Load Home Assistant secrets: 1


19-09-25 19:12:46 INFO (MainThread) [__main__] Run Hass.io


19-09-25 19:12:46 INFO (MainThread) [hassio.api] Start API on 172.30.32.2


19-09-25 19:12:46 INFO (MainThread) [hassio.supervisor] Update Supervisor to version 189


19-09-25 19:12:46 INFO (SyncWorker_8) [hassio.docker.interface] Update image homeassistant/amd64-hassio-supervisor:188 to homeassistant/amd64-hassio-supervisor:189


19-09-25 19:12:46 INFO (SyncWorker_8) [hassio.docker.interface] Pull image homeassistant/amd64-hassio-supervisor tag 189.


19-09-25 19:12:55 INFO (SyncWorker_8) [hassio.docker.interface] Tag image homeassistant/amd64-hassio-supervisor with version 189 as latest


19-09-25 19:12:56 INFO (SyncWorker_8) [hassio.docker.interface] Stop hassio_supervisor application


19-09-25 19:12:56 INFO (MainThread) [__main__] Stopping Hass.io


19-09-25 19:12:56 INFO (MainThread) [hassio.misc.forwarder] Stop DNS forwarding


19-09-25 19:12:56 INFO (MainThread) [hassio.api] Stop API on 172.30.32.2


19-09-25 19:12:56 INFO (MainThread) [hassio.core] Hass.io is down


19-09-25 19:12:56 INFO (MainThread) [__main__] Close Hass.io
6

Saw this as I am urgently searching for insight. I have what looks like the exact same issue. Every attempt to restart results in the same log: hass.io is down. Every time it appears to pull down a new image for the same version (in my case version 200).
At first I suspected it was some sort of dependency race during startup but I see this issue being raised in several discussions on the home assistant forum.
I did move from home assistant 104.3 to 105.x maybe 2 days ago. This happened as I was restarting my unraid host for maintenance.

Edit: The workaround is to edit the image and save. This was discovered by digiblur and mentioned in this blog.

7

Thanks for the workaround.
I had to delete image and start from scratch to solve the issue