Home Assistant is starting, not everything will be available until it is finished

Jamie_Pryer · July 3, 2020, 6:43pm

Hey all,
multiple reboots and home assistant never starts up, things like HACS etc dont seem load and i cant work out how to get things running again?

Getting some weird errors ive not seen below.

HassOS 4.10

Supervisor: Version 228

RPI4, 4GB

Home Assistant 0.112.1

System Health

arch	armv7l
chassis	embedded
dev	false
docker	true
docker_version	19.03.8
hassio	true
host_os	HassOS 4.10
installation_type	Home Assistant OS
os_name	Linux
os_version	4.19.126-v7l
python_version	3.7.7
supervisor	228
timezone	Europe/London
version	0.112.1
virtualenv	false

20-07-03 08:35:45 INFO (SyncWorker_9) [supervisor.docker.interface] Attach to homeassistant/armv7-hassio-cli with version 25
20-07-03 08:35:45 INFO (MainThread) [supervisor.plugins.cli] Start cli plugin
20-07-03 08:35:47 INFO (SyncWorker_0) [supervisor.docker.cli] Start CLI homeassistant/armv7-hassio-cli with version 25 - 172.30.32.5
20-07-03 08:35:47 INFO (SyncWorker_12) [supervisor.docker.interface] Attach to homeassistant/armv7-hassio-multicast with version 2
20-07-03 08:35:47 INFO (MainThread) [supervisor.plugins.multicast] Start Multicast plugin
20-07-03 08:35:48 INFO (SyncWorker_5) [supervisor.docker.multicast] Start Multicast homeassistant/armv7-hassio-multicast with version 2 - Host
20-07-03 08:35:48 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-07-03 08:35:49 INFO (SyncWorker_15) [supervisor.docker.interface] Attach to homeassistant/raspberrypi4-homeassistant with version 0.112.0
20-07-03 08:35:49 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /
20-07-03 08:35:49 INFO (MainThread) [supervisor.hassos] Detect HassOS 4.10 / BootSlot B
20-07-03 08:35:50 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/git/a0d7b954 repository
20-07-03 08:35:50 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/git/8c1e12d6 repository
20-07-03 08:35:50 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/core repository
20-07-03 08:35:50 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/git/ae6e943c repository
20-07-03 08:35:50 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/git/7ad98f9c repository
20-07-03 08:35:51 INFO (MainThread) [supervisor.store] Load add-ons from store: 71 all - 71 new - 0 remove
20-07-03 08:35:51 INFO (MainThread) [supervisor.addons] Found 7 installed add-ons
20-07-03 08:35:51 INFO (SyncWorker_2) [supervisor.docker.interface] Attach to homeassistant/armv7-addon-check_config with version 3.3.0
20-07-03 08:35:51 INFO (SyncWorker_14) [supervisor.docker.interface] Attach to homeassistant/armv7-addon-samba with version 9.2.0
20-07-03 08:35:51 INFO (SyncWorker_1) [supervisor.docker.interface] Attach to homeassistant/armv7-addon-configurator with version 5.0.0
20-07-03 08:35:51 INFO (SyncWorker_13) [supervisor.docker.interface] Attach to hassioaddons/ide-armv7 with version 2.2.0
20-07-03 08:35:51 INFO (SyncWorker_12) [supervisor.docker.interface] Attach to homeassistant/armhf-addon-deconz with version 5.3.6
20-07-03 08:35:51 INFO (SyncWorker_8) [supervisor.docker.interface] Attach to hassioaddons/glances-armv7 with version 0.8.0
20-07-03 08:35:51 INFO (SyncWorker_5) [supervisor.docker.interface] Attach to homeassistant/armv7-addon-ssh with version 8.6.0
20-07-03 08:35:51 INFO (MainThread) [supervisor.snapshots] Found 20 snapshot files
20-07-03 08:35:51 INFO (MainThread) [supervisor.discovery] Load 1 messages
20-07-03 08:35:51 INFO (MainThread) [supervisor.ingress] Load 3 ingress session
20-07-03 08:35:51 INFO (MainThread) [supervisor.misc.secrets] Load Home Assistant secrets: 116
20-07-03 08:35:51 INFO (MainThread) [__main__] Run Supervisor
20-07-03 08:35:51 INFO (MainThread) [supervisor.api] Start API on 172.30.32.2
20-07-03 08:35:51 INFO (MainThread) [supervisor.utils.gdbus] Call de.pengutronix.rauc.Installer.Mark on /
20-07-03 08:35:51 INFO (MainThread) [supervisor.hassos] Rauc: B - marked slot kernel.1 as good
20-07-03 08:35:51 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.INITIALIZE' start 0 add-ons
20-07-03 08:35:51 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SYSTEM' start 0 add-ons
20-07-03 08:35:51 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SERVICES' start 5 add-ons
20-07-03 08:35:53 INFO (SyncWorker_5) [supervisor.docker.addon] Start Docker add-on homeassistant/armv7-addon-samba with version 9.2.0
20-07-03 08:35:55 INFO (SyncWorker_3) [supervisor.docker.addon] Start Docker add-on homeassistant/armv7-addon-ssh with version 8.6.0
20-07-03 08:36:01 INFO (SyncWorker_7) [supervisor.docker.addon] Start Docker add-on hassioaddons/ide-armv7 with version 2.2.0
20-07-03 08:36:17 INFO (SyncWorker_1) [supervisor.docker.addon] Start Docker add-on homeassistant/armhf-addon-deconz with version 5.3.6
20-07-03 08:36:17 WARNING (SyncWorker_14) [supervisor.docker.addon] Glances run with disabled protected mode!
20-07-03 08:36:18 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_ide
20-07-03 08:36:19 INFO (SyncWorker_14) [supervisor.docker.addon] Start Docker add-on hassioaddons/glances-armv7 with version 0.8.0
20-07-03 08:36:22 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_glances
20-07-03 08:36:24 INFO (MainThread) [supervisor.api.security] /dns/info access from a0d7b954_glances
20-07-03 08:36:24 INFO (SyncWorker_11) [supervisor.docker.interface] Start homeassistant/raspberrypi4-homeassistant
20-07-03 08:36:27 INFO (MainThread) [supervisor.api.security] /dns/info access from a0d7b954_ide
20-07-03 08:36:50 INFO (MainThread) [supervisor.homeassistant] Updated Home Assistant API token
20-07-03 08:36:55 INFO (MainThread) [supervisor.homeassistant] Home Assistant pip installation in progress
20-07-03 08:37:15 INFO (MainThread) [supervisor.homeassistant] Home Assistant pip installation done
20-07-03 08:37:51 ERROR (MainThread) [supervisor.homeassistant] Error on call http://172.30.32.1:8123/api/config: 
20-07-03 08:44:58 ERROR (MainThread) [supervisor.homeassistant] Error on call http://172.30.32.1:8123/api/config: 
20-07-03 08:45:34 ERROR (MainThread) [supervisor.homeassistant] Error on call http://172.30.32.1:8123/api/config: 
20-07-03 08:47:16 WARNING (MainThread) [supervisor.homeassistant] Don't wait anymore of Home Assistant startup!
20-07-03 08:47:16 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.APPLICATION' start 1 add-ons
20-07-03 08:47:18 INFO (SyncWorker_8) [supervisor.docker.addon] Start Docker add-on homeassistant/armv7-addon-configurator with version 5.0.0
20-07-03 08:47:23 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled
20-07-03 08:47:23 INFO (MainThread) [supervisor.misc.hwmon] Started Supervisor hardware monitor
20-07-03 08:47:23 INFO (MainThread) [supervisor.core] Supervisor is up and running
20-07-03 08:47:23 INFO (MainThread) [supervisor.host.info] Update local host information
20-07-03 08:47:23 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-07-03 08:47:23 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-07-03 08:47:24 INFO (MainThread) [supervisor.host.services] Update service information
20-07-03 08:47:24 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-07-03 08:47:24 INFO (MainThread) [supervisor.host.network] Update local network DNS information
20-07-03 08:47:24 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager
20-07-03 08:47:24 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information
20-07-03 10:47:23 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-07-03 10:54:03 INFO (MainThread) [supervisor.host.info] Update local host information
20-07-03 10:54:03 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-07-03 10:54:04 INFO (MainThread) [supervisor.host.services] Update service information
20-07-03 10:54:04 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-07-03 10:54:04 INFO (MainThread) [supervisor.host.network] Update local network DNS information
20-07-03 10:54:04 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager
20-07-03 10:54:04 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information
20-07-03 11:47:23 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/hassio-addons-development repository
20-07-03 11:47:23 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/kevinvincent/hassio-addons repository
20-07-03 11:47:23 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/hassio-addons repository
20-07-03 11:47:23 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/danielwelch/hassio-zigbee2mqtt repository
20-07-03 11:47:23 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
20-07-03 11:47:25 INFO (MainThread) [supervisor.store] Load add-ons from store: 71 all - 0 new - 0 remove
20-07-03 11:52:15 INFO (MainThread) [supervisor.homeassistant] Update Home Assistant to version 0.112.1
20-07-03 11:52:15 INFO (SyncWorker_11) [supervisor.docker.interface] Update image homeassistant/raspberrypi4-homeassistant:0.112.0 to homeassistant/raspberrypi4-homeassistant:0.112.1
20-07-03 11:52:15 INFO (SyncWorker_11) [supervisor.docker.interface] Pull image homeassistant/raspberrypi4-homeassistant tag 0.112.1.
20-07-03 12:02:45 INFO (SyncWorker_11) [supervisor.docker.interface] Stop homeassistant application
20-07-03 12:03:21 INFO (SyncWorker_11) [supervisor.docker.interface] Clean homeassistant application
20-07-03 12:03:22 INFO (MainThread) [supervisor.homeassistant] Update pulse/client.config: /data/tmp/homeassistant_pulse
20-07-03 12:03:24 INFO (SyncWorker_10) [supervisor.docker.homeassistant] Start homeassistant homeassistant/raspberrypi4-homeassistant with version 0.112.1
20-07-03 12:03:44 INFO (MainThread) [supervisor.homeassistant] Updated Home Assistant API token
20-07-03 12:03:44 INFO (MainThread) [supervisor.homeassistant] Home Assistant pip installation in progress
20-07-03 12:04:13 INFO (MainThread) [supervisor.homeassistant] Home Assistant pip installation done
20-07-03 12:11:47 ERROR (MainThread) [supervisor.homeassistant] Error on call http://172.30.32.1:8123/api/config: 
20-07-03 12:14:14 WARNING (MainThread) [supervisor.homeassistant] Don't wait anymore of Home Assistant startup!
20-07-03 12:14:14 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=HomeAssistantUpdateError()>
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 362, in update
    raise HomeAssistantUpdateError()
