Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Ingress dies [ConnectionResetError: Cannot write to closing transport] #5248

Open
philbert opened this issue Aug 14, 2024 · 6 comments
Open
Labels

Comments

@philbert
Copy link

philbert commented Aug 14, 2024

Describe the issue you are experiencing

For a very long time on my setup (maybe 1 year+) ingress has been dying 2-3 times per month. The problem has migrated with my setup from a RPI4 4GB with SD card, to RPI4 4GB with SSD, to an Intel N100 with 16GB.

After ingress from the supervisor dies, HA still appears to run correctly, but all add-ons become unavailable. The only way to resolve the problem is to ssh to the host and run ha supervisor restart or to restart the host OS from the GUI.

The problem seems to begin with the supervisor encountering ConnectionResetError: Cannot write to closing transport.
This error occurs 22 times in the log file, and then HA is restarted. However when HA comes up again the supervisor seems to fail to establish contact with it as the supervisor log continually repeats the error
Timeout on call http://172.30.32.1:8123/api/core/state

supervisor-2024-08-14.log

From what I can tell in the HA log, everything appears ok except for one odd thing that I can't explain which is that this message from HA: s6-rc: info: service legacy-services: stopping at 18:48:58.033 occurs before the supervisor log message: [supervisor.docker.manager] Restarting homeassistant at 18:49:35.445. This oddity is quite a bit after the initial problems that the supervisor encountered, so I'm inclined not to read too much into it.

