search

home-assistant / supervisor

:house_with_garden: Home Assistant Supervisor
https://home-assistant.io/hassio/
Apache License 2.0
1.75k stars 639 forks source link

Supervisor's 'Core hasn't started' API timeout check seems to be firing every time #4655

Closed caraar12345 closed 11 months ago

caraar12345 commented 11 months ago

Describe the issue you are experiencing

4590 fixed the rollback of HA Core by Supervisor not running correctly... however it seems to now be running too often.

Seemingly, HA Core actually fully starts up within this time -- and the frontend is accessible with sensors working fine. This suggests the API is up and responding. But for some reason, Supervisor fails to detect this and continues to rollback the upgrade.

Once the rollback is complete, it detects No API Response again. But because it's not part of an upgrade, it doesn't roll anything back. And thankfully, it doesn't attempt to restart Core again.

What type of installation are you running?

Home Assistant OS

Which operating system are you running on?

Home Assistant Operating System

Steps to reproduce the issue

  1. Attempt to upgrade from 2023.11.0b1 to 2023.11.0b2
  2. Watch as Supervisor upgrades HA Core, then downgrades it again

Anything in the Supervisor logs that might be useful for us?

23-10-30 21:02:32 INFO (MainThread) [supervisor.homeassistant.core] Updating Home Assistant to version 2023.11.0b2
23-10-30 21:02:32 INFO (MainThread) [supervisor.docker.interface] Updating image ghcr.io/home-assistant/generic-x86-64-homeassistant:2023.11.0b1 to ghcr.io/home-assistant/generic-x86-64-homeassistant:2023.11.0b2
23-10-30 21:02:32 INFO (MainThread) [supervisor.docker.interface] Downloading docker image ghcr.io/home-assistant/generic-x86-64-homeassistant with tag 2023.11.0b2.

23-10-30 21:02:39 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from a0d7b954_ssh
23-10-30 21:02:39 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from a0d7b954_ssh
23-10-30 21:02:39 INFO (MainThread) [supervisor.api.middleware.security] /host/info access from a0d7b954_ssh
23-10-30 21:02:39 INFO (MainThread) [supervisor.api.middleware.security] /core/info access from a0d7b954_ssh
23-10-30 21:05:45 INFO (SyncWorker_5) [supervisor.docker.manager] Stopping homeassistant application
23-10-30 21:05:46 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 8
23-10-30 21:05:46 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-10-30 21:05:46 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 8
23-10-30 21:05:46 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-10-30 21:06:49 INFO (SyncWorker_5) [supervisor.docker.manager] Cleaning homeassistant application
23-10-30 21:06:49 INFO (MainThread) [supervisor.homeassistant.module] Update pulse/client.config: /data/tmp/homeassistant_pulse
23-10-30 21:06:49 INFO (MainThread) [supervisor.docker.homeassistant] Starting Home Assistant ghcr.io/home-assistant/generic-x86-64-homeassistant with version 2023.11.0b2
23-10-30 21:06:49 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
23-10-30 21:06:58 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
23-10-30 21:06:59 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete

...