supervisor.exceptions.HomeAssistantUpdateError

## Log Details (ERROR)

Logger: homeassistant.core
Source: core.py:157
First occurred: 14:44:35 (5 occurrences)
Last logged: 15:04:13

Error doing job: Unclosed client session

Last part of the full log


Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/update_coordinator.py", line 136, in async_refresh
    self.data = await self._async_update_data()
  File "/usr/src/homeassistant/homeassistant/helpers/update_coordinator.py", line 124, in _async_update_data
    return await self.update_method()
  File "/usr/src/homeassistant/homeassistant/components/withings/common.py", line 747, in async_get_all_data
    None,
  File "/usr/src/homeassistant/homeassistant/components/withings/common.py", line 745, in <genexpr>
    if flow.context == context
AttributeError: 'dict' object has no attribute 'context'
2020-07-03 15:04:30 ERROR (MainThread) [homeassistant.config_entries] Error setting up entry My home for smartthings
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/config_entries.py", line 220, in async_setup
    hass, self
  File "/usr/src/homeassistant/homeassistant/components/smartthings/__init__.py", line 123, in async_setup_entry
    entry.data[CONF_REFRESH_TOKEN],
  File "/usr/local/lib/python3.7/site-packages/pysmartthings/smartthings.py", line 210, in generate_tokens
    client_id, client_secret, refresh_token
  File "/usr/local/lib/python3.7/site-packages/pysmartthings/api.py", line 424, in generate_tokens
    raise APIInvalidGrant(data.get("error_description"))
