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

Containers continuously deactivated, system is almost useless #3021

Closed
MatteGary opened this issue Dec 26, 2023 · 12 comments
Closed

Containers continuously deactivated, system is almost useless #3021

MatteGary opened this issue Dec 26, 2023 · 12 comments
Labels

Comments

@MatteGary
Copy link

Describe the issue you are experiencing

Hi,
since several days, my HA installation became really slow, almost useless. UI crashes every other minute, Alexa and Apple Home have problem in sending command to UI.
I’m running HASS OS on a x64 minipc, I have HA from 5 year, and more than 1 year on this HW.
I did some investigation, and I think the reason of all of this is related to the containers behind HA. I have a lot of identical logs in the Host registry

Dec 22 20:20:56 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.ve78Ok.mount: Deactivated successfully.
Dec 22 20:20:58 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.EEzPUc.mount: Deactivated successfully.
Dec 22 20:20:59 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.3vgVgt.mount: Deactivated successfully.
Dec 22 20:21:26 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.9QmutO.mount: Deactivated successfully.
Dec 22 20:21:28 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.jzUyNk.mount: Deactivated successfully.
Dec 22 20:21:29 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.F4kLGS.mount: Deactivated successfully.
Dec 22 20:21:56 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.pZjFzJ.mount: Deactivated successfully.
Dec 22 20:22:56 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.GekjlS.mount: Deactivated successfully.
Dec 22 20:22:59 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.75gZY6.mount: Deactivated successfully.
Dec 22 20:23:26 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.FdJirn.mount: Deactivated successfully.
Dec 22 20:23:30 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.sLDQHZ.mount: Deactivated successfully.
Dec 22 20:23:59 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.62TxE4.mount: Deactivated successfully.
Dec 22 20:24:30 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.idiXKt.mount: Deactivated successfully.

and these

23-12-26 17:37:19 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-26 17:37:43 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-26 17:37:43 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-26 17:37:48 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-26 17:37:48 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-26 17:37:48 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running

These logs, thanks to another post in the community, led me to believe that some (or all) containers are constantly failing and being reactivated by the Watchdog.
I managed to understand which are the docker that keep getting deactivate while I was performing some troubleshooting:

b4f04e homeassistant/amd64-addon-configurator:5.7.0
8546a3 ghcr.io/hassio-addons/node-red/amd64:16.0.2
fc8db1 ghcr.io/hassio-addons/adguard/amd64:5.0.1

at this moment these are the only ones that are getting deactivated and activate all over again, but during the last 24 hours I noticed also others having the same behavior. How can I investigate more this issue? Where I can find the root cause of this activation/deactivation? Logs currently doesn't give any information on why everything seems to be failing.
There must be something, maybe on docker side, that can points me in the right direction.

Thanks
Matteo

What operating system image do you use?

generic-x86-64 (Generic UEFI capable x86-64 systems)

What version of Home Assistant Operating System is installed?

11.2

Did you upgrade the Operating System.

Yes

Steps to reproduce the issue

I wish I knew, it just keep happening.

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

23-12-26 17:37:19 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-26 17:37:43 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-26 17:37:43 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-26 17:37:48 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-26 17:37:48 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-26 17:37:48 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running

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

