After a clean new installation of Version 0.108.9 a restart tasks more than 16 minutes

This looks like home assistant cant connect to the ssh addon. Which is installed by default but is disabled. Try going to your profile and enabling advanced mode then checking the SSH addon.

I tested this with different SD cards and different Raspberry’s (3, 3B+, 4) and on top via VirtualBox. Always with the some result. Used mSD cards are:

  • ScanDisk extrem pro 100/90 (MB/sec read/write)
  • Tosihba M402

I don’t think the cause is the hardware

I will check this. After last restart incl. auth_providers in my empty configuartion.yaml a get this in my log:

20-04-27 06:06:38 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 06:06:40 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 06:06:45 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 06:09:41 WARNING (MainThread) [supervisor.homeassistant] Don't wait anymore of Home Assistant startup!
20-04-27 06:09:41 ERROR (MainThread) [asyncio] Task exception was never retrieved
future: <Task finished coro=<process_lock.<locals>.wrap_api() done, defined at /usr/src/supervisor/supervisor/utils/__init__.py:22> exception=HomeAssistantError()>
Traceback (most recent call last):
  File "/usr/src/supervisor/supervisor/utils/__init__.py", line 31, in wrap_api
    return await method(api, *args, **kwargs)
  File "/usr/src/supervisor/supervisor/homeassistant.py", line 417, in restart
    await self._block_till_run()
  File "/usr/src/supervisor/supervisor/homeassistant.py", line 627, in _block_till_run
    raise HomeAssistantError()
supervisor.exceptions.HomeAssistantError
20-04-27 06:12:35 INFO (MainThread) [supervisor.host.info] Update local host information
20-04-27 06:12:35 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-04-27 06:12:36 INFO (MainThread) [supervisor.host.services] Update service information
20-04-27 06:12:36 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-04-27 06:12:36 INFO (MainThread) [supervisor.host.network] Update local network DNS information
20-04-27 06:12:36 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager
20-04-27 06:12:36 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information
20-04-27 06:16:12 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh

when I start the SSH & Web Terminal addon I get this log back by the addon:

[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] permissions: applying... 
[fix-attrs.d] permissions: exited 0.
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] 00-banner.sh: executing... 
-----------------------------------------------------------
 Add-on: SSH & Web Terminal
 SSH & Web Terminal access to your Home Assistant instance
-----------------------------------------------------------
 Add-on version: 7.4.0
 You are running the latest version of this add-on.
 System: HassOS 3.13  (armv7 / raspberrypi3)
 Home Assistant Core: 0.108.9
 Home Assistant Supervisor: 219
-----------------------------------------------------------
 Please, share the above information when looking for help
 or support in, e.g., GitHub, forums or the Discord chat.
-----------------------------------------------------------
[cont-init.d] 00-banner.sh: exited 0.
[cont-init.d] 01-log-level.sh: executing... 
[cont-init.d] 01-log-level.sh: exited 0.
[cont-init.d] docker.sh: executing... 
[cont-init.d] docker.sh: exited 0.
[cont-init.d] mosquitto.sh: executing... 
[cont-init.d] mosquitto.sh: exited 0.
[cont-init.d] mysql.sh: executing... 
[cont-init.d] mysql.sh: exited 0.
[cont-init.d] ssh.sh: executing... 
[08:22:22] INFO: Password is NOT in the Have I Been Pwned database! Nice!
[08:22:23] WARNING: 
[08:22:23] WARNING: Logging in with a SSH password is security wise, a bad idea!
[08:22:23] WARNING: Please, consider using a public/private key pair.
[08:22:23] WARNING: What is this? https://kb.iu.edu/d/aews
[08:22:23] WARNING: 
[cont-init.d] ssh.sh: exited 0.
[cont-init.d] user.sh: executing... 
[08:22:28] NOTICE: Session sharing has been disabled!
[cont-init.d] user.sh: exited 0.
[cont-init.d] done.
[services.d] starting services
[services.d] done.
[08:22:29] INFO: Starting the ttyd daemon...
[08:22:29] INFO: Starting the Home Assistant STDIN service...
[08:22:29] INFO: Starting the SSH daemon...
Server listening on 0.0.0.0 port 22.
Server listening on :: port 22.

looks ok … or?

Yeah looks ok. So that’s not the issue.

It is really strange. I just install home assistant completely new otherwise I don’t do anything and the error occurs immediately. Regardless of the hardware.
For me is the question, is my network the cause or is it a general problem? Has anyone installed a new system with the current HA version without any issues? In my network runs two other HA instances with different hostnames and dndsd service names. This two run’s perfect together. I connect them via https://github.com/lukas-hetzenecker/home-assistant-remote (btw. great job ). Now I need a new HA instance and I have this issue.

In you logging I see also DNS errors…

could there possibly something wrong there? Using custom DNS or DNS servers?

You are right … I see it. No I don’t use custom DNS or DNS Server. I have a complete unifi network (switches USG Wifi AP etc) incl. a USG which is responsible for DNS. I already find out that HA has problems if you are use multiple instance for HA with the same dnssd service name. I know if you use dnssd to publish service names which are already publish you have to handle this, like same service name plus for example add #1. Or change the name to a free one. In my system I have two HA instances running with following dnssd service names:

  • SFHome
  • VMHome

HA creates by default a dnssd service name “Home”. So I thing it should be ok.

Try this command:

ha dns info

Ok. Result is:
The Host is not right, or?