ha-2024-08-14.log

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. Unknown :(

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

[31m2024-08-13 18:44:54.294 ERROR (MainThread) [aiohttp.server] Error handling request
Traceback (most recent call last):
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request
    resp = await request_handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_app.py", line 543, in _handle
    resp = await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_middlewares.py", line 114, in impl
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 189, in block_bad_requests
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 205, in system_validation
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 224, in token_validation
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 283, in core_proxy
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/ingress.py", line 161, in handler
    return await self._handle_websocket(request, addon, path, session_data)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/ingress.py", line 190, in _handle_websocket
    await ws_server.prepare(request)
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_ws.py", line 152, in prepare
    payload_writer = await super().prepare(request)
                     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_response.py", line 417, in prepare
    return await self._start(request)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_response.py", line 425, in _start
    await self._write_headers()
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_response.py", line 505, in _write_headers
    await writer.write_headers(status_line, self._headers)
  File "/usr/local/lib/python3.12/site-packages/aiohttp/http_writer.py", line 130, in write_headers
    self._write(buf)
  File "/usr/local/lib/python3.12/site-packages/aiohttp/http_writer.py", line 75, in _write
    raise ConnectionResetError("Cannot write to closing transport")
ConnectionResetError: Cannot write to closing transport�[0m

System Health information

There are currently no repairs available

Supervisor diagnostics

config_entry-hassio-a7a964f4381b8f813b47cd58b792c395.json

Additional information

No response

@philbert philbert added the bug label Aug 14, 2024
@philbert
Copy link
Author

Same issue occurred again this evening. Now I have set up an automation for HA to send me a push notification when it starts so I can investigate the system shortly afterward and restart the supervisor.

�[32m2024-08-19 17:53:23.498 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state running�[0m
�[32m2024-08-19 17:53:23.498 INFO (MainThread) [supervisor.resolution.checks.base] Run check for ipv4_connection_problem/system�[0m
�[32m2024-08-19 17:53:23.498 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system�[0m
�[32m2024-08-19 17:53:23.498 INFO (MainThread) [supervisor.resolution.checks.base] Run check for security/core�[0m
�[32m2024-08-19 17:53:23.499 INFO (MainThread) [supervisor.resolution.checks.base] Run check for disabled_data_disk/system�[0m
�[32m2024-08-19 17:53:23.499 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_ipv6_error/dns_server�[0m
�[32m2024-08-19 17:53:23.550 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_failed/dns_server�[0m
�[32m2024-08-19 17:53:23.551 INFO (MainThread) [supervisor.resolution.checks.base] Run check for no_current_backup/system�[0m
�[32m2024-08-19 17:53:23.551 INFO (MainThread) [supervisor.resolution.checks.base] Run check for pwned/addon�[0m
�[32m2024-08-19 17:53:23.551 INFO (MainThread) [supervisor.resolution.checks.base] Run check for trust/supervisor�[0m
�[32m2024-08-19 17:53:23.558 INFO (MainThread) [supervisor.resolution.checks.base] Run check for free_space/system�[0m
�[32m2024-08-19 17:53:23.558 INFO (MainThread) [supervisor.resolution.checks.base] Run check for docker_config/system�[0m
�[32m2024-08-19 17:53:23.558 INFO (MainThread) [supervisor.resolution.check] System checks complete�[0m
�[32m2024-08-19 17:53:23.558 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running�[0m
�[32m2024-08-19 17:53:23.619 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete�[0m
�[32m2024-08-19 17:53:23.620 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running�[0m
�[32m2024-08-19 17:53:23.620 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete�[0m
�[32m2024-08-19 17:54:40.786 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json�[0m
�[32m2024-08-19 17:55:08.604 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token�[0m
�[31m2024-08-19 17:55:31.205 ERROR (MainThread) [aiohttp.server] Error handling request
Traceback (most recent call last):
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request
    resp = await request_handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_app.py", line 543, in _handle
    resp = await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_middlewares.py", line 114, in impl
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 189, in block_bad_requests
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 205, in system_validation
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 224, in token_validation
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 283, in core_proxy
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/ingress.py", line 161, in handler
    return await self._handle_websocket(request, addon, path, session_data)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/ingress.py", line 190, in _handle_websocket
    await ws_server.prepare(request)
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_ws.py", line 152, in prepare
    payload_writer = await super().prepare(request)
                     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_response.py", line 417, in prepare
    return await self._start(request)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_response.py", line 425, in _start
    await self._write_headers()
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_response.py", line 505, in _write_headers
    await writer.write_headers(status_line, self._headers)
  File "/usr/local/lib/python3.12/site-packages/aiohttp/http_writer.py", line 130, in write_headers
    self._write(buf)
  File "/usr/local/lib/python3.12/site-packages/aiohttp/http_writer.py", line 75, in _write
    raise ConnectionResetError("Cannot write to closing transport")
ConnectionResetError: Cannot write to closing transport�[0m

@bdraco
Copy link
Member

bdraco commented Aug 19, 2024

home-assistant/core#124173 is the fix on the core side for this

@philbert
Copy link
Author

The issue occurred again this afternoon, however the supervisor log looks a bit different this time. In this case there was no error Cannot write to closing transport. Below is the relevant part of the supervisor log where the restart of HA occurs.

[32m2024-08-20 17:18:57.295 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token�[0m
�[32m2024-08-20 17:19:01.493 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup�[0m
�[32m2024-08-20 17:19:01.497 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup�[0m
�[32m2024-08-20 17:48:57.454 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token�[0m
�[31m2024-08-20 17:54:23.477 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-08-20 17:55:03.478 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-08-20 17:55:03.483 INFO (SyncWorker_5) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-08-20 17:55:03.684 INFO (SyncWorker_5) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-08-20 17:55:03.722 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m
�[32m2024-08-20 17:55:04.065 INFO (MainThread) [supervisor.docker.observer] Starting Observer ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0 - 172.30.32.6�[0m
�[31m2024-08-20 17:55:28.477 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-08-20 17:55:28.477 WARNING (MainThread) [supervisor.misc.tasks] Watchdog missed an Home Assistant Core API response.�[0m
�[31m2024-08-20 17:56:04.478 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 17:57:59.478 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 17:57:59.478 ERROR (MainThread) [supervisor.misc.tasks] Watchdog missed 2 Home Assistant Core API responses in a row. Restarting Home Assistant Core!�[0m
�[32m2024-08-20 17:57:59.485 INFO (SyncWorker_7) [supervisor.docker.manager] Restarting homeassistant�[0m
�[32m2024-08-20 17:57:59.554 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-08-20 17:57:59.555 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_nodered closed�[0m
�[33m2024-08-20 17:58:14.478 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-08-20 17:58:14.484 INFO (SyncWorker_3) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-08-20 17:58:14.684 INFO (SyncWorker_3) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-08-20 17:58:14.723 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m
�[32m2024-08-20 17:58:15.078 INFO (MainThread) [supervisor.docker.observer] Starting Observer ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0 - 172.30.32.6�[0m
�[32m2024-08-20 17:58:38.048 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready�[0m
�[32m2024-08-20 17:58:40.545 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running�[0m
�[32m2024-08-20 17:58:40.654 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete�[0m
�[31m2024-08-20 17:58:47.477 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 17:58:47.477 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 17:58:47.478 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 17:58:47.478 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 17:58:47.478 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 17:58:59.478 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 17:59:04.478 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 17:59:11.478 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 17:59:13.477 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 17:59:15.476 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 17:59:40.478 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 17:59:45.477 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 17:59:47.477 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 17:59:49.478 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 17:59:51.477 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 18:00:19.478 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 18:00:20.478 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 18:00:25.477 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 18:00:25.478 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 18:00:27.478 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 18:00:51.477 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 18:01:01.477 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 18:01:01.477 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 18:01:03.477 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 18:01:06.477 ERROR (MainThread) [aiohttp.server] Error handling request�[0m
�[31m2024-08-20 18:01:23.478 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-08-20 18:01:25.479 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-08-20 18:01:25.485 INFO (SyncWorker_7) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-08-20 18:01:25.698 INFO (SyncWorker_7) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-08-20 18:01:25.735 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m
�[32m2024-08-20 18:01:26.094 INFO (MainThread) [supervisor.docker.observer] Starting Observer ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0 - 172.30.32.6�[0m
�[31m2024-08-20 18:01:37.477 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-08-20 18:01:39.478 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m

Here is the HA log from around the same period:

[32m2024-08-20 17:50:00.223 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Running automation actions�[0m
�[32m2024-08-20 17:50:00.223 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Choose at step 1: choice 1: Running automation actions�[0m
�[32m2024-08-20 17:50:00.223 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Choose at step 1: choice 1: Executing step call service�[0m
�[32m2024-08-20 17:50:23.465 INFO (MainThread) [habluetooth.scanner] hci0 (00:A5:54:3F:C0:59): Bluetooth scanner has gone quiet for 90s, restarting�[0m
�[32m2024-08-20 17:54:23.474 INFO (MainThread) [habluetooth.scanner] hci0 (00:A5:54:3F:C0:59): Bluetooth scanner has gone quiet for 90s, restarting�[0m
�[32m2024-08-20 17:55:00.223 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Running automation actions�[0m
�[32m2024-08-20 17:55:00.224 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Choose at step 1: choice 1: Running automation actions�[0m
�[32m2024-08-20 17:55:00.224 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Choose at step 1: choice 1: Executing step call service�[0m
�[32m2024-08-20 17:57:53.484 INFO (MainThread) [habluetooth.scanner] hci0 (00:A5:54:3F:C0:59): Bluetooth scanner has gone quiet for 90s, restarting�[0m
s6-rc: info: service legacy-services: stopping
�[32m2024-08-20 17:57:59.527 INFO (MainThread) [homeassistant.components.netatmo.data_handler] Netatmo webhook unregistered�[0m
�[32m2024-08-20 17:57:59.541 INFO (MainThread) [dsmr_parser.clients.protocol] disconnected because of close/abort.�[0m
�[31m2024-08-20 17:58:31.479 ERROR (MainThread) [aiohttp.server] Unhandled exception
Traceback (most recent call last):
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_protocol.py", line 456, in _handle_request
    resp = await request_handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_app.py", line 537, in _handle
    resp = await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_middlewares.py", line 114, in impl
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/security_filter.py", line 92, in security_filter_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/forwarded.py", line 83, in forwarded_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/request_context.py", line 26, in request_context_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/ban.py", line 85, in ban_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/auth.py", line 242, in auth_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/headers.py", line 32, in headers_middleware
    response = await handler(request)
               ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/http.py", line 73, in handle
    result = await handler(request, **request.match_info)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/hassio/ingress.py", line 94, in _handle
    return await self._handle_websocket(request, token, path)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/hassio/ingress.py", line 146, in _handle_websocket
    await asyncio.wait(
  File "/usr/local/lib/python3.12/asyncio/tasks.py", line 464, in wait
    return await _wait(fs, timeout, return_when, loop)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/asyncio/tasks.py", line 550, in _wait
    await waiter
asyncio.exceptions.CancelledError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_protocol.py", line 539, in start
    resp, reset = await task
                  ^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_protocol.py", line 483, in _handle_request
    self._handler_waiter.set_result(None)
asyncio.exceptions.InvalidStateError: invalid state�[0m
�[31m2024-08-20 17:58:31.489 ERROR (MainThread) [aiohttp.server] Unhandled exception
Traceback (most recent call last):
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_protocol.py", line 456, in _handle_request
    resp = await request_handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_app.py", line 537, in _handle
    resp = await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_middlewares.py", line 114, in impl
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/security_filter.py", line 92, in security_filter_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/forwarded.py", line 83, in forwarded_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/request_context.py", line 26, in request_context_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/ban.py", line 85, in ban_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/auth.py", line 242, in auth_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/headers.py", line 32, in headers_middleware
    response = await handler(request)
               ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/http.py", line 73, in handle
    result = await handler(request, **request.match_info)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/hassio/ingress.py", line 94, in _handle
    return await self._handle_websocket(request, token, path)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/hassio/ingress.py", line 146, in _handle_websocket
    await asyncio.wait(
  File "/usr/local/lib/python3.12/asyncio/tasks.py", line 464, in wait
    return await _wait(fs, timeout, return_when, loop)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/asyncio/tasks.py", line 550, in _wait
    await waiter
asyncio.exceptions.CancelledError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_protocol.py", line 539, in start
    resp, reset = await task
                  ^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_protocol.py", line 483, in _handle_request
    self._handler_waiter.set_result(None)
asyncio.exceptions.InvalidStateError: invalid state�[0m
[15:58:33] INFO: �[32mHome Assistant Core finish process exit code 0�[0m
[15:58:33] INFO: �[32mHome Assistant Core service shutdown�[0m
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
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

Even though the logs appear quite different, the symptoms that I experienced were exactly the same. Ingress was still down, and sshing to the host and running ha supervisor restart was the fix.

The problems that HA encounters which cause the supervisor to restart it could be many, however the main issue is that the supervisor fails to re-establish a connection to HA after the restart because it has encountered some problem of it's own. Can you tell from these new logs whether you expect your fix to work for this situation as well?

Copy link

There hasn't been any activity on this issue recently. Due to the high number of incoming GitHub notifications, we have to clean some of the old issues, as many of them have already been resolved with the latest updates.
Please make sure to update to the latest version and check if that solves the issue. Let us know if that works for you by adding a comment 👍
This issue has now been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.

@github-actions github-actions bot added the stale label Sep 19, 2024
@philbert
Copy link
Author

Since upgrading to 2024.8.3 I have not seen the random restarts of Home Assistant which seem to be the main cause of this issue with the supervisor. The system has been running stable for about 1 month now which is a good sign! Although there is still likely some problem with the Supervisor I'll close this issue for now and reopen again if the problem re-occurs.

@philbert philbert reopened this Oct 13, 2024
@github-actions github-actions bot removed the stale label Oct 13, 2024
@philbert
Copy link
Author

philbert commented Oct 13, 2024

Although the occasions when this occurs has reduced, the problem is still there.

This is how I understand the sequence in the logs:

  1. This morning around 4:35 the supervisor started getting timeouts polling the HA core API.
  2. After another timeout, the watchdog is triggered, and the observer gets restarted. Seems maybe this might be done as a precautionary step rather than restarting HA immediately?
  3. After observer is running again, supervisor continues to see timeouts against the HA core API
  4. The watchdog is triggered a second time, causing supervisor to restart HA
  5. Supervisor tracks of state of HA, setting it to 'STARTING'
  6. Timeouts continue to be seen against HA core API, supervisor repeatedly restarts observer to try and fix the issue
  7. Supervisor eventually concludes there has been a fatal startup error, but continues vainly to restart observer to no avail

Why does the supervisor continually restart the observer after the supervisor loses contact with home assistant, rather than restart itself? Isn't the supervisor supposed to manage itself too? There seems to be a error in the logic of the decision making from supervisor here. I just run ha supervisor restart on the host to solve this issue.

I just learned about the observer webportal available at http://homeassistant.local:4357/, which I will inspect next time this happens. I also set the supervisor.plugins.observer logging level to debug

Supervisor log:

[32m2024-10-13 04:22:17.317 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running�[0m
�[32m2024-10-13 04:22:17.398 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete�[0m
�[32m2024-10-13 04:22:17.398 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running�[0m
�[32m2024-10-13 04:22:17.398 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete�[0m
�[32m2024-10-13 04:22:39.128 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token�[0m
�[31m2024-10-13 04:34:59.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:35:09.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-10-13 04:35:09.862 WARNING (MainThread) [supervisor.misc.tasks] Watchdog missed an Home Assistant Core API response.�[0m
�[31m2024-10-13 04:36:40.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-10-13 04:37:21.863 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-10-13 04:37:21.869 INFO (SyncWorker_6) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-10-13 04:37:22.078 INFO (SyncWorker_6) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-10-13 04:37:22.122 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m
�[32m2024-10-13 04:37:22.507 INFO (MainThread) [supervisor.docker.observer] Starting Observer ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0 - 172.30.32.6�[0m
�[31m2024-10-13 04:37:40.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:37:40.862 ERROR (MainThread) [supervisor.misc.tasks] Watchdog missed 2 Home Assistant Core API responses in a row. Restarting Home Assistant Core!�[0m
�[32m2024-10-13 04:37:40.870 INFO (SyncWorker_5) [supervisor.docker.manager] Restarting homeassistant�[0m
�[32m2024-10-13 04:37:40.938 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.942 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.942 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.943 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.943 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.943 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.948 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.948 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.949 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.951 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.951 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.951 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.952 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.952 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.954 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.955 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.955 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed�[0m
�[32m2024-10-13 04:37:40.958 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_nodered closed�[0m
�[32m2024-10-13 04:38:08.766 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready�[0m
�[32m2024-10-13 04:38:11.411 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running�[0m
�[32m2024-10-13 04:38:11.484 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete�[0m
�[32m2024-10-13 04:38:13.772 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to APIState(core_state='NOT_RUNNING', offline_db_migration=False)�[0m
�[32m2024-10-13 04:38:18.784 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to APIState(core_state='STARTING', offline_db_migration=False)�[0m
�[32m2024-10-13 04:38:19.240 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.242 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.244 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.246 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.252 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.254 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.332 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.333 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.336 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.336 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.339 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.340 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.341 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.342 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.344 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.346 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.363 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.366 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.367 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.368 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.369 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.372 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.373 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.373 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.375 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.376 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.378 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.531 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.532 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.533 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.535 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.537 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.538 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.540 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.541 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.543 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.544 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.547 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.549 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.551 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.552 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:19.554 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.556 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode�[0m
�[32m2024-10-13 04:38:19.883 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.883 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.888 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.891 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.892 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.906 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.913 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:19.918 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[32m2024-10-13 04:38:21.051 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize�[0m
�[32m2024-10-13 04:38:21.054 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered�[0m
�[32m2024-10-13 04:38:21.058 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running�[0m
�[31m2024-10-13 04:38:53.861 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:39:21.864 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:39:29.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:40:05.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-10-13 04:40:32.862 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-10-13 04:40:32.866 INFO (SyncWorker_1) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-10-13 04:40:33.064 INFO (SyncWorker_1) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-10-13 04:40:33.112 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m
�[32m2024-10-13 04:40:33.515 INFO (MainThread) [supervisor.docker.observer] Starting Observer ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0 - 172.30.32.6�[0m
�[31m2024-10-13 04:40:41.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:03.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:03.864 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:03.864 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:03.864 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:03.864 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:03.865 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:03.865 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:13.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:17.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:41:53.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:42:29.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:43:05.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:43:41.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-10-13 04:43:43.862 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-10-13 04:43:43.868 INFO (SyncWorker_1) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-10-13 04:43:44.067 INFO (SyncWorker_1) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-10-13 04:43:44.104 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m
[32m2024-10-13 04:43:44.483 INFO (MainThread) [supervisor.docker.observer] Starting Observer ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0 - 172.30.32.6�[0m
�[31m2024-10-13 04:44:02.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:14.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:14.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:14.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:14.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:14.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:14.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:14.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:17.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:24.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:44:53.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:45:29.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:46:05.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:46:41.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-10-13 04:46:54.863 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-10-13 04:46:54.869 INFO (SyncWorker_4) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-10-13 04:46:55.126 INFO (SyncWorker_4) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-10-13 04:46:55.163 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m
�[32m2024-10-13 04:46:55.511 INFO (MainThread) [supervisor.docker.observer] Starting Observer ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0 - 172.30.32.6�[0m
�[31m2024-10-13 04:47:17.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:47:25.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:47:25.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:47:25.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:47:25.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:47:25.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:47:25.864 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:47:25.864 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:47:35.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:47:53.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:48:29.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:49:05.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:49:41.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:49:43.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-10-13 04:50:05.863 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-10-13 04:50:05.870 INFO (SyncWorker_6) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-10-13 04:50:06.073 INFO (SyncWorker_6) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-10-13 04:50:06.108 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m
�[32m2024-10-13 04:50:06.465 INFO (MainThread) [supervisor.docker.observer] Starting Observer ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0 - 172.30.32.6�[0m
�[31m2024-10-13 04:50:17.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:50:36.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:50:36.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:50:36.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:50:36.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:50:36.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:50:36.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:50:36.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:50:46.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:50:53.864 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:51:29.864 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:52:05.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:52:33.861 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:52:33.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:52:41.862 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-10-13 04:53:16.862 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-10-13 04:53:16.865 INFO (SyncWorker_4) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-10-13 04:53:17.038 INFO (SyncWorker_4) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-10-13 04:53:17.075 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m
�[32m2024-10-13 04:53:17.444 INFO (MainThread) [supervisor.docker.observer] Starting Observer ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0 - 172.30.32.6�[0m
�[31m2024-10-13 04:53:48.867 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:53:48.867 ERROR (MainThread) [supervisor.homeassistant.core] No Home Assistant Core response, assuming a fatal startup error�[0m
�[31m2024-10-13 04:53:48.867 ERROR (MainThread) [supervisor.misc.tasks] Home Assistant watchdog reanimation failed!�[0m
�[31m2024-10-13 04:54:19.867 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:54:50.865 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:55:21.867 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:55:52.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[31m2024-10-13 04:56:23.863 ERROR (MainThread) [supervisor.homeassistant.api] Timeout on call http://172.30.32.1:8123/api/core/state.�[0m
�[33m2024-10-13 04:56:27.862 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!�[0m
�[32m2024-10-13 04:56:27.868 INFO (SyncWorker_2) [supervisor.docker.manager] Stopping hassio_observer application�[0m
�[32m2024-10-13 04:56:28.040 INFO (SyncWorker_2) [supervisor.docker.manager] Cleaning hassio_observer application�[0m
�[32m2024-10-13 04:56:28.077 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin�[0m

HA log:

[33m2024-10-13 04:30:56.211 WARNING (MainThread) [homeassistant.helpers.template] Template variable warning: 'dict object' has no attribute 'current_heating_setpoint' when rendering '{{ value_json.current_heating_setpoint }}'�[0m
�[33m2024-10-13 04:31:06.945 WARNING (MainThread) [homeassistant.helpers.template] Template variable warning: 'dict object' has no attribute 'current_heating_setpoint' when rendering '{{ value_json.current_heating_setpoint }}'�[0m
�[33m2024-10-13 04:32:06.786 WARNING (MainThread) [homeassistant.helpers.template] Template variable warning: 'dict object' has no attribute 'current_heating_setpoint' when rendering '{{ value_json.current_heating_setpoint }}'�[0m
�[32m2024-10-13 04:35:00.496 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Running automation actions�[0m
�[32m2024-10-13 04:35:00.497 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Choose at step 1: choice 1: Running automation actions�[0m
�[32m2024-10-13 04:35:00.497 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Choose at step 1: choice 1: Executing step call service�[0m
s6-rc: info: service legacy-services: stopping
�[32m2024-10-13 04:37:40.927 INFO (MainThread) [dsmr_parser.clients.protocol] disconnected because of close/abort.�[0m
[02:38:05] INFO: �[32mHome Assistant Core finish process exit code 0�[0m
[02:38:05] INFO: �[32mHome Assistant Core service shutdown�[0m
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
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
�[33m2024-10-13 04:38:10.719 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration nodered 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�[0m
�[33m2024-10-13 04:38:10.720 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration 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�[0m
�[33m2024-10-13 04:38:10.721 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration easee 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�[0m
�[33m2024-10-13 04:38:10.722 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration tesla_custom 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�[0m
�[33m2024-10-13 04:38:10.722 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration scheduler 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�[0m
�[33m2024-10-13 04:38:10.723 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration climate_group 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�[0m
�[32m2024-10-13 04:38:11.182 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.12 seconds�[0m
�[32m2024-10-13 04:38:11.183 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.12 seconds�[0m
�[32m2024-10-13 04:38:11.183 INFO (MainThread) [homeassistant.bootstrap] Setting up frontend: {'frontend'}�[0m
�[32m2024-10-13 04:38:11.183 INFO (MainThread) [homeassistant.setup] Setting up http�[0m
�[32m2024-10-13 04:38:11.184 INFO (MainThread) [homeassistant.setup] Setting up device_automation�[0m

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

2 participants