Dec 26 20:15:53 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.rUUMtc.mount: Deactivated successfully.
Dec 26 20:16:23 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.3z9OeL.mount: Deactivated successfully.
Dec 26 20:16:39 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.YCqhxL.mount: Deactivated successfully.
Dec 26 20:16:53 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.Sd7xvE.mount: Deactivated successfully.
Dec 26 20:17:09 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.KWlHeQ.mount: Deactivated successfully.
Dec 26 20:17:23 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.SEvHL4.mount: Deactivated successfully.
Dec 26 20:17:39 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.BbeprY.mount: Deactivated successfully.
Dec 26 20:17:50 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.DPhViJ.mount: Deactivated successfully.
Dec 26 20:17:53 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.NvgFCj.mount: Deactivated successfully.
Dec 26 20:18:09 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.OXvPN2.mount: Deactivated successfully.
Dec 26 20:18:21 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.aaYWF3.mount: Deactivated successfully.
Dec 26 20:19:10 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.pUO1bG.mount: Deactivated successfully.
Dec 26 20:19:21 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.cX55nv.mount: Deactivated successfully.
Dec 26 20:19:23 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.6BBasL.mount: Deactivated successfully.
Dec 26 20:19:40 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.H9Ag2Q.mount: Deactivated successfully.
Dec 26 20:19:51 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.GcJhFD.mount: Deactivated successfully.
Dec 26 20:19:53 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.RPbRF9.mount: Deactivated successfully.
Dec 26 20:20:23 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.gW0LqW.mount: Deactivated successfully.
Dec 26 20:20:40 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.IDJud8.mount: Deactivated successfully.
Dec 26 20:20:51 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.715qDB.mount: Deactivated successfully.
Dec 26 20:20:53 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.X2V8wN.mount: Deactivated successfully.
Dec 26 20:21:10 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.DEHKWp.mount: Deactivated successfully.
Dec 26 20:21:21 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.KkuVcg.mount: Deactivated successfully.
Dec 26 20:21:40 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.1oOige.mount: Deactivated successfully.
Dec 26 20:22:40 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.3ffNrF.mount: Deactivated successfully.
Dec 26 20:22:51 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.1NspIw.mount: Deactivated successfully.
Dec 26 20:23:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.dNwOyL.mount: Deactivated successfully.
Dec 26 20:23:52 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.yRSk0E.mount: Deactivated successfully.
Dec 26 20:23:54 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.2cxKI8.mount: Deactivated successfully.
Dec 26 20:24:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.WUx2fT.mount: Deactivated successfully.
Dec 26 20:24:40 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.wX2MwS.mount: Deactivated successfully.
Dec 26 20:24:52 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.kDc49A.mount: Deactivated successfully.
Dec 26 20:24:54 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.zdssoD.mount: Deactivated successfully.
Dec 26 20:25:10 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.pIBnSi.mount: Deactivated successfully.
Dec 26 20:25:22 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.qZWkMS.mount: Deactivated successfully.
Dec 26 20:25:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.Fqy4ac.mount: Deactivated successfully.
Dec 26 20:25:40 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.8s6bo1.mount: Deactivated successfully.
Dec 26 20:26:10 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.aEQjsN.mount: Deactivated successfully.
Dec 26 20:26:22 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.vGoZSN.mount: Deactivated successfully.
Dec 26 20:26:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.6rk2KN.mount: Deactivated successfully.
Dec 26 20:26:54 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.Yd8V4V.mount: Deactivated successfully.
Dec 26 20:26:54 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.IMrHL2.mount: Deactivated successfully.
Dec 26 20:27:10 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.TCCfsc.mount: Deactivated successfully.
Dec 26 20:27:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.ccRZ7d.mount: Deactivated successfully.
Dec 26 20:27:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.S5doJU.mount: Deactivated successfully.
Dec 26 20:27:54 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.0yWcvw.mount: Deactivated successfully.
Dec 26 20:28:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.J3KMgi.mount: Deactivated successfully.
Dec 26 20:28:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.9m3t37.mount: Deactivated successfully.
Dec 26 20:29:10 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.75T9pA.mount: Deactivated successfully.
Dec 26 20:29:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.AiTuw1.mount: Deactivated successfully.
Dec 26 20:30:10 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.LMwpp0.mount: Deactivated successfully.
Dec 26 20:30:40 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.QSS0sQ.mount: Deactivated successfully.
Dec 26 20:31:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.Uu796G.mount: Deactivated successfully.
Dec 26 20:31:25 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.KKHD1b.mount: Deactivated successfully.
Dec 26 20:31:41 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.YdLooK.mount: Deactivated successfully.
Dec 26 20:31:54 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.KHSv0d.mount: Deactivated successfully.
Dec 26 20:32:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.iQt8xK.mount: Deactivated successfully.
Dec 26 20:32:25 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.z64TRd.mount: Deactivated successfully.
Dec 26 20:32:41 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.zRG2XC.mount: Deactivated successfully.
Dec 26 20:32:58 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.RH0TVI.mount: Deactivated successfully.
Dec 26 20:33:11 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.yYm9Ti.mount: Deactivated successfully.
Dec 26 20:33:24 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.kpEDMY.mount: Deactivated successfully.
Dec 26 20:33:28 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.KOn5FP.mount: Deactivated successfully.
Dec 26 20:33:41 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.2datqL.mount: Deactivated successfully.
Dec 26 20:33:58 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.qnkWSz.mount: Deactivated successfully.
Dec 26 20:34:11 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.yGQRC4.mount: Deactivated successfully.
Dec 26 20:34:28 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.7wDAA6.mount: Deactivated successfully.
Dec 26 20:34:41 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.sVDbvC.mount: Deactivated successfully.
Dec 26 20:34:55 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.Ch8lpu.mount: Deactivated successfully.
Dec 26 20:35:11 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.uWm65Q.mount: Deactivated successfully.
Dec 26 20:35:25 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.rAEBuZ.mount: Deactivated successfully.
Dec 26 20:35:28 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.f4sZGc.mount: Deactivated successfully.
Dec 26 20:35:41 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.3gAmf3.mount: Deactivated successfully.
Dec 26 20:35:58 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.64X4yP.mount: Deactivated successfully.
Dec 26 20:36:11 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.IFYD7Z.mount: Deactivated successfully.
Dec 26 20:36:28 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.hNyTm8.mount: Deactivated successfully.
Dec 26 20:36:41 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.n269vd.mount: Deactivated successfully.
Dec 26 20:37:11 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.Qkqwim.mount: Deactivated successfully.
Dec 26 20:37:25 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.wlaHOY.mount: Deactivated successfully.
Dec 26 20:37:59 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.SpPIBD.mount: Deactivated successfully.
Dec 26 20:38:30 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.oqRowU.mount: Deactivated successfully.
Dec 26 20:39:00 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.gxdkbV.mount: Deactivated successfully.
Dec 26 20:39:30 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.UmKGQ6.mount: Deactivated successfully.
Dec 26 20:39:41 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.SVFyXN.mount: Deactivated successfully.
Dec 26 20:40:00 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.3ng6BC.mount: Deactivated successfully.
Dec 26 20:40:11 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.MlHhtU.mount: Deactivated successfully.
Dec 26 20:40:25 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.iC0ia9.mount: Deactivated successfully.
Dec 26 20:41:00 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.yI2Ubw.mount: Deactivated successfully.
Dec 26 20:41:25 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.InyG1N.mount: Deactivated successfully.
Dec 26 20:41:30 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.FYyP03.mount: Deactivated successfully.
Dec 26 20:41:55 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.tzkvni.mount: Deactivated successfully.
Dec 26 20:42:00 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.QZNoas.mount: Deactivated successfully.
Dec 26 20:42:30 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.cDW89Y.mount: Deactivated successfully.
Dec 26 20:42:41 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.XC4kWV.mount: Deactivated successfully.
Dec 26 20:42:55 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.BoqjOZ.mount: Deactivated successfully.
Dec 26 20:43:01 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.x0QKQT.mount: Deactivated successfully.
Dec 26 20:43:11 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-8546a3eb45afbfe7ba92e255af53b8e35c932e77c44a2ac9aff1b32dbf0e6b97-runc.8DZhb7.mount: Deactivated successfully.
Dec 26 20:43:25 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.lAXZ7P.mount: Deactivated successfully.
Dec 26 20:43:55 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.y59WND.mount: Deactivated successfully.
Dec 26 20:44:25 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-fc8db1ba81fa8e4fc1efe6e962c6a1b1c22db3153fe3fcdba2f0a23f467ffdcd-runc.FvJAqQ.mount: Deactivated successfully.
Dec 26 20:44:33 homeassistant systemd[1]: run-docker-runtime\x2drunc-moby-b4f04e67d1a73ad21ea784e13b561666fda2150913c93d8915863e6e2274da4c-runc.SQZoXd.mount: Deactivated successfully.