➜  ~ ha dns info       
host: 172.30.32.3
locals:
- dns://192.168.1.1
servers: []
version: "9"
version_latest: "9"

I think this is exactly the same issue. But I used the official image from home-assistant direct with etcher. Without any modification.

No, that is ok. 172.30… is the docker container network

Is your router IP address 192.168.1.1?

Is it set up to forward DNS queries?

Try running:

ha dns logs

Ok thx, Yes my router is 192.168.1.1 and yes it is set up for forward DNS queries.

➜  ~ ha dns logs       
[INFO] 172.30.32.1:55936 - 10737 "AAAA IN updater.home-assistant.io. udp 43 false 512" NOERROR qr,rd,ra 138 0.005111092s
[INFO] 172.30.32.1:55936 - 9904 "A IN updater.home-assistant.io. udp 43 false 512" NOERROR qr,rd,ra 125 0.01576864s
[INFO] 172.30.32.1:42957 - 59521 "AAAA IN aa015h6buqvih86i1.api.met.no. udp 46 false 512" NOERROR qr,rd,ra 116 0.005134061s
[INFO] 172.30.32.1:42957 - 57802 "A IN aa015h6buqvih86i1.api.met.no. udp 46 false 512" NOERROR qr,rd,ra 90 0.006404164s
[INFO] 172.30.32.1:39530 - 15831 "A IN account-link.nabucasa.com. udp 43 false 512" NOERROR qr,rd,ra 125 0.01595541s
[INFO] 172.30.32.1:39530 - 17446 "AAAA IN account-link.nabucasa.com. udp 43 false 512" NOERROR qr,rd,ra 142 0.018147962s
[INFO] 172.30.32.1:45652 - 45491 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.001065052s
[INFO] 172.30.32.1:53839 - 45861 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.001952499s
[INFO] 172.30.32.1:47382 - 53013 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000258281s
[INFO] 172.30.32.1:35600 - 54834 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000304635s
[INFO] 172.30.32.1:56085 - 20788 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.00031349s
[INFO] 172.30.32.1:47116 - 61716 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000644219s
[INFO] 172.30.32.1:60586 - 7825 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.00054375s
[INFO] 172.30.32.1:36316 - 10623 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.001582917s
[INFO] 172.30.32.1:44342 - 64579 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000645937s
[INFO] 172.30.32.1:58495 - 3143 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.001286405s
[INFO] 172.30.32.1:34199 - 32432 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000356875s
[INFO] 172.30.32.1:38023 - 9827 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000692708s
[INFO] 172.30.32.1:40738 - 64898 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000541718s
[INFO] 172.30.32.1:58145 - 11626 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.001358176s
[INFO] 172.30.32.1:35116 - 33291 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.001253958s
[INFO] 172.30.32.1:54747 - 6875 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.006788383s
[INFO] 172.30.32.1:38772 - 55141 "A IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000879635s
[INFO] 172.30.32.1:38772 - 56964 "AAAA IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.001062031s
[INFO] 172.30.32.1:60307 - 7837 "A IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000366927s
[INFO] 172.30.32.1:60307 - 9452 "AAAA IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000495208s
[INFO] 172.30.32.1:43547 - 3517 "AAAA IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000429479s
[INFO] 172.30.32.1:43547 - 1849 "A IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.001370781s
[INFO] 172.30.32.1:47267 - 20607 "A IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000350156s
[INFO] 172.30.32.1:47267 - 22222 "AAAA IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000570208s
[INFO] 172.30.32.1:47165 - 27688 "A IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000867344s
[INFO] 172.30.32.1:47165 - 29407 "AAAA IN core-samba.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.001076197s
[INFO] 172.30.32.1:45961 - 50924 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000562291s
[INFO] 172.30.32.1:52103 - 55459 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000741458s
[INFO] 172.30.32.1:33573 - 43579 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000446979s
[INFO] 172.30.32.1:39894 - 46563 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000708594s
[INFO] 172.30.32.1:48784 - 2009 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000293645s
[INFO] 172.30.32.1:59507 - 6951 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000636614s
[INFO] 172.30.32.1:36733 - 35509 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000589578s
[INFO] 172.30.32.1:52957 - 47003 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000784108s
[INFO] 172.30.32.1:52263 - 44718 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000521037s
[INFO] 172.30.32.1:46786 - 36190 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.00098546s
[INFO] 172.30.32.1:59270 - 45124 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000626714s
[INFO] 172.30.32.1:42064 - 35965 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.00083791s

I installed a new one and just restart it at 10:32:03

20-04-27 10:32:03 INFO (SyncWorker_14) [supervisor.docker.interface] Restart homeassistant/raspberrypi3-homeassistant
20-04-27 10:33:45 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:33:47 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 10:33:51 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:34:05 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:34:06 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 10:34:14 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 10:34:16 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:34:55 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:38:57 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:38:59 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 10:39:02 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
➜  ~ ha supervisor logs
20-04-27 10:32:03 INFO (SyncWorker_14) [supervisor.docker.interface] Restart homeassistant/raspberrypi3-homeassistant
20-04-27 10:33:45 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:33:47 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 10:33:51 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:34:05 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:34:06 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 10:34:14 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 10:34:16 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:34:55 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:38:57 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:38:59 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 10:39:02 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 10:42:13 WARNING (MainThread) [supervisor.homeassistant] Don't wait anymore of Home Assistant startup!
20-04-27 10:42:13 ERROR (MainThread) [asyncio] Task exception was never retrieved
future: <Task finished coro=<process_lock.<locals>.wrap_api() done, defined at /usr/src/supervisor/supervisor/utils/__init__.py:22> exception=HomeAssistantError()>
Traceback (most recent call last):
  File "/usr/src/supervisor/supervisor/utils/__init__.py", line 31, in wrap_api
    return await method(api, *args, **kwargs)
  File "/usr/src/supervisor/supervisor/homeassistant.py", line 417, in restart
    await self._block_till_run()
  File "/usr/src/supervisor/supervisor/homeassistant.py", line 627, in _block_till_run
    raise HomeAssistantError()