pysmartthings.errors.APIInvalidGrant: Invalid refresh token: ab1c154d-0823-42f6-a711-9ef643c02207
2020-07-03 15:04:33 WARNING (MainThread) [homeassistant.components.konnected.panel] Exception trying to connect to panel: 
2020-07-03 17:08:41 ERROR (MainThread) [snitun.multiplexer.core] Ping fails, no response from peer
2020-07-03 19:14:27 ERROR (MainThread) [snitun.multiplexer.core] Ping fails, no response from peer

lgcness · July 3, 2020, 10:42pm

I have same issues since updating to 0.112 and still broken on 0.112.1. HACS, Tesla, Geolocation not starting.

Thanks

Jamie_Pryer · July 4, 2020, 8:32am

ive rolled back - for some reason, a load of my core folders got deleted (like HACS) somehow?!

im now going to go and see what changes i need to make, before doing the upgrade again.

lgcness · July 4, 2020, 9:53pm

I still have the same issues even upgrading to version 0.112.2 and to the new HassOS 4.11.

srk23 · July 5, 2020, 8:24am

I am having the same issues - never seems to start fully, nothing obvious in the logs. Certain components (Blink, Tesla, HACS) and some sensors just never load.

Strangely, after a few restarts in 0.112.1 eventually everything loaded, but on upgrading to 0.112.2 it all broke again.