System information

System Information

version core-2023.12.3
installation_type Home Assistant OS
dev false
hassio true
docker true
user root
virtualenv false
python_version 3.11.6
os_name Linux
os_version 6.1.63-haos
arch x86_64
timezone Europe/Rome
config_dir /config
Home Assistant Community Store
GitHub API ok
GitHub Content ok
GitHub Web ok
GitHub API Calls Remaining 4924
Installed Version 1.33.0
Stage running
Available Repositories 1368
Downloaded Repositories 35
AccuWeather
can_reach_server ok
remaining_requests 38
Home Assistant Supervisor
host_os Home Assistant OS 11.2
update_channel stable
supervisor_version supervisor-2023.12.0
agent_version 1.6.0
docker_version 24.0.7
disk_total 219.4 GB
disk_used 14.3 GB
healthy true
supported true
board generic-x86-64
supervisor_api ok
version_api ok
installed_addons Samba share (12.2.0), File editor (5.7.0), Let's Encrypt (5.0.8), Mosquitto broker (6.4.0), Terminal & SSH (9.8.1), AppDaemon (0.16.0), Uptime Kuma (0.10.1), Home Assistant Google Drive Backup (0.112.1), Node-RED (16.0.2), ESPHome (2023.12.5), MariaDB (2.6.1), phpMyAdmin (0.9.0), AdGuard Home (5.0.1), Advanced SSH & Web Terminal (17.0.0), Portainer (2.19.4-3)
Dashboards
dashboards 5
resources 29
views 32
mode storage
Recorder
oldest_recorder_run 18 dicembre 2023 alle ore 07:01
current_recorder_run 26 dicembre 2023 alle ore 14:46
estimated_db_size 38.69 MiB
database_engine mysql
database_version 10.6.12

Additional information

No response

@MatteGary MatteGary added the bug label Dec 26, 2023
@agners
Copy link
Member

agners commented Dec 27, 2023

Is there maybe more in the logs of Supervisor when the add-on shuts down? E.g. is it the Supervisor shutting down the add-on or is it the add-on itself which shuts itself down?

I'd guess an out-of-memory situation being the issue here. You can monitor memory usage on your system using https://www.home-assistant.io/integrations/systemmonitor?

@Impact123
Copy link

Impact123 commented Dec 27, 2023