supervisor.exceptions.HomeAssistantError
20-04-27 10:49:00 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh

I found something out. What I done … I installed a new HA v.0.108.9 and after first boot up I restore an already running fine backup from my existing HA instance with version v.0.103.5. Replace the configaration.yaml to a fresh default configuration.yaml, delete all integrations and changed the hostname and service name to a new one. The systems runs perfect. Every restart takes as it should be. Then I tought you could update this to v.0.108.9. Result it fails again with:

20-04-27 11:30:37 INFO (SyncWorker_0) [supervisor.docker.interface] Pull image hassioaddons/ssh-armv7 tag 7.4.0.
20-04-27 11:30:40 INFO (SyncWorker_0) [supervisor.docker.interface] Stop addon_a0d7b954_ssh application
20-04-27 11:30:41 INFO (SyncWorker_0) [supervisor.docker.interface] Clean addon_a0d7b954_ssh application
20-04-27 11:30:41 INFO (SyncWorker_10) [supervisor.docker.interface] Cleanup images: ['hassioaddons/ssh-armv7:7.3.2']
20-04-27 11:30:47 INFO (MainThread) [supervisor.addons] Add-on 'a0d7b954_ssh' successfully updated
20-04-27 11:30:47 WARNING (MainThread) [supervisor.addons.validate] Unknown options web
20-04-27 11:30:49 INFO (SyncWorker_16) [supervisor.docker.addon] Start Docker add-on hassioaddons/ssh-armv7 with version 7.4.0
20-04-27 11:30:55 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_ssh
20-04-27 11:31:07 INFO (SyncWorker_11) [supervisor.docker.interface] Update image homeassistant/armv7-addon-samba:9.1.0 to homeassistant/armv7-addon-samba:9.2.0
20-04-27 11:31:07 INFO (SyncWorker_11) [supervisor.docker.interface] Pull image homeassistant/armv7-addon-samba tag 9.2.0.
20-04-27 11:31:09 INFO (MainThread) [supervisor.homeassistant] Updated Home Assistant API token
20-04-27 11:31:09 INFO (SyncWorker_11) [supervisor.docker.interface] Stop addon_core_samba application
20-04-27 11:31:13 INFO (SyncWorker_11) [supervisor.docker.interface] Clean addon_core_samba application
20-04-27 11:31:14 INFO (SyncWorker_16) [supervisor.docker.interface] Cleanup images: ['homeassistant/armv7-addon-samba:9.1.0']
20-04-27 11:31:14 INFO (MainThread) [supervisor.addons] Add-on 'core_samba' successfully updated
20-04-27 11:31:16 INFO (SyncWorker_2) [supervisor.docker.addon] Start Docker add-on homeassistant/armv7-addon-samba with version 9.2.0
20-04-27 11:31:49 INFO (MainThread) [supervisor.homeassistant] Update Home Assistant to version 0.108.9
20-04-27 11:31:49 INFO (SyncWorker_14) [supervisor.docker.interface] Update image homeassistant/raspberrypi3-homeassistant:0.103.5 to homeassistant/raspberrypi3-homeassistant:0.108.9
20-04-27 11:31:49 INFO (SyncWorker_14) [supervisor.docker.interface] Pull image homeassistant/raspberrypi3-homeassistant tag 0.108.9.
20-04-27 11:40:26 INFO (SyncWorker_14) [supervisor.docker.interface] Stop homeassistant application
20-04-27 11:41:28 INFO (SyncWorker_14) [supervisor.docker.interface] Clean homeassistant application
20-04-27 11:41:29 INFO (MainThread) [supervisor.homeassistant] Update pulse/client.config: /data/tmp/homeassistant_pulse
20-04-27 11:41:30 INFO (SyncWorker_18) [supervisor.docker.homeassistant] Start homeassistant homeassistant/raspberrypi3-homeassistant with version 0.108.9
20-04-27 11:46:48 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 11:47:09 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 11:47:15 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 11:48:43 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 11:48:45 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 11:51:33 WARNING (MainThread) [supervisor.homeassistant] Don't wait anymore of Home Assistant startup!
20-04-27 11:51:33 CRITICAL (MainThread) [supervisor.homeassistant] HomeAssistant update fails -> rollback!
20-04-27 11:51:33 INFO (MainThread) [supervisor.homeassistant] Update Home Assistant to version 0.103.5
20-04-27 11:51:33 INFO (SyncWorker_12) [supervisor.docker.interface] Update image homeassistant/raspberrypi3-homeassistant:0.108.9 to homeassistant/raspberrypi3-homeassistant:0.103.5
20-04-27 11:51:33 INFO (SyncWorker_12) [supervisor.docker.interface] Pull image homeassistant/raspberrypi3-homeassistant tag 0.103.5.
20-04-27 11:51:36 INFO (SyncWorker_12) [supervisor.docker.interface] Stop homeassistant application
20-04-27 11:51:38 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh

I think there is a bug in HA newer versions.
Funny is i have one HA instance with version 0.108.9 that works great. I try the whole thing again with a restore from this instance and see if it works.

My reboots are taking longer since 108

Mine are bout the same (<60 sec). Have you checked the home assistant log?

Some news … I tried to restore from my fine running instance v.0.108.9 and result is the same issue, but without errors?. What I done was the same as I done before with the backup from v.0.103.5. This instance with version v.0.108.9 was growing up version by version from an old one to the current version v.0.108.9. Strange is that there is now error just a warning [supervisor.homeassistant] Don’t wait anymore of Home Assistant startup!

20-04-27 12:48:26 INFO (SyncWorker_10) [supervisor.docker.cli] Start CLI homeassistant/armv7-hassio-cli with version 25 - 172.30.32.5
20-04-27 12:48:27 INFO (SyncWorker_7) [supervisor.docker.audio] Start Audio homeassistant/armv7-hassio-audio with version 14 - 172.30.32.4
20-04-27 12:48:27 INFO (SyncWorker_14) [supervisor.docker.dns] Start DNS homeassistant/armv7-hassio-dns with version 9 - 172.30.32.3
20-04-27 12:48:27 INFO (MainThread) [supervisor.plugins.dns] Updated /etc/resolv.conf
20-04-27 12:48:27 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-04-27 12:48:27 INFO (SyncWorker_5) [supervisor.docker.interface] Attach to homeassistant/raspberrypi3-homeassistant with version 0.108.8
20-04-27 12:48:27 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /
20-04-27 12:48:27 INFO (MainThread) [supervisor.hassos] Detect HassOS 3.13 / BootSlot A
20-04-27 12:48:29 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/git/a0d7b954 repository
20-04-27 12:48:29 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/core repository
20-04-27 12:48:29 INFO (MainThread) [supervisor.store] Load add-ons from store: 65 all - 65 new - 0 remove
20-04-27 12:48:29 INFO (MainThread) [supervisor.addons] Found 3 installed add-ons
20-04-27 12:48:30 INFO (SyncWorker_12) [supervisor.docker.interface] Attach to homeassistant/armv7-addon-configurator with version 5.0.0
20-04-27 12:48:30 INFO (SyncWorker_4) [supervisor.docker.interface] Attach to homeassistant/armv7-addon-samba with version 9.2.0
20-04-27 12:48:30 INFO (SyncWorker_13) [supervisor.docker.interface] Attach to hassioaddons/ssh-armv7 with version 7.4.0
20-04-27 12:48:30 INFO (MainThread) [supervisor.snapshots] Found 2 snapshot files
20-04-27 12:48:30 INFO (MainThread) [supervisor.discovery] Load 0 messages
20-04-27 12:48:30 INFO (MainThread) [supervisor.ingress] Load 5 ingress session
20-04-27 12:48:30 INFO (MainThread) [supervisor.secrets] Load Home Assistant secrets: 27
20-04-27 12:48:30 INFO (MainThread) [__main__] Run Supervisor
20-04-27 12:48:30 INFO (MainThread) [supervisor.api] Start API on 172.30.32.2
20-04-27 12:48:30 INFO (MainThread) [supervisor.utils.gdbus] Call de.pengutronix.rauc.Installer.Mark on /
20-04-27 12:48:30 INFO (MainThread) [supervisor.hassos] Rauc: A - marked slot kernel.0 as good
20-04-27 12:48:30 INFO (MainThread) [supervisor.addons] Phase 'initialize' start 0 add-ons
20-04-27 12:48:30 INFO (MainThread) [supervisor.addons] Phase 'system' start 0 add-ons
20-04-27 12:48:30 INFO (MainThread) [supervisor.addons] Phase 'services' start 2 add-ons
20-04-27 12:48:32 INFO (SyncWorker_11) [supervisor.docker.addon] Start Docker add-on homeassistant/armv7-addon-samba with version 9.2.0
20-04-27 12:48:32 INFO (SyncWorker_12) [supervisor.docker.addon] Start Docker add-on hassioaddons/ssh-armv7 with version 7.4.0
20-04-27 12:48:37 INFO (SyncWorker_17) [supervisor.docker.interface] Start homeassistant/raspberrypi3-homeassistant
20-04-27 12:48:39 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_ssh
20-04-27 12:52:51 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 12:52:55 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 12:53:13 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 12:53:15 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 12:54:56 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 12:54:58 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 12:55:04 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 12:55:05 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 12:57:51 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-27 12:57:52 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 12:58:42 WARNING (MainThread) [supervisor.homeassistant] Don't wait anymore of Home Assistant startup!
20-04-27 12:58:42 INFO (MainThread) [supervisor.addons] Phase 'application' start 1 add-ons
20-04-27 12:58:45 INFO (SyncWorker_18) [supervisor.docker.addon] Start Docker add-on homeassistant/armv7-addon-configurator with version 5.0.0
20-04-27 12:58:50 INFO (MainThread) [supervisor.tasks] All core tasks are scheduled
20-04-27 12:58:50 INFO (MainThread) [supervisor.hwmon] Started Supervisor hardware monitor
20-04-27 12:58:50 INFO (MainThread) [supervisor.core] Supervisor is up and running
20-04-27 12:58:50 INFO (MainThread) [supervisor.host.info] Update local host information
20-04-27 12:58:50 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-04-27 12:58:50 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-04-27 12:58:51 INFO (MainThread) [supervisor.host.services] Update service information
20-04-27 12:58:51 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-04-27 12:58:52 INFO (MainThread) [supervisor.host.network] Update local network DNS information
20-04-27 12:58:52 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager
20-04-27 12:58:52 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information
20-04-27 13:00:11 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-27 13:01:15 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
➜  ~ 