I can’t see an obvious cause…

srk23 · July 5, 2020, 9:55am

Have now rolled back to 0.111.4 and everything is working.

Will wait to see if others find a solution before trying again.

petro · July 5, 2020, 1:43pm

All your errors are in specific components. Withings and smarthings. Both are python errors. You should write up the issues attaching the errors.

petro · July 5, 2020, 1:44pm

You’ll have to post your logs. They are most likely completely unrelated to OPs errors.

srk23 · July 5, 2020, 3:17pm

Thanks. Unfortunately as it was so screwed I rolled back without keeping the logs. But there were no obvious errors in either the HA or supervisor logs to explain why the frontend wouldn’t load.

I will try again with the next update and post logs if the problem persists

petro · July 5, 2020, 4:42pm

You should always check the log file, not what’s in the UI.

srk23 · July 8, 2020, 10:00am

So…after rolling back to 0.111.4 and tidying up my config a bit to suppress a few errors in the log, I waited for 0.112.3 to be released.

I ran the upgrade again, and hit exactly the same problem. Left stuck with the message “home assistant is starting…” and a bunch of components never start (Tesla, some sensors, blink etc). Still no errors in the logs, HA:

2020-07-08 10:32:02 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for shelly which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.
2020-07-08 10:32:02 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for alexa_media which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.
2020-07-08 10:32:02 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.
2020-07-08 10:32:02 WARNING (Recorder) [homeassistant.components.recorder.migration] Database is about to upgrade. Schema version: 7
2020-07-08 10:32:02 WARNING (Recorder) [homeassistant.components.recorder.migration] Adding columns context_parent_id to table events. Note: this can take several minutes on large databases and slow computers. Please be patient!
2020-07-08 10:32:02 WARNING (Recorder) [homeassistant.components.recorder.migration] Adding columns old_state_id to table states. Note: this can take several minutes on large databases and slow computers. Please be patient!
2020-07-08 10:32:02 WARNING (Recorder) [homeassistant.components.recorder.migration] Adding index `ix_events_context_parent_id` to database. Note: this can take several minutes on large databases and slow computers. Please be patient!
2020-07-08 10:32:05 WARNING (Recorder) [homeassistant.components.recorder.migration] Adding index `ix_events_event_type_time_fired` to database. Note: this can take several minutes on large databases and slow computers. Please be patient!
2020-07-08 10:32:10 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for html5 which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.
2020-07-08 10:32:10 INFO (Thread-2) [homeassistant.components.mqtt] Connected to MQTT server core-mosquitto.local.hass.io:1883 (0)
2020-07-08 10:32:10 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/#
2020-07-08 10:32:10 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to tele/rf_bridge/RESULT
2020-07-08 10:32:10 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for reolink_dev which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.