These *.mount messages are likely related to docker's health checks and very normal/common: docker/for-linux#679.

@agners
Copy link
Member

agners commented Dec 27, 2023

Ah yeah right the health checks cause this. So yes, this is regular operation and unlikely the culprit of the issue you are seeing. The slowdown it is likely caused by something else.

@MatteGary
Copy link
Author

MatteGary commented Dec 27, 2023

Is there maybe more in the logs of Supervisor when the add-on shuts down? E.g. is it the Supervisor shutting down the add-on or is it the add-on itself which shuts itself down?

I'd guess an out-of-memory situation being the issue here. You can monitor memory usage on your system using https://www.home-assistant.io/integrations/systemmonitor?

Resource doesn’t seems to be an issue (right now RAM 15%, CPU 4%, disk 6%).
Regarding supervisor logs this is the current extract (issue is still happening)

23-12-27 13:16:11 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:16:11 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:16:11 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:17:58 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:17:59 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:18:25 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:18:25 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:18:30 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:18:30 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:18:30 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:20:20 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:20:28 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:20:44 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:20:44 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:20:50 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:20:50 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:20:50 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:22:41 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:22:58 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:23:04 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:23:04 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:23:09 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:23:09 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:23:09 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:24:58 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:25:02 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:25:24 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:25:24 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:25:29 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:25:29 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:25:29 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:27:23 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:27:29 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:27:43 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:27:43 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:27:48 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:27:48 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:27:48 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:29:34 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:29:59 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:30:02 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:30:02 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:30:07 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:30:07 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:30:07 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:31:55 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:31:59 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:32:22 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:32:22 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:32:27 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:32:27 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:32:27 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:32:45 INFO (MainThread) [supervisor.host.info] Updating local host information
23-12-27 13:32:46 INFO (MainThread) [supervisor.host.services] Updating service information
23-12-27 13:32:46 INFO (MainThread) [supervisor.host.network] Updating local network information
23-12-27 13:32:46 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
23-12-27 13:32:46 INFO (MainThread) [supervisor.host.manager] Host information reload completed
23-12-27 13:34:16 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:34:30 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:34:42 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:34:42 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:34:47 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:34:47 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:34:47 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:36:37 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:37:00 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:37:01 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:37:01 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:37:02 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
23-12-27 13:37:06 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:37:06 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:37:06 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:38:58 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:39:00 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:39:20 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:39:20 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:39:25 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:39:25 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:39:25 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:41:19 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:41:30 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:41:40 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:41:40 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:41:45 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:41:45 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:41:45 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:43:31 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:43:40 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:43:59 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:43:59 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:44:04 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:44:04 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:44:04 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
23-12-27 13:45:51 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:46:01 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:46:19 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
23-12-27 13:46:19 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed
23-12-27 13:46:24 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
23-12-27 13:46:24 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
23-12-27 13:46:24 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running 

@MatteGary
Copy link
Author

These *.mount messages are likely related to docker's health checks and very normal/common: docker/for-linux#679.

Only few users reported the “deactivated successfully” logs in the issue you referred. They make the logs useless. How can I be sure those are “good” logs, and how can I investigate more my issue?

@agners
Copy link
Member

agners commented Dec 27, 2023

23-12-27 13:46:19 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257

Sounds like NodeRED sends some invalid websocket message? 🤔

You filed this as a Home Assistant OS issue, what makes you belive this is a OS issue? I think a new version/configuration change of another component of the system made this error to start appear.

@agners
Copy link
Member

agners commented Dec 27, 2023

Actually:


23-12-27 13:46:01 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/core/state: 
23-12-27 13:46:19 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257

This are independent API calls from the Supervisor to the Core. It looks more like your Core is having troubles. I'd check it's logs. Also make sure to disable custom components to see if any of these cause the issue.

@MatteGary
Copy link
Author

23-12-27 13:46:19 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257

Sounds like NodeRED sends some invalid websocket message? 🤔

You filed this as a Home Assistant OS issue, what makes you belive this is a OS issue? I think a new version/configuration change of another component of the system made this error to start appear.

How can you say this logs are related to NodeRED?
These are the logs from NodeRED