So I am really interesseted if there is someone how already has install succesfully a new fresh installation of v.0.108.9 without any issues? Or if the issues has something to do with my network?

I tried several days to install a new hassio system but always with an issue. I tried it on different hardware like Raspberry’s an VirtualBox. I tried it on different networks. I following this instructions https://www.home-assistant.io/getting-started/ . The problem is after a new installation of hassio without any modifications every restart of the system takes over 16 minutes. Always with the log entry “Don’t wait anymore of Home Assistant startup”. The system itself after long restart after the first installation- boot-up seems to run fine. I have an old backup from version 0.103.5 which is running fine boot up in 30sec. If I make a restore from this backup without updating the system it runs perfect. If I start to update this fine running v.103.5 the update process always fails and it rollback with the same log entry “Don’t wait anymore of Home Assistant startup”. I also have a perfect running version of v.0.109.0. This version grows up via updates version by version. If I make a restore of this backup … the same result every restart takes over 16 minutes incl. the log entry. I am really frustrated and don’t know whats wrong. Is there anybody who installed a new hassio without such issues? It does’t matter if you using Raspberry or VirtualMachine like VirtualBox.

Following a example is a log of a total new installation (start 08:51 … 09:01 Don’t wait anymore of Home Assistant startup)

supervisor logs

