home-assistant / supervisor

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

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

Open philbert opened 2 months ago

philbert commented 2 months ago

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

System Health information

There are currently no repairs available

Supervisor diagnostics

config_entry-hassio-a7a964f4381b8f813b47cd58b792c395.json

Additional information

No response

philbert commented 2 months ago

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.

2024-08-19 17:53:23.498 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state running
2024-08-19 17:53:23.498 INFO (MainThread) [supervisor.resolution.checks.base] Run check for ipv4_connection_problem/system
2024-08-19 17:53:23.498 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system
2024-08-19 17:53:23.498 INFO (MainThread) [supervisor.resolution.checks.base] Run check for security/core
2024-08-19 17:53:23.499 INFO (MainThread) [supervisor.resolution.checks.base] Run check for disabled_data_disk/system
2024-08-19 17:53:23.499 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_ipv6_error/dns_server
2024-08-19 17:53:23.550 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_failed/dns_server
2024-08-19 17:53:23.551 INFO (MainThread) [supervisor.resolution.checks.base] Run check for no_current_backup/system
2024-08-19 17:53:23.551 INFO (MainThread) [supervisor.resolution.checks.base] Run check for pwned/addon
2024-08-19 17:53:23.551 INFO (MainThread) [supervisor.resolution.checks.base] Run check for trust/supervisor
2024-08-19 17:53:23.558 INFO (MainThread) [supervisor.resolution.checks.base] Run check for free_space/system
2024-08-19 17:53:23.558 INFO (MainThread) [supervisor.resolution.checks.base] Run check for docker_config/system
2024-08-19 17:53:23.558 INFO (MainThread) [supervisor.resolution.check] System checks complete
2024-08-19 17:53:23.558 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
2024-08-19 17:53:23.619 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
2024-08-19 17:53:23.620 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running
2024-08-19 17:53:23.620 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete
2024-08-19 17:54:40.786 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
2024-08-19 17:55:08.604 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
2024-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
bdraco commented 2 months ago

https://github.com/home-assistant/core/pull/124173 is the fix on the core side for this

philbert commented 2 months ago

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

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
2024-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
2024-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
2024-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
2024-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
2024-08-20 17:55:00.223 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Running automation actions
2024-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
2024-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
2024-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
s6-rc: info: service legacy-services: stopping
2024-08-20 17:57:59.527 INFO (MainThread) [homeassistant.components.netatmo.data_handler] Netatmo webhook unregistered
2024-08-20 17:57:59.541 INFO (MainThread) [dsmr_parser.clients.protocol] disconnected because of close/abort.
2024-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
2024-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
[15:58:33] INFO: Home Assistant Core finish process exit code 0
[15:58:33] INFO: Home Assistant Core service shutdown
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?

github-actions[bot] commented 1 month ago

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.

philbert commented 1 month ago

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 commented 1 week ago

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

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 }}'
2024-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 }}'
2024-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 }}'
2024-10-13 04:35:00.496 INFO (MainThread) [homeassistant.components.automation.unavailable_entities_notification] Unavailable Entities Notification: Running automation actions
2024-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
2024-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
s6-rc: info: service legacy-services: stopping
2024-10-13 04:37:40.927 INFO (MainThread) [dsmr_parser.clients.protocol] disconnected because of close/abort.
[02:38:05] INFO: Home Assistant Core finish process exit code 0
[02:38:05] INFO: Home Assistant Core service shutdown
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
2024-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
2024-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
2024-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
2024-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
2024-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
2024-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
2024-10-13 04:38:11.182 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.12 seconds
2024-10-13 04:38:11.183 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.12 seconds
2024-10-13 04:38:11.183 INFO (MainThread) [homeassistant.bootstrap] Setting up frontend: {'frontend'}
2024-10-13 04:38:11.183 INFO (MainThread) [homeassistant.setup] Setting up http
2024-10-13 04:38:11.184 INFO (MainThread) [homeassistant.setup] Setting up device_automation