27 Dec 15:59:55 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:02:09 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:02:14 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:02:14 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:04:29 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:04:34 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:04:34 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:06:48 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:06:53 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:06:53 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:09:08 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:09:13 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:09:13 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:11:27 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:11:32 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:11:32 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:13:47 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:13:52 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:13:52 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:16:06 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:16:11 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:16:11 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:18:25 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:18:30 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:18:30 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:20:45 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:20:50 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:20:50 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:23:04 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:23:09 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:23:09 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:25:23 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:25:28 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:25:28 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:27:43 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:27:48 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:27:48 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:30:03 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:30:08 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:30:08 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:32:22 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:32:27 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:32:27 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:34:42 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:34:47 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:34:47 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:37:01 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:37:06 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:37:06 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:39:21 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:39:26 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:39:26 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:41:40 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:41:45 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:41:45 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:43:59 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:44:04 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:44:04 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:46:19 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:46:24 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:46:24 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:48:39 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:48:44 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:48:44 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:50:58 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:51:03 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:51:03 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:53:18 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:53:23 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:53:23 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:55:37 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:55:42 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:55:42 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 16:57:57 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 16:58:02 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 16:58:02 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 17:00:16 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 17:00:21 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 17:00:21 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 17:02:35 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 17:02:40 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 17:02:40 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 17:04:55 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 17:05:00 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 17:05:00 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 17:07:14 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 17:07:19 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 17:07:19 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 17:09:34 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 17:09:39 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 17:09:39 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 17:11:53 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 17:11:58 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 17:11:58 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 17:14:12 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 17:14:17 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 17:14:17 - [info] [server:Home Assistant] Connected to http://supervisor/core
27 Dec 17:16:32 - [info] [server:Home Assistant] Connection closed to http://supervisor/core
27 Dec 17:16:37 - [info] [server:Home Assistant] Connecting to http://supervisor/core
27 Dec 17:16:37 - [info] [server:Home Assistant] Connected to http://supervisor/core

Regarding your other update, this is one line of log that sound suspicious in the HA Core log, but it doesn't give that many information

