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

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.

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

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

[email protected]:~$ 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.,

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?

[email protected]:~$ 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

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.

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