20-04-30 08:51:24 INFO (MainThread) [supervisor.bootstrap] Use the old homeassistant repository for machine extraction
20-04-30 08:51:24 INFO (MainThread) [__main__] Initialize Supervisor setup
20-04-30 08:51:25 INFO (MainThread) [supervisor.bootstrap] Setup coresys for machine: qemux86-64
20-04-30 08:51:25 INFO (SyncWorker_0) [supervisor.docker.supervisor] Attach to Supervisor homeassistant/amd64-hassio-supervisor with version 219
20-04-30 08:51:25 INFO (MainThread) [__main__] Setup Supervisor
20-04-30 08:51:25 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.systemd1 - /org/freedesktop/systemd1
20-04-30 08:51:25 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.hostname1 - /org/freedesktop/hostname1
20-04-30 08:51:25 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: de.pengutronix.rauc - /
20-04-30 08:51:25 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.NetworkManager - /org/freedesktop/NetworkManager/DnsManager
20-04-30 08:51:25 INFO (MainThread) [supervisor.host.info] Update local host information
20-04-30 08:51:25 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-04-30 08:51:25 INFO (MainThread) [supervisor.host.services] Update service information
20-04-30 08:51:25 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-04-30 08:51:25 INFO (MainThread) [supervisor.host.network] Update local network DNS information
20-04-30 08:51:25 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager
20-04-30 08:51:25 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information
20-04-30 08:51:25 INFO (MainThread) [supervisor.host.apparmor] Load AppArmor Profiles: {'hassio-supervisor'}
20-04-30 08:51:25 INFO (MainThread) [supervisor.host.services] Reload local service hassos-apparmor.service
20-04-30 08:51:25 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ReloadOrRestartUnit on /org/freedesktop/systemd1
20-04-30 08:51:25 INFO (SyncWorker_4) [supervisor.docker.interface] Attach to homeassistant/amd64-hassio-dns with version 9
20-04-30 08:51:25 INFO (SyncWorker_3) [supervisor.docker.interface] Attach to homeassistant/amd64-hassio-audio with version 14
20-04-30 08:51:25 INFO (SyncWorker_5) [supervisor.docker.interface] Attach to homeassistant/amd64-hassio-multicast with version 2
20-04-30 08:51:25 INFO (SyncWorker_0) [supervisor.docker.interface] Attach to homeassistant/amd64-hassio-cli with version 25
20-04-30 08:51:25 INFO (MainThread) [supervisor.misc.forwarder] Start DNS port forwarding to 172.30.32.3
20-04-30 08:51:25 INFO (MainThread) [supervisor.plugins.audio] Start Audio plugin
20-04-30 08:51:25 INFO (MainThread) [supervisor.plugins.dns] Start CoreDNS plugin
20-04-30 08:51:25 INFO (MainThread) [supervisor.plugins.multicast] Start Multicast plugin
20-04-30 08:51:25 INFO (MainThread) [supervisor.plugins.cli] Start cli plugin
20-04-30 08:51:27 INFO (SyncWorker_2) [supervisor.docker.multicast] Start Multicast homeassistant/amd64-hassio-multicast with version 2 - Host
20-04-30 08:51:28 INFO (SyncWorker_0) [supervisor.docker.audio] Start Audio homeassistant/amd64-hassio-audio with version 14 - 172.30.32.4
20-04-30 08:51:28 INFO (SyncWorker_6) [supervisor.docker.dns] Start DNS homeassistant/amd64-hassio-dns with version 9 - 172.30.32.3
20-04-30 08:51:28 INFO (MainThread) [supervisor.plugins.dns] Updated /etc/resolv.conf
20-04-30 08:51:28 INFO (SyncWorker_3) [supervisor.docker.cli] Start CLI homeassistant/amd64-hassio-cli with version 25 - 172.30.32.5
20-04-30 08:51:29 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-04-30 08:51:29 INFO (SyncWorker_9) [supervisor.docker.interface] Attach to homeassistant/qemux86-64-homeassistant with version 0.109.0
20-04-30 08:51:29 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /
20-04-30 08:51:29 INFO (MainThread) [supervisor.hassos] Detect HassOS 3.13 / BootSlot A
20-04-30 08:51:29 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/git/a0d7b954 repository
20-04-30 08:51:29 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/core repository
20-04-30 08:51:29 INFO (MainThread) [supervisor.store] Load add-ons from store: 65 all - 65 new - 0 remove
20-04-30 08:51:29 INFO (MainThread) [supervisor.addons] Found 4 installed add-ons
20-04-30 08:51:30 INFO (SyncWorker_2) [supervisor.docker.interface] Attach to hassioaddons/ssh-amd64 with version 7.4.0
20-04-30 08:51:30 INFO (SyncWorker_3) [supervisor.docker.interface] Attach to homeassistant/amd64-addon-samba with version 9.2.0
20-04-30 08:51:30 INFO (SyncWorker_6) [supervisor.docker.interface] Attach to hassioaddons/log-viewer-amd64 with version 0.8.1
20-04-30 08:51:30 INFO (SyncWorker_0) [supervisor.docker.interface] Attach to homeassistant/amd64-addon-configurator with version 5.0.0
20-04-30 08:51:30 INFO (MainThread) [supervisor.snapshots] Found 0 snapshot files
20-04-30 08:51:30 INFO (MainThread) [supervisor.discovery] Load 0 messages
20-04-30 08:51:30 INFO (MainThread) [supervisor.ingress] Load 14 ingress session
20-04-30 08:51:30 INFO (MainThread) [supervisor.secrets] Load Home Assistant secrets: 1
20-04-30 08:51:30 INFO (MainThread) [__main__] Run Supervisor
20-04-30 08:51:30 INFO (MainThread) [supervisor.api] Start API on 172.30.32.2
20-04-30 08:51:30 INFO (MainThread) [supervisor.utils.gdbus] Call de.pengutronix.rauc.Installer.Mark on /
20-04-30 08:51:30 INFO (MainThread) [supervisor.hassos] Rauc: A - marked slot kernel.0 as good
20-04-30 08:51:30 INFO (MainThread) [supervisor.addons] Phase 'initialize' start 0 add-ons
20-04-30 08:51:30 INFO (MainThread) [supervisor.addons] Phase 'system' start 0 add-ons
20-04-30 08:51:30 INFO (MainThread) [supervisor.addons] Phase 'services' start 2 add-ons
20-04-30 08:51:30 INFO (SyncWorker_7) [supervisor.docker.addon] Start Docker add-on homeassistant/amd64-addon-samba with version 9.2.0
20-04-30 08:51:30 INFO (SyncWorker_5) [supervisor.docker.addon] Start Docker add-on hassioaddons/ssh-amd64 with version 7.4.0
20-04-30 08:51:33 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_ssh
20-04-30 08:51:36 INFO (SyncWorker_7) [supervisor.docker.interface] Start homeassistant/qemux86-64-homeassistant
20-04-30 08:52:10 INFO (MainThread) [supervisor.api.security] /dns/info access from a0d7b954_ssh
20-04-30 08:52:12 INFO (MainThread) [supervisor.api.security] /dns/logs access from a0d7b954_ssh
20-04-30 08:52:15 INFO (MainThread) [supervisor.api.security] /core/logs access from a0d7b954_ssh
20-04-30 08:52:19 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_ssh
20-04-30 09:01:35 WARNING (MainThread) [supervisor.homeassistant] Don't wait anymore of Home Assistant startup!
20-04-30 09:01:35 INFO (MainThread) [supervisor.addons] Phase 'application' start 2 add-ons
20-04-30 09:01:37 INFO (SyncWorker_9) [supervisor.docker.addon] Start Docker add-on hassioaddons/log-viewer-amd64 with version 0.8.1
20-04-30 09:01:37 INFO (SyncWorker_6) [supervisor.docker.addon] Start Docker add-on homeassistant/amd64-addon-configurator with version 5.0.0
20-04-30 09:01:39 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_logviewer
20-04-30 09:01:42 INFO (MainThread) [supervisor.tasks] All core tasks are scheduled
20-04-30 09:01:42 INFO (MainThread) [supervisor.hwmon] Started Supervisor hardware monitor
20-04-30 09:01:42 INFO (MainThread) [supervisor.core] Supervisor is up and running
20-04-30 09:01:42 INFO (MainThread) [supervisor.host.info] Update local host information
20-04-30 09:01:42 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-04-30 09:01:42 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-04-30 09:01:42 INFO (MainThread) [supervisor.host.services] Update service information
20-04-30 09:01:42 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-04-30 09:01:43 INFO (MainThread) [supervisor.host.network] Update local network DNS information
20-04-30 09:01:43 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager
20-04-30 09:01:43 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information
20-04-30 09:01:43 INFO (MainThread) [supervisor.api.security] /dns/info access from a0d7b954_logviewer

configuration.yaml (just add logger)


# Configure a default setup of Home Assistant (frontend, api, etc)
default_config:

# Uncomment this if you are using SSL/TLS, running in Docker container, etc.
# http:
#   base_url: example.duckdns.org:8123

# Text to speech
tts:
  - platform: google_translate

logger:
  default: info

group: !include groups.yaml
automation: !include automations.yaml
script: !include scripts.yaml
scene: !include scenes.yaml

this is the content of the home-assistant.log

2020-04-30 10:51:41 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.
2020-04-30 10:51:41 INFO (MainThread) [homeassistant.setup] Setting up http
2020-04-30 10:51:41 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.0 seconds.
2020-04-30 10:51:41 INFO (MainThread) [homeassistant.setup] Setting up system_log
2020-04-30 10:51:41 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.
2020-04-30 10:51:42 INFO (MainThread) [homeassistant.setup] Setting up recorder
2020-04-30 10:51:42 INFO (MainThread) [homeassistant.setup] Setting up webhook
2020-04-30 10:51:42 INFO (MainThread) [homeassistant.setup] Setup of domain webhook took 0.0 seconds.
2020-04-30 10:51:43 INFO (MainThread) [homeassistant.setup] Setting up alexa
2020-04-30 10:51:43 INFO (MainThread) [homeassistant.setup] Setup of domain alexa took 0.0 seconds.
2020-04-30 10:51:43 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 0.6 seconds.
2020-04-30 10:51:43 INFO (SyncWorker_8) [homeassistant.loader] Loaded camera from homeassistant.components.camera
2020-04-30 10:51:43 INFO (SyncWorker_6) [homeassistant.loader] Loaded google_assistant from homeassistant.components.google_assistant
2020-04-30 10:51:43 INFO (SyncWorker_9) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player
2020-04-30 10:51:43 INFO (SyncWorker_5) [homeassistant.loader] Loaded homekit from homeassistant.components.homekit
2020-04-30 10:51:43 INFO (SyncWorker_7) [homeassistant.loader] Loaded device_tracker from homeassistant.components.device_tracker
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up cloud
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain cloud took 0.1 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up lovelace
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up group
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.0 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up sun
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.3 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up ssdp
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain ssdp took 0.0 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up input_text
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up input_datetime
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up updater
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up scene
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.components.scene] Setting up scene.homeassistant
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up zone
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up script
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain script took 0.0 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up input_select
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up input_boolean
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up met
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain met took 0.0 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up input_number
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up automation
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 0.0 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up person
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up history
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain history took 0.0 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up device_automation
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain device_automation took 0.0 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up system_health
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain system_health took 0.0 seconds.
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up config
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.
2020-04-30 10:51:47 INFO (SyncWorker_7) [homeassistant.loader] Loaded weather from homeassistant.components.weather
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setting up api
2020-04-30 10:51:47 INFO (MainThread) [homeassistant.setup] Setup of domain scene took 0.2 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setting up auth
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.7 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain input_text took 0.3 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain input_datetime took 0.3 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain input_select took 0.3 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain input_boolean took 0.3 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain input_number took 0.2 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setting up weather
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain weather took 0.0 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.1 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain config took 0.1 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setting up search
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain search took 0.0 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.components.weather] Setting up weather.met
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain zone took 0.3 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setting up zeroconf
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain person took 0.2 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2020-04-30 10:51:48 INFO (SyncWorker_7) [homeassistant.loader] Loaded homekit_controller from homeassistant.components.homekit_controller
2020-04-30 10:51:48 INFO (SyncWorker_9) [homeassistant.loader] Loaded ipp from homeassistant.components.ipp
2020-04-30 10:51:48 INFO (SyncWorker_2) [homeassistant.loader] Loaded cast from homeassistant.components.cast
2020-04-30 10:51:48 INFO (SyncWorker_8) [homeassistant.loader] Loaded spotify from homeassistant.components.spotify
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setting up mobile_app
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain zeroconf took 0.3 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 0.3 seconds.
2020-04-30 10:51:48 INFO (SyncWorker_5) [homeassistant.loader] Loaded netatmo from homeassistant.components.netatmo
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain mobile_app took 0.1 seconds.
2020-04-30 10:51:48 INFO (SyncWorker_4) [homeassistant.loader] Loaded notify from homeassistant.components.notify
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setting up frontend
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 0.0 seconds.
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.components.updater] Submitted analytics to Home Assistant servers. Information submitted includes {}
2020-04-30 10:51:48 INFO (MainThread) [homeassistant.setup] Setup of domain updater took 1.2 seconds.
2020-04-30 10:51:49 INFO (MainThread) [homeassistant.setup] Setting up notify
2020-04-30 10:51:49 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 0.0 seconds.
2020-04-30 10:51:49 INFO (SyncWorker_8) [homeassistant.loader] Loaded binary_sensor from homeassistant.components.binary_sensor
2020-04-30 10:51:49 INFO (MainThread) [homeassistant.setup] Setting up map
2020-04-30 10:51:49 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds.
2020-04-30 10:51:49 INFO (MainThread) [homeassistant.setup] Setting up logbook
2020-04-30 10:51:49 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.
2020-04-30 10:51:50 INFO (MainThread) [homeassistant.components.notify] Setting up notify.mobile_app
2020-04-30 10:51:50 INFO (MainThread) [homeassistant.setup] Setting up binary_sensor
2020-04-30 10:51:50 INFO (MainThread) [homeassistant.setup] Setup of domain binary_sensor took 0.0 seconds.
2020-04-30 10:51:50 INFO (MainThread) [homeassistant.setup] Setting up default_config
2020-04-30 10:51:50 INFO (SyncWorker_3) [homeassistant.loader] Loaded stream from homeassistant.components.stream
2020-04-30 10:51:50 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.updater
2020-04-30 10:51:51 INFO (MainThread) [homeassistant.setup] Setting up stream
2020-04-30 10:51:51 INFO (MainThread) [homeassistant.setup] Setup of domain stream took 0.0 seconds.
2020-04-30 10:51:51 INFO (MainThread) [homeassistant.setup] Setup of domain default_config took 0.1 seconds.
2020-04-30 10:51:51 INFO (MainThread) [homeassistant.setup] Setting up hassio
2020-04-30 10:51:51 INFO (SyncWorker_5) [homeassistant.loader] Loaded sonos from homeassistant.components.sonos
2020-04-30 10:51:51 INFO (SyncWorker_4) [homeassistant.loader] Loaded google_translate from homeassistant.components.google_translate
2020-04-30 10:51:51 INFO (MainThread) [homeassistant.setup] Setting up tts
2020-04-30 10:51:51 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 0.0 seconds.
2020-04-30 10:51:51 INFO (MainThread) [homeassistant.setup] Setup of domain hassio took 0.3 seconds.
2020-04-30 11:06:55 INFO (MainThread) [homeassistant.components.cloud.account_link] Timeout fetching tokens for flow 9632c8332ddc4e319b03fe9bcd6173fb
2020-04-30 11:06:55 INFO (MainThread) [homeassistant.bootstrap] Home Assistant initialized in 914.08s
2020-04-30 11:06:55 INFO (MainThread) [homeassistant.core] Starting Home Assistant
2020-04-30 11:06:55 INFO (SyncWorker_4) [homeassistant.components.zeroconf] Starting Zeroconf broadcast
2020-04-30 11:06:55 INFO (MainThread) [homeassistant.core] Timer:starting