I waited a lot longer this time, and eventually HA restarted itself and rolled back automatically to 0.111.4. Supervisor log didn’t really give ny clues as to why:

20-07-08 09:21:39 INFO (MainThread) [supervisor.store] Load add-ons from store: 86 all - 0 new - 0 remove
20-07-08 09:30:23 INFO (MainThread) [supervisor.homeassistant] Update Home Assistant to version 0.112.3
20-07-08 09:30:23 INFO (SyncWorker_10) [supervisor.docker.interface] Update image homeassistant/qemux86-64-homeassistant:0.111.4 to homeassistant/qemux86-64-homeassistant:0.112.3
20-07-08 09:30:23 INFO (SyncWorker_10) [supervisor.docker.interface] Pull image homeassistant/qemux86-64-homeassistant tag 0.112.3.
20-07-08 09:31:50 INFO (SyncWorker_10) [supervisor.docker.interface] Stop homeassistant application
20-07-08 09:31:52 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Received message 8:1000 is not str
20-07-08 09:31:52 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
20-07-08 09:32:00 INFO (SyncWorker_10) [supervisor.docker.interface] Clean homeassistant application
20-07-08 09:32:00 INFO (MainThread) [supervisor.homeassistant] Update pulse/client.config: /data/tmp/homeassistant_pulse
20-07-08 09:32:00 INFO (SyncWorker_0) [supervisor.docker.homeassistant] Start homeassistant homeassistant/qemux86-64-homeassistant with version 0.112.3
20-07-08 09:32:05 INFO (MainThread) [supervisor.homeassistant] Home Assistant record migration in progress
20-07-08 09:32:10 INFO (MainThread) [supervisor.homeassistant] Home Assistant record migration done
20-07-08 09:32:10 INFO (MainThread) [supervisor.homeassistant] Home Assistant pip installation in progress
20-07-08 09:33:00 INFO (MainThread) [supervisor.homeassistant] Home Assistant pip installation done
20-07-08 09:43:01 WARNING (MainThread) [supervisor.homeassistant] Don't wait anymore of Home Assistant startup!
20-07-08 09:43:01 CRITICAL (MainThread) [supervisor.homeassistant] HomeAssistant update fails -> rollback!
20-07-08 09:43:01 INFO (MainThread) [supervisor.homeassistant] Update Home Assistant to version 0.111.4
20-07-08 09:43:01 INFO (SyncWorker_2) [supervisor.docker.interface] Update image homeassistant/qemux86-64-homeassistant:0.112.3 to homeassistant/qemux86-64-homeassistant:0.111.4
20-07-08 09:43:01 INFO (SyncWorker_2) [supervisor.docker.interface] Pull image homeassistant/qemux86-64-homeassistant tag 0.111.4.
20-07-08 09:43:17 INFO (SyncWorker_2) [supervisor.docker.interface] Stop homeassistant application
20-07-08 09:43:21 INFO (SyncWorker_2) [supervisor.docker.interface] Clean homeassistant application
20-07-08 09:43:21 INFO (MainThread) [supervisor.homeassistant] Update pulse/client.config: /data/tmp/homeassistant_pulse
20-07-08 09:43:21 INFO (SyncWorker_1) [supervisor.docker.homeassistant] Start homeassistant homeassistant/qemux86-64-homeassistant with version 0.111.4
20-07-08 09:43:26 INFO (MainThread) [supervisor.homeassistant] Home Assistant pip installation in progress
20-07-08 09:44:16 INFO (MainThread) [supervisor.homeassistant] Home Assistant pip installation done
20-07-08 09:44:26 INFO (MainThread) [supervisor.homeassistant] Detect a running Home Assistant instance
20-07-08 09:44:26 INFO (MainThread) [supervisor.homeassistant] Successful run Home Assistant 0.111.4
20-07-08 09:44:26 INFO (SyncWorker_12) [supervisor.docker.interface] Cleanup images: ['homeassistant/qemux86-64-homeassistant:0.112.3']