23-10-30 21:09:20 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
23-10-30 21:11:39 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'mqtt-scrypted'
23-10-30 21:11:39 INFO (MainThread) [supervisor.auth] Home Assistant not running, checking cache
23-10-30 21:11:41 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'mqtt-appdaemon'
23-10-30 21:11:41 INFO (MainThread) [supervisor.auth] Home Assistant not running, checking cache
23-10-30 21:11:41 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'mqtt-cec'
23-10-30 21:11:41 INFO (MainThread) [supervisor.api.middleware.security] /network/interface/default/info access from dc822f5c_sambanas
23-10-30 21:11:42 INFO (MainThread) [supervisor.api.middleware.security] /network/interface/enp0s18/info access from dc822f5c_sambanas
23-10-30 21:11:42 ERROR (MainThread) [supervisor.services.modules.mqtt] There is already a MQTT service in use from core_mosquitto
23-10-30 21:11:42 INFO (MainThread) [supervisor.auth] Home Assistant not running, checking cache
23-10-30 21:11:52 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'mqtt-glow'
23-10-30 21:11:52 INFO (MainThread) [supervisor.auth] Home Assistant not running, checking cache
23-10-30 21:11:52 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'mqtt-node-red'
23-10-30 21:11:52 INFO (MainThread) [supervisor.auth] Home Assistant not running, checking cache
23-10-30 21:11:52 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from dc822f5c_sambanas
23-10-30 21:11:54 ERROR (MainThread) [supervisor.homeassistant.core] No API response in 5 minutes, assuming core has had a fatal startup error
23-10-30 21:11:54 CRITICAL (MainThread) [supervisor.homeassistant.core] HomeAssistant update failed -> rollback!
23-10-30 21:11:54 INFO (MainThread) [supervisor.homeassistant.core] A backup of the logfile is stored in /config/home-assistant-rollback.log
23-10-30 21:11:54 INFO (MainThread) [supervisor.homeassistant.core] Updating Home Assistant to version 2023.11.0b1
23-10-30 21:11:54 INFO (MainThread) [supervisor.docker.interface] Updating image ghcr.io/home-assistant/generic-x86-64-homeassistant:2023.11.0b2 to ghcr.io/home-assistant/generic-x86-64-homeassistant:2023.11.0b1
23-10-30 21:11:54 INFO (MainThread) [supervisor.docker.interface] Downloading docker image ghcr.io/home-assistant/generic-x86-64-homeassistant with tag 2023.11.0b1.
23-10-30 21:11:55 INFO (SyncWorker_5) [supervisor.docker.manager] Stopping homeassistant application
23-10-30 21:11:55 ERROR (MainThread) [supervisor.homeassistant.api] Error on call http://172.30.32.1:8123/api/core/state: [Errno 104] Connection reset by peer
23-10-30 21:11:55 ERROR (MainThread) [supervisor.homeassistant.api] Error on call http://172.30.32.1:8123/api/core/state: [Errno 104] Connection reset by peer
23-10-30 21:11:59 INFO (SyncWorker_5) [supervisor.docker.manager] Cleaning homeassistant application
23-10-30 21:12:00 INFO (MainThread) [supervisor.homeassistant.module] Update pulse/client.config: /data/tmp/homeassistant_pulse
23-10-30 21:12:00 INFO (MainThread) [supervisor.docker.homeassistant] Starting Home Assistant ghcr.io/home-assistant/generic-x86-64-homeassistant with version 2023.11.0b1
23-10-30 21:12:00 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
23-10-30 21:12:10 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
23-10-30 21:12:11 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
23-10-30 21:13:25 INFO (MainThread) [supervisor.host.info] Updating local host information
23-10-30 21:13:26 INFO (MainThread) [supervisor.host.services] Updating service information
23-10-30 21:13:26 INFO (MainThread) [supervisor.host.network] Updating local network information
23-10-30 21:13:27 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
23-10-30 21:13:27 INFO (MainThread) [supervisor.host.manager] Host information reload completed
23-10-30 21:17:03 ERROR (MainThread) [supervisor.homeassistant.core] No API response in 5 minutes, assuming core has had a fatal startup error

System Health information

System Information

version core-2023.11.0b1
installation_type Home Assistant OS
dev false
hassio true
docker true
user root
virtualenv false
python_version 3.11.6
os_name Linux
os_version 6.1.59
arch x86_64
timezone Europe/London
config_dir /config
Home Assistant Community Store GitHub API | ok -- | -- GitHub Content | ok GitHub Web | ok GitHub API Calls Remaining | 5000 Installed Version | 1.33.0 Stage | running Available Repositories | 1400 Downloaded Repositories | 86 HACS Data | ok
Home Assistant Cloud logged_in | true -- | -- subscription_expiration | 5 November 2023 at 00:00 relayer_connected | true relayer_region | eu-central-1 remote_enabled | true remote_connected | true alexa_enabled | true google_enabled | true remote_server | eu-central-1-8.ui.nabu.casa certificate_status | ready can_reach_cert_server | ok can_reach_cloud_auth | ok can_reach_cloud | ok
Home Assistant Supervisor host_os | Home Assistant OS 11.1.rc1 -- | -- update_channel | beta supervisor_version | supervisor-2023.10.1 agent_version | 1.6.0 docker_version | 24.0.6 disk_total | 116.7 GB disk_used | 74.0 GB healthy | true supported | true board | generic-x86-64 supervisor_api | ok version_api | ok installed_addons | Mosquitto broker (6.3.1), Ookla Speedtest CLI (1.0.1), Home Assistant Google Drive Backup (0.111.1), Advanced SSH & Web Terminal (15.1.0), MariaDB (2.6.1), Studio Code Server (5.13.0), InfluxDB2 (1.0.2), Caddy 2 (1.5.3), Samba share (10.0.2), Ring-MQTT with Video Streaming (5.6.3), Node-RED (14.6.3), Zigbee2MQTT (1.33.1-1), fluent-bit (1.2.4), Let's Encrypt (4.12.9), JupyterLab (0.12.1), AppDaemon (0.13.6), MQTT Explorer (browser-1.0.1), Network UPS Tools (0.12.2), Tailscale (0.13.1), File editor (5.6.0), diyHue (2.0.10), ESPHome (2023.10.3), RPC Shutdown (2.4), Glances (0.19.4), File editor (5.4.3), scanservjs (v2.26.1-5), Samba NAS (10.0.2-nas4), InfluxDB2 (2.7.0-alpha.3), Zoneminder (1.36.33), Grocy (0.20.1), Music Assistant BETA (2.0.0b74), Scrypted (18-jammy-full.s6-v0.57.0), Zigbee2MQTT Edge (edge)
Dashboards dashboards | 8 -- | -- resources | 40 views | 20 mode | storage
NextDNS can_reach_server | ok -- | --
Recorder oldest_recorder_run | 24 October 2023 at 19:02 -- | -- current_recorder_run | 30 October 2023 at 21:12 estimated_db_size | 957.71 MiB database_engine | sqlite database_version | 3.41.2
Spotify api_endpoint_reachable | ok -- | --