Logger: homeassistant.components.websocket_api.http.connection
Source: components/websocket_api/http.py:371
Integration: Home Assistant WebSocket API ([documentation](https://www.home-assistant.io/integrations/websocket_api), [issues](https://github.com/home-assistant/core/issues?q=is%3Aissue+is%3Aopen+label%3A%22integration%3A+websocket_api%22))
First occurred: 14:54:04 (63 occurrences)
Last logged: 17:16:32

[140110663262400] Supervisor from 172.30.32.2 (HomeAssistantSupervisor/2023.12.0 aiohttp/3.9.1 Python/3.11): Unexpected error inside websocket API
[140110670484416] Supervisor from 172.30.32.2 (HomeAssistantSupervisor/2023.12.0 aiohttp/3.9.1 Python/3.11): Unexpected error inside websocket API
[140110664319296] Supervisor from 172.30.32.2 (HomeAssistantSupervisor/2023.12.0 aiohttp/3.9.1 Python/3.11): Unexpected error inside websocket API
[140110664815296] Supervisor from 172.30.32.2 (HomeAssistantSupervisor/2023.12.0 aiohttp/3.9.1 Python/3.11): Unexpected error inside websocket API
[140110664686144] Supervisor from 172.30.32.2 (HomeAssistantSupervisor/2023.12.0 aiohttp/3.9.1 Python/3.11): Unexpected error inside websocket API
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/http.py", line 371, in async_handle
    msg = await wsock.receive()
          ^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/aiohttp/web_ws.py", line 472, in receive
    await self.pong(msg.data)
  File "/usr/local/lib/python3.11/site-packages/aiohttp/web_ws.py", line 329, in pong
    await self._writer.pong(message)
  File "/usr/local/lib/python3.11/site-packages/aiohttp/http_websocket.py", line 709, in pong
    await self._send_frame(message, WSMsgType.PONG)
  File "/usr/local/lib/python3.11/site-packages/aiohttp/http_websocket.py", line 682, in _send_frame
    self._write(header + message)
  File "/usr/local/lib/python3.11/site-packages/aiohttp/http_websocket.py", line 702, in _write
    raise ConnectionResetError("Cannot write to closing transport")
ConnectionResetError: Cannot write to closing transport

I filed this issue as HA OS issue because from my initial troubleshooting it looked to me that:

  • it affects all the system, not just core, but also other components running other than strictly HA
  • from what I understood about the "Deactivated succesfully", it was a log reporting the fact that a container has been restarted automatically after a failure. Therefore, if this behavior is spread across all containers like Core, it may affect the usability of the entire system since every other minute some container became unresponsive for several seconds.
  • i cannot find enough logs to point me towards core or other components.

I also have lots of error in the DNS logs

[ERROR] plugin/errors: 2 api.telegram.org. A: read udp 127.0.0.1:54966->127.0.0.1:5553: i/o timeout
[INFO] 127.0.0.1:56935 - 56426 "NS IN . udp 17 false 512" NOERROR - 0 30.000356464s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:46756 - 10080 "A IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000377257s
[INFO] 127.0.0.1:42784 - 10646 "AAAA IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000210217s
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: dial tcp 1.1.1.1:853: i/o timeout
[ERROR] plugin/errors: 2 api.telegram.org. A: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:59329 - 62409 "NS IN . udp 17 false 512" NOERROR - 0 30.000354031s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:42086 - 20324 "A IN token1.habackup.io. udp 47 true 2048" NOERROR - 0 30.000414969s
[ERROR] plugin/errors: 2 token1.habackup.io. A: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 172.30.32.1:54344 - 37945 "A IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.001720814s
[INFO] 172.30.32.1:54344 - 38587 "AAAA IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.001771542s
[ERROR] plugin/errors: 2 api.telegram.org. A: read udp 127.0.0.1:59246->127.0.0.1:5553: i/o timeout
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: read udp 127.0.0.1:41152->127.0.0.1:5553: i/o timeout
[INFO] 127.0.0.1:55716 - 19812 "NS IN . udp 17 false 512" NOERROR - 0 30.000384299s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:46310 - 10646 "AAAA IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.00029101s
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:41438 - 10080 "A IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000320959s
[ERROR] plugin/errors: 2 api.telegram.org. A: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:42351 - 40586 "NS IN . udp 17 false 512" NOERROR - 0 30.00027013s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 172.30.32.1:53696 - 61467 "AAAA IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.001810384s
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: read udp 127.0.0.1:54781->127.0.0.1:5553: i/o timeout
[INFO] 172.30.32.1:53696 - 60810 "A IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.001496471s
[ERROR] plugin/errors: 2 api.telegram.org. A: read udp 127.0.0.1:36376->127.0.0.1:5553: i/o timeout
[INFO] 127.0.0.1:58109 - 34612 "A IN habackup.io. udp 40 true 2048" NOERROR - 0 30.0003421s
[ERROR] plugin/errors: 2 habackup.io. A: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:52705 - 9071 "NS IN . udp 17 false 512" NOERROR - 0 30.000367756s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:43831 - 20324 "A IN token1.habackup.io. udp 47 true 2048" NOERROR - 0 30.000317448s
[ERROR] plugin/errors: 2 token1.habackup.io. A: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:33827 - 40774 "AAAA IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000365851s
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:45075 - 40067 "A IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000364813s
[ERROR] plugin/errors: 2 api.telegram.org. A: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 172.30.32.1:53696 - 61467 "AAAA IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.001661717s
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: read udp 127.0.0.1:57808->127.0.0.1:5553: i/o timeout
[INFO] 172.30.32.1:53696 - 60810 "A IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.001726212s
[ERROR] plugin/errors: 2 api.telegram.org. A: read udp 127.0.0.1:39538->127.0.0.1:5553: i/o timeout
[INFO] 127.0.0.1:52400 - 62498 "NS IN . udp 17 false 512" NOERROR - 0 30.000366929s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:55081 - 40774 "AAAA IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000285395s
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:51269 - 40067 "A IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000461199s
[ERROR] plugin/errors: 2 api.telegram.org. A: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:46329 - 12722 "NS IN . udp 17 false 512" NOERROR - 0 30.000306086s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:38873 - 34612 "A IN habackup.io. udp 40 true 2048" NOERROR - 0 30.000368015s
[ERROR] plugin/errors: 2 habackup.io. A: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 172.30.32.1:46895 - 27994 "A IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.001792801s
[ERROR] plugin/errors: 2 api.telegram.org. A: read udp 127.0.0.1:36604->127.0.0.1:5553: i/o timeout
[INFO] 172.30.32.1:46895 - 28611 "AAAA IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.001532449s
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: read udp 127.0.0.1:44688->127.0.0.1:5553: i/o timeout
[INFO] 127.0.0.1:59089 - 61679 "NS IN . udp 17 false 512" NOERROR - 0 30.000399337s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:50579 - 1907 "A IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000439182s
[ERROR] plugin/errors: 2 api.telegram.org. A: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:38245 - 2504 "AAAA IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000232712s
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:41332 - 41240 "NS IN . udp 17 false 512" NOERROR - 0 30.000342849s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 172.30.32.1:46895 - 28611 "AAAA IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.001692166s
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: read udp 127.0.0.1:58611->127.0.0.1:5553: i/o timeout
[INFO] 172.30.32.1:46895 - 27994 "A IN api.telegram.org. udp 34 false 512" NOERROR - 0 8.00171533s
[ERROR] plugin/errors: 2 api.telegram.org. A: read udp 127.0.0.1:46307->127.0.0.1:5553: i/o timeout
[INFO] 127.0.0.1:53150 - 34612 "A IN habackup.io. udp 40 true 2048" NOERROR - 0 30.000326907s
[ERROR] plugin/errors: 2 habackup.io. A: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:51182 - 32328 "NS IN . udp 17 false 512" NOERROR - 0 30.000333431s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:43931 - 2504 "AAAA IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.000363546s
[INFO] 127.0.0.1:35153 - 1907 "A IN api.telegram.org. udp 45 true 2048" NOERROR - 0 30.00044472s
[ERROR] plugin/errors: 2 api.telegram.org. A: dial tcp 1.1.1.1:853: i/o timeout
[ERROR] plugin/errors: 2 api.telegram.org. AAAA: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:41751 - 21161 "NS IN . udp 17 false 512" NOERROR - 0 30.000331811s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:38532 - 9884 "NS IN . udp 17 false 512" NOERROR - 0 30.000494464s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:54583 - 34612 "A IN habackup.io. udp 40 true 2048" NOERROR - 0 30.000432561s
[ERROR] plugin/errors: 2 habackup.io. A: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:49944 - 54148 "NS IN . udp 17 false 512" NOERROR - 0 30.000291847s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:55182 - 59846 "NS IN . udp 17 false 512" NOERROR - 0 30.000376923s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:38261 - 1353 "NS IN . udp 17 false 512" NOERROR - 0 30.000372486s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:59736 - 2727 "NS IN . udp 17 false 512" NOERROR - 0 30.000363159s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:42555 - 39451 "NS IN . udp 17 false 512" NOERROR - 0 30.00025636s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout
[INFO] 127.0.0.1:51961 - 20483 "NS IN . udp 17 false 512" NOERROR - 0 30.000277047s
[ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout
[INFO] 127.0.0.1:59580 - 49101 "PTR IN 137.136.99.36.in-addr.arpa. udp 55 true 2048" SERVFAIL qr,rd,ra 44 0.88346688s
[INFO] 172.30.32.1:36346 - 49101 "PTR IN 137.136.99.36.in-addr.arpa. udp 44 false 512" SERVFAIL qr,rd,ra 44 0.893668165s
[INFO] 172.30.32.1:36346 - 49101 "PTR IN 137.136.99.36.in-addr.arpa. udp 44 false 512" SERVFAIL qr,aa,rd,ra 44 0.000091418s
[INFO] 172.30.32.1:36346 - 49101 "PTR IN 137.136.99.36.in-addr.arpa. udp 44 false 512" SERVFAIL qr,aa,rd,ra 44 0.00007469s
[INFO] 127.0.0.1:59580 - 49101 "PTR IN 137.136.99.36.in-addr.arpa. udp 55 true 2048" SERVFAIL qr,aa,rd,ra 44 0.000110979s
[INFO] 172.30.32.1:36346 - 49101 "PTR IN 137.136.99.36.in-addr.arpa. udp 44 false 512" SERVFAIL qr,aa,rd,ra 44 4.249028162s
[INFO] 127.0.0.1:44356 - 49101 "PTR IN 137.136.99.36.in-addr.arpa. udp 55 true 2048" SERVFAIL qr,rd,ra 44 2.084173749s

I don't know if this is meant to be like this, or are a sign of some sort of failure.
I haven't added any new custom component in a while.

I think my point is: my HA OS installation is not working properly, there are evidence of issue in the logs but to me (I do this for a living) the logs are not enough to understand where is the issue and what caused it.

Do you know where to point me to get more information on the issue I'm experiencing?

Thanks

@MatteGary
Copy link
Author

In the end it may be related to a custom component... I added through HACS a component to connect to my ABB Solar Inverter, and it is not working (configuration error). After I removed it from the configuration, everything started to work fine again. I tried to add the configuration again, and the screen froze with a spinning wheel, the configuration was never added, and the usual logs reappeared.

This is the error thrown by the component

Logger: aiohttp.server
Source: /usr/local/lib/python3.11/site-packages/aiohttp/web_protocol.py:421
First occurred: 13:19:58 (1 occurrences)
Last logged: 13:19:58

Error handling request
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request
    resp = await request_handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/aiohttp/web_app.py", line 543, in _handle
    resp = await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/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 85, in security_filter_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/forwarded.py", line 100, in forwarded_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/request_context.py", line 28, in request_context_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/ban.py", line 80, in ban_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/auth.py", line 233, in auth_middleware
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/headers.py", line 31, in headers_middleware
    response = await handler(request)
               ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/view.py", line 149, in handle
    result = await handler(request, **request.match_info)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/decorators.py", line 63, in with_admin
    return await func(self, request, *args, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/config/config_entries.py", line 177, in post
    return await super().post(request, flow_id)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/http/data_validator.py", line 72, in wrapper
    result = await method(view, request, data, *args, **kwargs)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/data_entry_flow.py", line 110, in post
    result = await self._flow_mgr.async_configure(flow_id, data)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 293, in async_configure
    result = await self._async_handle_step(
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 389, in _async_handle_step
    result: FlowResult = await getattr(flow, method)(user_input)
                         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/config/custom_components/abb_powerone_pvi_sunspec/config_flow.py", line 90, in async_step_user
    uid = await self.test_connection(name, host, port, slave_id, base_addr, scan_interval)
          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/config/custom_components/abb_powerone_pvi_sunspec/config_flow.py", line 65, in test_connection
    self.hub_data = await self.hub.async_get_data()
                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/config/custom_components/abb_powerone_pvi_sunspec/api.py", line 185, in async_get_data
    self.read_sunspec_modbus_model_1()
  File "/config/custom_components/abb_powerone_pvi_sunspec/api.py", line 221, in read_sunspec_modbus_model_1
    read_model_1_data.registers, byteorder=Endian.BIG
    ^^^^^^^^^^^^^^^^^^^^^^^^^^^
AttributeError: 'ModbusIOException' object has no attribute 'registers'

Immediately followed by

Logger: homeassistant.components.websocket_api.http.connection
Source: components/websocket_api/http.py:371
Integration: Home Assistant WebSocket API (documentation, issues)
First occurred: 13:19:58 (1 occurrences)
Last logged: 13:19:58

[140110664856768] Supervisor from 172.30.32.2 (HomeAssistantSupervisor/2023.12.0 aiohttp/3.9.1 Python/3.11): Unexpected error inside websocket API
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/http.py", line 371, in async_handle
    msg = await wsock.receive()
          ^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/aiohttp/web_ws.py", line 472, in receive
    await self.pong(msg.data)
  File "/usr/local/lib/python3.11/site-packages/aiohttp/web_ws.py", line 329, in pong
    await self._writer.pong(message)
  File "/usr/local/lib/python3.11/site-packages/aiohttp/http_websocket.py", line 709, in pong
    await self._send_frame(message, WSMsgType.PONG)
  File "/usr/local/lib/python3.11/site-packages/aiohttp/http_websocket.py", line 682, in _send_frame
    self._write(header + message)
  File "/usr/local/lib/python3.11/site-packages/aiohttp/http_websocket.py", line 702, in _write
    raise ConnectionResetError("Cannot write to closing transport")
ConnectionResetError: Cannot write to closing transport

The entire UI froze for around 1 minute then everything started working again. Since it wasn't added correctly, the issue was momentarily. Before I had the configuration (it was working at some point) and I guess HA core kept retrying to setup the component, creating all the mess.

How can it be that the fail of an integration cause so much issue to the entire system?
I already opened a issue to the integration creator, but he claim that since the entire HA system froze, the issue is not inside its component but somewhere else.

@mnorrsken
Copy link

Try delete the custom component completely from the file system, restart, and try to re add it.

@MatteGary
Copy link
Author

Try delete the custom component completely from the file system, restart, and try to re add it.

Tried but it didn't helped, the issue is still there every time I re-enable the custom component. I guess I can close this issue and see if the owner of the component do something about it.
Thanks to everyone.

@MatteGary
Copy link
Author

Close since it was an issue related to a custom component, even if not sure on how to solve it.

sairon added a commit that referenced this issue Jul 24, 2024
Reduce verbosity from deactivated Docker mounts, triggered by the Docker
healthcheck. These messages do not carry any value for us and logs supplied by
users are often spammed mostly with these. Moreover, they sometimes cause
confusion that something is wrong, see for example #3021.

Unfortunately, it's not possible to use LogFilterPatterns= here, because it's
not applied to these messages, as explicitly said in the docs:

>  Filtering is based on the unit for which LogFilterPatterns= is defined, >
meaning log messages coming from systemd(1) about the unit are not taken > into
account.

runc 1.2.0 supposedly should fix this, but it's unclear when it would be
available, so let's stick to this solution (reducing verbosity from debug to
notice for all units `run-docker-*.mount`) for the time being.
sairon added a commit that referenced this issue Jul 24, 2024
Reduce verbosity from deactivated Docker mounts, triggered by the Docker
healthcheck. These messages do not carry any value for us and logs supplied by
users are often spammed mostly with these. Moreover, they sometimes cause
confusion that something is wrong, see for example #3021.

Unfortunately, it's not possible to use LogFilterPatterns= here, because it's
not applied to these messages, as explicitly said in the docs:

  Filtering is based on the unit for which LogFilterPatterns= is defined
  meaning log messages coming from systemd(1) about the unit are not taken
  into account.

runc 1.2.0 supposedly should fix this, but it's unclear when it would be
available, so let's stick to this solution (reducing verbosity from debug to
notice for all units `run-docker-*.mount`) for the time being.
sairon added a commit that referenced this issue Jul 24, 2024
Reduce verbosity from deactivated Docker mounts, triggered by the Docker
healthcheck. These messages do not carry any value for us and logs supplied by
users are often spammed mostly with these. Moreover, they sometimes cause
confusion that something is wrong, see for example #3021.

Unfortunately, it's not possible to use LogFilterPatterns= here, because it's
not applied to these messages, as explicitly said in the docs:

  Filtering is based on the unit for which LogFilterPatterns= is defined
  meaning log messages coming from systemd(1) about the unit are not taken
  into account.

runc 1.2.0 supposedly should fix this, but it's unclear when it would be
available, so let's stick to this solution (reducing verbosity from debug to
notice for all units `run-docker-*.mount`) for the time being.
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

4 participants