supervisor Info

addons:
- description: Expose Home Assistant folders with SMB/CIFS
  icon: true
  installed: 9.2.0
  logo: true
  name: Samba share
  repository: core
  slug: core_samba
  state: started
  version: 9.2.0
- description: Simple browser-based file editor for Home Assistant
  icon: true
  installed: 5.0.0
  logo: true
  name: File editor
  repository: core
  slug: core_configurator
  state: started
  version: 5.0.0
- description: Browser-based log utility for Home Assistant
  icon: true
  installed: 0.8.1
  logo: true
  name: Log Viewer
  repository: a0d7b954
  slug: a0d7b954_logviewer
  state: started
  version: 0.8.1
- description: SSH & Web Terminal access to your Home Assistant instance
  icon: true
  installed: 7.4.0
  logo: true
  name: SSH & Web Terminal
  repository: a0d7b954
  slug: a0d7b954_ssh
  state: started
  version: 7.4.0
addons_repositories:
- https://github.com/hassio-addons/repository
arch: amd64
channel: stable
ip_address: 172.30.32.2
logging: info
timezone: UTC
version: "219"
version_latest: "219"
wait_boot: 5

DNS logs

[INFO] 172.30.32.1:38961 - 6312 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000318378s
[INFO] 172.30.32.1:59693 - 42651 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000307291s
[INFO] 172.30.32.2:41455 - 27775 "A IN version.home-assistant.io. udp 43 false 512" NOERROR qr,rd,ra 125 0.019409382s
[INFO] 172.30.32.2:41455 - 28375 "AAAA IN version.home-assistant.io. udp 43 false 512" NOERROR qr,rd,ra 149 0.031482818s
[INFO] 172.30.32.1:39031 - 46433 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000245888s
[INFO] 172.30.32.1:36967 - 40140 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000148926s
[INFO] 172.30.32.1:34915 - 4045 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000417391s
[INFO] 172.30.32.1:54991 - 33317 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000177184s
[INFO] 172.30.33.0:50456 - 64160 "A IN api.github.com. udp 32 false 512" NOERROR qr,rd,ra 62 0.013333973s
[INFO] 172.30.33.0:41878 - 64734 "AAAA IN api.github.com. udp 32 false 512" NOERROR qr,rd,ra 129 0.014123584s
[INFO] 172.30.32.1:34711 - 41533 "AAAA IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 42 0.000638609s
[INFO] 172.30.32.1:46619 - 44856 "A IN supervisor.local.hass.io. udp 42 false 512" NOERROR qr,aa,rd 82 0.000425366sha

DNS info (192.168.1.1 is my dns server)

host: 172.30.32.3
locals:
- dns://192.168.1.1
servers: []
version: "9"
version_latest: "9"

for those who are interested. I found the error. The error has existed since HA 0.105.x and is triggered by the netatmo integration. It is probably the same error as this and can also be fixed in this way. Simply add:

netatmo:
    client_id: dummy
    client_secret: dummy

to your configuration file and you’re done. This is definitely a bug in HA and should be fixed in the future. It took me a lot of days to find out. I don’t know why that happens yet. I have netatmo devices on my network. I didn’t want to have this devices in the new HA system. I wanted to test the days to see if the error also occurs when all netatmo devices in my network are off.

How did you figure this out? I have never had any NETAMO devices that I know of nore installed the add in but I have been getting the same issues in my Supervisor >DNS Logs.