Strange thing is, I waited a while and ran the update again. Second time, it took straight away with no errors. Everything seems to work, including logbook/history.

Odd.

Hopefully future updates will now be smoother…

favstech · July 9, 2020, 2:58pm

setup: VM on VirtualBox, core 4.11, version 112.4
fresh install, install in order, Samba, Terminal & SSH, Mosquitto, File editor, Node-RED and HACS
after put the HACS folder and make the host first restart, every still ok, when I add the HACS in the integrations, wait finish background jobs and restart again, bummmm, the message starting never finish.

point 1: if i go in configuration, server controls and reboot from there, reboot HA, not the host… works

point 2: if a shutdown VM (hassio / System / Shutdown Host), and go to Virtual Box and start again… works

conclusion: i think, in my case, only with HACS, the problem is in reboot… maybe shutdown has some improvments that reboot has not… anyway, the version 112 has an issue but i think with this above, maybe you guys can use 112.

allan · July 10, 2020, 6:23pm

I seem to be having the same issue, I had already been running 0.112.0 and didn’t remember having the issue throughout beta or at release so I thought I would try reverting to 0.112.0 using

ha core upgrade --version 0.112.0

With 0.112.0 installs complete startup as expected upgrade past that version with no config changes and I have the “Home Assistant is starting, not everything will be available until it is finished.” message forever. This is the same on two installs, my “dev/testing” (x86_64 vm) install and my “production” ( install Pi3)

rsaturns · July 14, 2020, 4:16am

Can reproduce the exact same issue.

menekis · July 16, 2020, 2:33am

same here, can’t go back since it blocks the snapshot restore…

bdraco · July 16, 2020, 3:12am

0.112.4 turns up the logging level so you can see which integration is the culprit that is blocking startup.

Tovrin · July 17, 2020, 8:15am

I’m having this same issue. As such none of my automations are working anymore.

The automations themselves work as I can execute them manually and they do the right things, but they just don;t trigger automatically.

This is an absolute mess? I’ve spent an entire day trying to get it working. I’d like to start all over again, but I don’t want to lost my entire setup. It took me aages to get this all right and overnight it’s all stuffed!

I’ve got no idea how to roll back, itherwise I would.

bdraco · July 17, 2020, 8:56am

Automations don’t trigger until startup as been completed.

Is there anything helpful in the logs?

Try removing any custom components if you have anything that is not from core.

Tovrin · July 17, 2020, 9:30am

I managed to restore from an older snapshot and it seems to be running again. I then updated to 112.4 and I seem to be ok again.

I’ve got Log Viewer installed and the amount of stuff it’s spewing out is just scary! It seems to be generating a massive number of errors. By way of example …