Supervisor diagnostics

config_entry-hassio-6c7ea8e38b0da3aa619bf181cf6d462b.json.txt

Additional information

HA Core logs

 ``` s6-rc: info: service s6rc-oneshot-runner: starting s6-rc: info: service s6rc-oneshot-runner successfully started s6-rc: info: service fix-attrs: starting s6-rc: info: service fix-attrs successfully started s6-rc: info: service legacy-cont-init: starting s6-rc: info: service legacy-cont-init successfully started s6-rc: info: service legacy-services: starting services-up: info: copying legacy longrun home-assistant (no readiness notification) s6-rc: info: service legacy-services successfully started 2023-10-30 21:06:52.817 WARNING (SyncWorker_0) [homeassistant.util.yaml.loader] YAML file /config/themes/m3-xx-yyyy/m3-c11-purple.yaml contains duplicate key "theme-sys-ha-disabled-text-color". Check lines 535 and 537 2023-10-30 21:06:52.817 WARNING (SyncWorker_0) [homeassistant.util.yaml.loader] YAML file /config/themes/m3-xx-yyyy/m3-c11-purple.yaml contains duplicate key "theme-sys-ha-card-background-color-active". Check lines 549 and 550 ... 2023-10-30 21:11:44.363 ERROR (MainThread) [frontend.js.latest.202310270] Uncaught error from Safari 17.0 on Mac OS 10.15.7 TypeError: null is not an object (evaluating 'elHaMain.shadowRoot') /hacsfiles/lovelace-wallpanel/wallpanel.js:2190:10 ... [21:11:55] INFO: Home Assistant Core finish process exit code 256 [21:11:55] INFO: Home Assistant Core finish process received signal 15 s6-rc: info: service legacy-services successfully stopped s6-rc: info: service legacy-cont-init: stopping s6-rc: info: service legacy-cont-init successfully stopped s6-rc: info: service fix-attrs: stopping s6-rc: info: service fix-attrs successfully stopped s6-rc: info: service s6rc-oneshot-runner: stopping s6-rc: info: service s6rc-oneshot-runner successfully stopped ```
bdraco commented 11 months ago

@caraar12345 could you login to port 22222 and run

journalctl -f

Than restart core so we can get a combined log with core and supervisor to better see what’s going on?

bdraco commented 11 months ago

https://developers.home-assistant.io/docs/operating-system/debugging/

If you need to open port 22222

caraar12345 commented 11 months ago

Hi sorry! Tried to get a log for this... but then it would not reproduce (no rollback took place even though I got the 'no API response') 😂

Glad to see it's been resolved though; thanks ❤️

agners commented 11 months ago

If you are seeing the "No API response in 5 minutes, assuming core has had a fatal startup error", this problem has been addressed and a fix will be released soon. I'll update this thread once a new Supervisor is available which has this addressed.

agners commented 11 months ago

