Stuck i start-up, can't execute restart

[witno (Ivar)]

Hi
After misc tweeking attemts and a Home assistant upgrade, my system is stuck in the start-up. Several of the integrations and add-ons are not starting and I am unable to restart the system running on remote location on a rpi4. Hard reset by pulling the power has no effect.

ERROR (MainThread) [supervisor.utils] Can't execute restart while a task is in progress

So I assume there is some task being stuck, preveting other processes to start and the system form being restarted.

The logs indicates an error not reaching a Telldus device on the network. This is expected, as the device is switched off. It normaly is when I am not at the location, and this has not caused any issue before.

> 2021-02-05 13:11:13 WARNING (SyncWorker_9) [tellduslive] Failed request: HTTPConnectionPool(host='192.168.1.104', port=80): Max retries exceeded with url: /api/devices/list?supportedMethods=915&includeIgnored=0 (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0xa51ea670>: Failed to establish a new connection: [Errno 113] Host is unreachable'))

The log also shows these warnings:

> Error handling request
> 
> 12:54:46 PM – /usr/local/lib/python3.8/site-packages/aiohttp/web_protocol.py (ERROR) - message first occurred at 5:31:48 AM and shows up 7 times
> 
> Error during Bluetooth LE scan: Unexpected error when scanning:
> 
> 12:20:25 PM – bluetooth_le_tracker (ERROR) - message first occurred at 10:51:51 AM and shows up 3 times
> 
> Unexpected error when scanning:
> 
> 12:20:25 PM – /usr/local/lib/python3.8/site-packages/pygatt/backends/gatttool/gatttool.py (ERROR) - message first occurred at 10:51:51 AM and shows up 3 times
> 
> [2958534496] Client unable to keep up with pending messages. Stayed over 512 for 5 seconds
> 
> 3:19:51 AM – Home Assistant WebSocket API (ERROR)
> 
> Ping fails, no response from peer
> 
> 3:17:27 AM – /usr/local/lib/python3.8/site-packages/snitun/multiplexer/core.py (ERROR)
> 
> Connection to (192.168.1.102, 502) failed: [Errno 101] Network unreachable
> 
> 3:16:16 AM – /usr/local/lib/python3.8/site-packages/pymodbus/client/sync.py (ERROR) - message first occurred at 3:15:49 AM and shows up 23 times
> 
> Unable to connect: Cannot connect to host cloud.nabucasa.com:443 ssl:default [Try again]
> 
> 3:16:14 AM – /usr/local/lib/python3.8/site-packages/hass_nabucasa/iot_base.py (WARNING) - message first occurred at 3:15:59 AM and shows up 2 times
> 
> Error sending through socket 23
> 
> 3:16:02 AM – /usr/local/lib/python3.8/site-packages/zeroconf/__init__.py (WARNING)
> 
> Error requesting Archer_MR600 data: Cannot connect to host 192.168.1.1:1900 ssl:default [Network unreachable]
> 
> 3:15:56 AM – UPnP (ERROR)
> 
> You are using a custom integration for ble_monitor 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.
> 
> 3:11:49 AM – loader.py (WARNING)
> 
> Socket timeout trying to read info from Tellstick
> 
> 3:11:48 AM – /usr/local/lib/python3.8/site-packages/tellduslive.py (WARNING)
> 
> Failed to refresh access token: HTTPConnectionPool(host='192.168.1.104', port=80): Max retries exceeded with url: /api/refreshToken (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0xae359cb8>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
> 
> 3:11:47 AM – /usr/local/lib/python3.8/site-packages/tellduslive.py (ERROR)

I am no coding expert and need some advice on how to approach this.
Thanks.

[cloudedturtle (Matt)]

I’m having this same issue, although my logs are different. I updated for 2021.2 yesterday, tried adding ZwaveJS. Started seeing failures, so restored a snapshot prior to adding ZwaveJS, but still showing is a perpetual state of “starting up” and can’t restart core, etc.

Supervisor Log:

21-02-05 14:19:58 INFO (MainThread) [supervisor.api.security] /snapshots access from cebe7a76_hassio_google_drive_backup
21-02-05 14:40:00 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
21-02-05 14:57:16 INFO (MainThread) [supervisor.snapshots] Found 1 snapshot files
21-02-05 15:00:05 ERROR (MainThread) [supervisor.utils] Can't execute restart while a task is in progress
21-02-05 15:01:01 ERROR (MainThread) [supervisor.utils] Can't execute restart while a task is in progress
21-02-05 15:04:51 INFO (MainThread) [supervisor.snapshots] Found 1 snapshot files
21-02-05 15:10:01 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
21-02-05 15:10:01 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token

Core Log:

2021-02-04 21:07:45 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds
2021-02-04 21:07:45 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=system_log>
2021-02-04 21:07:55 WARNING (MainThread) [homeassistant.setup] Setup of recorder is taking over 10 seconds.
--- Logging error ---
Traceback (most recent call last):
  File "/usr/local/lib/python3.8/logging/__init__.py", line 1081, in emit
    msg = self.format(record)
  File "/usr/local/lib/python3.8/logging/__init__.py", line 925, in format
    return fmt.format(record)
  File "/usr/local/lib/python3.8/site-packages/colorlog/colorlog.py", line 123, in format
    message = super(ColoredFormatter, self).format(record)
  File "/usr/local/lib/python3.8/logging/__init__.py", line 672, in format
    record.exc_text = self.formatException(record.exc_info)
  File "/usr/local/lib/python3.8/logging/__init__.py", line 622, in formatException
    traceback.print_exception(ei[0], ei[1], tb, None, sio)
  File "/usr/local/lib/python3.8/traceback.py", line 103, in print_exception
    for line in TracebackException(
  File "/usr/local/lib/python3.8/traceback.py", line 508, in __init__
    self.stack = StackSummary.extract(
  File "/usr/local/lib/python3.8/traceback.py", line 366, in extract
    f.line
  File "/usr/local/lib/python3.8/traceback.py", line 288, in line
    self._line = linecache.getline(self.filename, self.lineno).strip()
  File "/usr/local/lib/python3.8/linecache.py", line 16, in getline
    lines = getlines(filename, module_globals)
  File "/usr/local/lib/python3.8/linecache.py", line 47, in getlines
    return updatecache(filename, module_globals)
  File "/usr/local/lib/python3.8/linecache.py", line 137, in updatecache
    lines = fp.readlines()
  File "/usr/local/lib/python3.8/codecs.py", line 322, in decode
    (result, consumed) = self._buffer_decode(data, self.errors, final)
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x8c in position 5554: invalid start byte
Call stack:
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/connector.py:999: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  hosts = await asyncio.shield(
/usr/local/lib/python3.8/site-packages/alexapy/aiohttp/locks.py:22: DeprecationWarning: The loop argument is deprecated since Python 3.8, and scheduled for removal in Python 3.10.
  self._event = asyncio.Event(loop=loop)

[tetragram (JohnP)]

I’m having this same issue, although my logs are different than both yours. I updated to 2021.2.1 today and now also showing is a perpetual state of “starting up” and can’t restart core.
It’s just stuck.

[witno (Ivar)]

Yup. Unable to update as well. Same thing with a task in prgress.

21-02-05 16:40:40 ERROR (MainThread) [supervisor.utils] Can’t execute wrapper while a task is in progress

Is there any ways of getting a readout of task’s, preferabley filtered by task in prgress?
And then kill the task?
Please?

[tetragram (JohnP)]

I found this (attached) and tried removing Z-wave from config. The system starts up with the upgrade but I have no z-wave.
I am moving to the new JS Z-wave so it’s a moot point.

Z-Wave (deprecated) 2021.2.0 Release Issue / Fix

Once I upgraded to 2021.2.0 home assistant would not fully start. I kept having the message : Home Assistant is starting, not everything will be available until it is finished. To fix I had to remove the zwave config from the configuration file (I just commented it out). I had to shutdown home assistant as the restart wouldn’t complete as it would say another task hasn’t finished. Maybe it saves some headaches for others. I might have missed a notice on this but it helped. All my zwave devices …

[witno (Ivar)]

Thanks, bro! You nailed it.
Removed the z-wave integrations and now back on track.

[Jerrit]

Ahh I wonder if I had zwave setup through the integration page as well as the configuration file. Everything is working for me but I think I am going to move my zwave back over to a vera hub. The jumping around from zwave, to OZW and now something new has me a little worried.