[SQL: INSERT INTO events (event_type, event_data, origin, time_fired, created, context_id, context_user_id, context_parent_id) VALUES (?, ?, ?, ?, ?, ?, ?, ?)]
[parameters: ('state_changed', '{}', 'LOCAL', '2020-07-17 09:26:39.108253', '2020-07-17 09:26:39.109928', 'c086e49f47184469aafdf6f570143bbb', None, None)]
(Background on this error at: http://sqlalche.me/e/4xp6) (Background on this error at: http://sqlalche.me/e/7s2a)
2020-07-17 19:26:40 ERROR (Recorder) [homeassistant.components.recorder] Error executing query: This Session's transaction has been rolled back due to a previous exception during flush. To begin a new transaction with this Session, first issue Session.rollback(). Original exception was: (sqlite3.DatabaseError) database disk image is malformed
[SQL: INSERT INTO events (event_type, event_data, origin, time_fired, created, context_id, context_user_id, context_parent_id) VALUES (?, ?, ?, ?, ?, ?, ?, ?)]
[parameters: ('state_changed', '{}', 'LOCAL', '2020-07-17 09:26:39.108253', '2020-07-17 09:26:39.109928', 'c086e49f47184469aafdf6f570143bbb', None, None)]
(Background on this error at: http://sqlalche.me/e/4xp6) (Background on this error at: http://sqlalche.me/e/7s2a)
2020-07-17 19:26:40 ERROR (Recorder) [homeassistant.components.recorder] Error saving events: This Session's transaction has been rolled back due to a previous exception during flush. To begin a new transaction with this Session, first issue Session.rollback(). Original exception was: (sqlite3.DatabaseError) database disk image is malformed
[SQL: INSERT INTO events (event_type, event_data, origin, time_fired, created, context_id, context_user_id, context_parent_id) VALUES (?, ?, ?, ?, ?, ?, ?, ?)]
[parameters: ('state_changed', '{}', 'LOCAL', '2020-07-17 09:26:39.108253', '2020-07-17 09:26:39.109928', 'c086e49f47184469aafdf6f570143bbb', None, None)]
(Background on this error at: http://sqlalche.me/e/4xp6) (Background on this error at: http://sqlalche.me/e/7s2a)
Traceback (most recent call last):
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 435, in _commit_event_session_or_retry
self._commit_event_session()
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 486, in _commit_event_session
self.event_session.commit()
File "/usr/local/lib/python3.7/site-packages/sqlalchemy/orm/session.py", line 1042, in commit
self.transaction.commit()
File "/usr/local/lib/python3.7/site-packages/sqlalchemy/orm/session.py", line 502, in commit
self._assert_active(prepared_ok=True)
File "/usr/local/lib/python3.7/site-packages/sqlalchemy/orm/session.py", line 296, in _assert_active
code="7s2a",
sqlalchemy.exc.InvalidRequestError: This Session's transaction has been rolled back due to a previous exception during flush. To begin a new transaction with this Session, first issue Session.rollback(). Original exception was: (sqlite3.DatabaseError) database disk image is malformed
[SQL: INSERT INTO events (event_type, event_data, origin, time_fired, created, context_id, context_user_id, context_parent_id) VALUES (?, ?, ?, ?, ?, ?, ?, ?)]
[parameters: ('state_changed', '{}', 'LOCAL', '2020-07-17 09:26:39.108253', '2020-07-17 09:26:39.109928', 'c086e49f47184469aafdf6f570143bbb', None, None)]
(Background on this error at: http://sqlalche.me/e/4xp6) (Background on this error at: http://sqlalche.me/e/7s2a)
2020-07-17 19:26:46 ERROR (Recorder) [homeassistant.components.recorder] Error adding event: (sqlite3.DatabaseError) database disk image is malformed

And there’s LOTS more. It just keeps spewing messages. I’m pretty sure that’s not normal.

Does anyone have any advice on how I can save my configuration, integrations, and so on … rebuild the HASSIO server and reload my config?

francisp · July 17, 2020, 9:44am

Delete your home-assistant_v2.db and restart HA.