Supervisor 2023.11.0 addresses the problem. The release is currently on the beta channel (see this guide on how-to join the beta channel, this can be done temporarily only to update this Supervisor version and then switch back).

sentry-io[bot] commented 11 months ago

Sentry issue: SUPERVISOR-83S

Mark-64 commented 11 months ago

This issue is not fixed for me.

On RPi 3B:

Trying to update to 2023.11.2, it fails and rolls back.

Supervisor Log:

  File "/usr/src/supervisor/supervisor/homeassistant/websocket.py", line 93, in async_send_command
    return await self._futures[message["id"]]
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
supervisor.exceptions.HomeAssistantWSConnectionError: Connection was closed

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/supervisor/supervisor/homeassistant/websocket.py", line 323, in async_supervisor_event
    await self.async_send_message(
  File "/usr/src/supervisor/supervisor/homeassistant/websocket.py", line 265, in async_send_message
    await self._client.close()
          ^^^^^^^^^^^^^^^^^^
AttributeError: 'NoneType' object has no attribute 'close'
23-11-18 05:30:59 ERROR (MainThread) [asyncio] Task exception was never retrieved
future: <Task finished name='Task-129043' coro=<HomeAssistantWebSocket.async_supervisor_event() done, defined at /usr/src/supervisor/supervisor/homeassistant/websocket.py:318> exception=AttributeError("'NoneType' object has no attribute 'close'")>
Traceback (most recent call last):
  File "/usr/src/supervisor/supervisor/homeassistant/websocket.py", line 263, in async_send_message
    await self._client.async_send_command(message)
  File "/usr/src/supervisor/supervisor/homeassistant/websocket.py", line 93, in async_send_command
    return await self._futures[message["id"]]
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
supervisor.exceptions.HomeAssistantWSConnectionError: Connection was closed

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/supervisor/supervisor/homeassistant/websocket.py", line 323, in async_supervisor_event
    await self.async_send_message(
  File "/usr/src/supervisor/supervisor/homeassistant/websocket.py", line 265, in async_send_message
    await self._client.close()
          ^^^^^^^^^^^^^^^^^^
AttributeError: 'NoneType' object has no attribute 'close'
23-11-18 05:31:02 INFO (SyncWorker_4) [supervisor.docker.manager] Cleaning homeassistant application
23-11-18 05:31:03 INFO (MainThread) [supervisor.homeassistant.module] Update pulse/client.config: /data/tmp/homeassistant_pulse
23-11-18 05:31:07 INFO (MainThread) [supervisor.docker.homeassistant] Starting Home Assistant ghcr.io/home-assistant/raspberrypi3-64-homeassistant with version 2023.11.2
23-11-18 05:31:07 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
23-11-18 05:31:47 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to NOT_RUNNING
23-11-18 05:31:49 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
23-11-18 05:31:55 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
23-11-18 05:32:07 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/brenner-tobias/ha-addons repository
23-11-18 05:32:08 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/TazzerMAN/increase_swap_addon repository
23-11-18 05:32:08 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/zigbee2mqtt/hassio-zigbee2mqtt repository
23-11-18 05:32:09 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/home-assistant-addon repository
23-11-18 05:32:09 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/sabeechen/hassio-google-drive-backup repository
23-11-18 05:32:10 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
23-11-18 05:32:10 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
23-11-18 05:32:14 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
23-11-18 05:32:32 INFO (MainThread) [supervisor.store] Loading add-ons from store: 77 all - 0 new - 0 remove
23-11-18 05:32:32 INFO (MainThread) [supervisor.store] Loading add-ons from store: 77 all - 0 new - 0 remove
23-11-18 05:37:10 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'mqtt_user'
23-11-18 05:37:13 INFO (MainThread) [supervisor.auth] Home Assistant not running, checking cache
23-11-18 05:40:22 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
23-11-18 05:46:50 ERROR (MainThread) [supervisor.homeassistant.core] No Home Assistant Core response, assuming a fatal startup error
23-11-18 05:46:50 CRITICAL (MainThread) [supervisor.homeassistant.core] HomeAssistant update failed -> rollback!
23-11-18 05:46:50 INFO (MainThread) [supervisor.resolution.module] Create new issue update_rollback - core / None
23-11-18 05:46:50 INFO (MainThread) [supervisor.homeassistant.core] A backup of the logfile is stored in /config/home-assistant-rollback.log
23-11-18 05:46:50 INFO (MainThread) [supervisor.homeassistant.core] Updating Home Assistant to version 2023.10.5
23-11-18 05:46:50 INFO (MainThread) [supervisor.docker.interface] Updating image ghcr.io/home-assistant/raspberrypi3-64-homeassistant:2023.11.2 to ghcr.io/home-assistant/raspberrypi3-64-homeassistant:2023.10.5
23-11-18 05:46:50 INFO (MainThread) [supervisor.docker.interface] Downloading docker image ghcr.io/home-assistant/raspberrypi3-64-homeassistant with tag 2023.10.5.
23-11-18 05:47:01 INFO (SyncWorker_5) [supervisor.docker.manager] Stopping homeassistant application
23-11-18 05:47:23 INFO (SyncWorker_5) [supervisor.docker.manager] Cleaning homeassistant application
23-11-18 05:47:25 INFO (MainThread) [supervisor.homeassistant.module] Update pulse/client.config: /data/tmp/homeassistant_pulse
23-11-18 05:47:32 INFO (MainThread) [supervisor.docker.homeassistant] Starting Home Assistant ghcr.io/home-assistant/raspberrypi3-64-homeassistant with version 2023.10.5
23-11-18 05:47:32 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
23-11-18 05:48:27 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to NOT_RUNNING
23-11-18 05:48:31 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
23-11-18 05:48:37 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
23-11-18 05:48:50 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/brenner-tobias/ha-addons repository
23-11-18 05:48:50 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/TazzerMAN/increase_swap_addon repository
23-11-18 05:48:50 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/zigbee2mqtt/hassio-zigbee2mqtt repository
23-11-18 05:48:51 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/home-assistant-addon repository
23-11-18 05:48:51 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/sabeechen/hassio-google-drive-backup repository
23-11-18 05:48:51 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
23-11-18 05:48:51 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
23-11-18 05:48:53 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
23-11-18 05:49:18 INFO (MainThread) [supervisor.store] Loading add-ons from store: 77 all - 0 new - 0 remove
23-11-18 05:49:18 INFO (MainThread) [supervisor.store] Loading add-ons from store: 77 all - 0 new - 0 remove
23-11-18 06:01:21 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state running
23-11-18 06:01:21 INFO (MainThread) [supervisor.resolution.checks.base] Run check for docker_config/system
23-11-18 06:01:21 INFO (MainThread) [supervisor.resolution.checks.base] Run check for free_space/system
23-11-18 06:01:21 INFO (MainThread) [supervisor.resolution.checks.base] Run check for ipv4_connection_problem/system
23-11-18 06:01:21 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_failed/dns_server
23-11-18 06:01:22 INFO (MainThread) [supervisor.resolution.checks.base] Run check for trust/supervisor
23-11-18 06:01:24 INFO (MainThread) [supervisor.resolution.checks.base] Run check for pwned/addon
23-11-18 06:01:24 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system
23-11-18 06:01:24 INFO (MainThread) [supervisor.resolution.checks.base] Run check for security/core
23-11-18 06:01:24 INFO (MainThread) [supervisor.resolution.checks.base] Run check for no_current_backup/system
23-11-18 06:01:24 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_ipv6_error/dns_server
23-11-18 06:01:24 INFO (MainThread) [supervisor.resolution.check] System checks complete
23-11-18 06:01:24 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
23-11-18 06:01:37 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
23-11-18 06:01:37 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running
23-11-18 06:01:37 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete
23-11-18 06:02:41 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'mqtt_user'
23-11-18 06:02:41 INFO (MainThread) [supervisor.auth] Home Assistant not running, checking cache
23-11-18 06:03:00 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup
23-11-18 06:03:00 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup
23-11-18 06:03:28 ERROR (MainThread) [supervisor.homeassistant.core] No Home Assistant Core response, assuming a fatal startup error
23-11-18 06:10:24 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
agners commented 11 months ago

It seems that Home Assistant Core doesn't change it's state to running within 15 minutes of runtime, which is really not expected. The overall start time of Home Assistant Core should not exceed 15 minutes.

Can you open a new issue and also attach the Core logs from the update? There is likely something wrong on the Core side. Are you using custom components?

23-11-18 05:31:47 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to NOT_RUNNING
...
23-11-18 05:46:50 ERROR (MainThread) [supervisor.homeassistant.core] No Home Assistant Core response, assuming a fatal startup error