home-assistant / os-agent

Daemon allowing to control OS features through D-Bus
Apache License 2.0
281 stars 74 forks source link

Campatibility issue with RaspberryMatic #107

Closed ciB89 closed 1 year ago

ciB89 commented 2 years ago

Describe the issue you are experiencing

I have restarted my host (home assistant supervised) after updating os-agent to latest version (1.4.1) und suddenly the addon would not work properly anymore.

Logs show:

Starting multimacd: ...........ERROR: couldn't setup /dev/mmd_* devices
Starting rfd: ....................ERROR

I tried sudo service pivccu-dkms start and sudo modprobe eq3_char_loop again, now the logs of the addon changed a little bit:

Starting multimacd: ..........ERROR: couldn't setup /dev/eq3loop
Starting rfd: ERROR: /dev/mmd_bidcos missing, no BidCos-RF hardware found
Starting HMIPServer: ERROR: /dev/mmd_hmip missing

Describe the behavior you expected

Add-on working normally without throwing errors during startup.

Steps to reproduce the issue

-upgrade os-agent to 1.4.1 -start the addon with a hmip device

What is the version this bug report is based on?

3.65.11.20221005 of addon, 1.4.1 of os-agent

Which base platform are you running?

rpi4 (RaspberryPi4)

Which HomeMatic/homematicIP radio module are you using?

HmIP-RFUSB

Anything in the logs that might be useful for us?

Supervisor logs before rerunning modprobe:

22-10-27 01:43:33 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/mmd_bidcos - None
22-10-27 01:43:33 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/mmd_hmip - None
22-10-27 01:43:35 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/eq3loop - None
22-10-27 01:43:43 INFO (SyncWorker_0) [supervisor.docker.interface] Cleaning addon_de838cd8_raspberrymatic application
22-10-27 01:43:43 WARNING (SyncWorker_6) [supervisor.docker.addon] RaspberryMatic CCU running with disabled protected mode!
22-10-27 01:43:44 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-10-27 01:43:44 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/40: 
22-10-27 01:43:44 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-10-27 01:43:44 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/40: 
22-10-27 01:43:44 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-10-27 01:43:44 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/69: 
22-10-27 01:43:46 INFO (SyncWorker_6) [supervisor.docker.addon] Starting Docker add-on ghcr.io/jens-maus/raspberrymatic with version 3.65.11.20221005
22-10-27 01:44:14 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/eq3loop - None
22-10-27 01:44:15 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 241:0 rwm' for device /dev/eq3loop to addon_de838cd8_raspberrymatic

Supervisor logs after rerunning modprobe:

22-10-27 02:13:31 INFO (SyncWorker_7) [supervisor.docker.addon] Starting Docker add-on ghcr.io/jens-maus/raspberrymatic with version 3.65.11.20221005
22-10-27 02:13:59 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_hmip - None
22-10-27 02:13:59 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_bidcos - None
22-10-27 02:14:00 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 241:1 rwm' for device /dev/mmd_hmip to addon_de838cd8_raspberrymatic
22-10-27 02:14:00 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 241:2 rwm' for device /dev/mmd_bidcos to addon_de838cd8_raspberrymatic

Additional information

Downgrading back to version 1.2.2 "fixed" the issue.

Link to Raspberrymatic issue: https://github.com/jens-maus/RaspberryMatic/issues/2019

agners commented 2 years ago

Hm, the most likely cause is the added support for permission updates via run with https://github.com/home-assistant/os-agent/pull/92. In theory, the behavior should not change on Supervised.

@ciB89 can you try 1.3.0 and see if that fails too? Also, is /sys/fs/cgroup/cgroup.controllers present in your case?

Maybe you have to rerun the Supervised installer, specifically the switch to cgroups v1 needs to execute properly: https://github.com/home-assistant/supervised-installer/blob/0779e8d745d59d9c1cfbc9087071b7cfd489850a/homeassistant-supervised/DEBIAN/postinst#L143-L162

/cc @jens-maus

ciB89 commented 2 years ago

1.3.0 has the same issue. /sys/fs/cgroup/cgroup.controllers is not present. I had only manually added systemd.unified_cgroup_hierarchy=false to my cmdline.txt beforehand, so that's not the issue.

Btw, since I come from Raspbian, this version of the installer script would not have worked since the cmdline.txt is located at /boot/cmdline.txt instead of /boot/firmware/cmdline.txt

oetken commented 2 years ago

Same issue here. Works up to 1.2.2.

Rainerlan commented 1 year ago

Yes, I can see the same problem

ciB89 commented 1 year ago

Since I am not running Debian but PiOS, I presume that I won't get any support on this matter. Is anyone else who is having this issue running Debian or Home Assistant OS?

oetken commented 1 year ago

@ciB89 running Debian bullseye here.

Rainerlan commented 1 year ago

Running Raspberry Pi OS (64-bit), which actually is a Debian 11 (bullseye)

ciB89 commented 1 year ago

Running Raspberry Pi OS (64-bit), which actually is a Debian 11 (bullseye)

PiOS it's nevertheless not officially supported.

ciB89 commented 1 year ago

Since there has not been any progress on this, is there anything we can do to debug this?

agners commented 1 year ago

I've just reviewed the difference between 1.2.2 and 1.3.0, and it really should behave the same as long as /sys/fs/cgroup/cgroup.controllers is not present.

Can you double check if /sys/fs/cgroup/cgroup.controllers is indeed not present? Also, try downgrading 1.2.2 and see if that indeed fixes the problem (to rule out any update of other component did not introduce the issue)?

agners commented 1 year ago

Can you also provide the logs of the OS Agent (journalctl -u haos-agent.service)?

ciB89 commented 1 year ago

Can you double check if /sys/fs/cgroup/cgroup.controllers is indeed not present? Also, try downgrading 1.2.2 and see if that indeed fixes the problem (to rule out any update of other component did not introduce the issue)?

As already has been mentioned, /sys/fs/cgroup/cgroup.controllers is not present and downgrading works, so the issue starts with 1.3.0

Im am currently not home, so I cannot provide the output till next week.

agners commented 1 year ago

As already has been mentioned

I know, that is why I said "double check" :smile: It would explain it all. Because if that file is not present, the very same code is used as in 1.2.2, so I am really confused why it is no longer working for you.

Maybe the logs help to better understand. I am waiting for the logs.

ciB89 commented 1 year ago

As already has been mentioned

I know, that is why I said "double check" 😄 It would explain it all. Because if that file is not present, the very same code is used as in 1.2.2, so I am really confused why it is no longer working for you.

Maybe the logs help to better understand. I am waiting for the logs.

double checked, still not present ;)

Here is the log output before upgrading (running on 1.2.2):

-- Journal begins at Sun 2023-02-05 02:30:46 CET, ends at Tue 2023-02-07 12:10:24 CET. --
Feb 05 14:26:59 raspberrypi os-agent[444]: INFO: 2023/02/05 14:26:59 apparmor.go:44: Load AppArmor profile '/usr/share/hassio/apparmor/db21ed7f_readarr_nas'.
Feb 05 14:26:59 raspberrypi os-agent[444]: INFO: 2023/02/05 14:26:59 apparmor.go:52: Load profile '/usr/share/hassio/apparmor/db21ed7f_readarr_nas':
Feb 05 19:36:29 raspberrypi os-agent[444]: INFO: 2023/02/05 19:36:29 cgroup.go:51: Permission '3ff12e21f149b5e4ef73b75bd5ab415a8ac24b24a00e880ec44b2ef66fc7bf21', granted for Container 'c 241:1 rwm'
Feb 05 19:36:30 raspberrypi os-agent[444]: INFO: 2023/02/05 19:36:30 cgroup.go:51: Permission '3ff12e21f149b5e4ef73b75bd5ab415a8ac24b24a00e880ec44b2ef66fc7bf21', granted for Container 'c 241:2 rwm'
Feb 06 14:12:06 raspberrypi os-agent[444]: INFO: 2023/02/06 14:12:06 cgroup.go:51: Permission 'a7b3fc35147a0f4af295817e29f2a19d3ebdb22d130417cc96350e0c98bfaf55', granted for Container 'c 241:0 rwm'
Feb 06 14:12:09 raspberrypi os-agent[444]: INFO: 2023/02/06 14:12:09 cgroup.go:51: Permission 'a7b3fc35147a0f4af295817e29f2a19d3ebdb22d130417cc96350e0c98bfaf55', granted for Container 'c 241:1 rwm'
Feb 06 14:12:09 raspberrypi os-agent[444]: INFO: 2023/02/06 14:12:09 cgroup.go:51: Permission 'a7b3fc35147a0f4af295817e29f2a19d3ebdb22d130417cc96350e0c98bfaf55', granted for Container 'c 241:2 rwm'
Feb 06 14:24:44 raspberrypi os-agent[444]: INFO: 2023/02/06 14:24:44 cgroup.go:51: Permission 'db0ed26b5894a4294b4027c31074753ff83b15c147269fe4723f52d96b301801', granted for Container 'c 241:0 rwm'
Feb 06 14:24:47 raspberrypi os-agent[444]: INFO: 2023/02/06 14:24:47 cgroup.go:51: Permission 'db0ed26b5894a4294b4027c31074753ff83b15c147269fe4723f52d96b301801', granted for Container 'c 241:1 rwm'
Feb 06 14:24:47 raspberrypi os-agent[444]: INFO: 2023/02/06 14:24:47 cgroup.go:51: Permission 'db0ed26b5894a4294b4027c31074753ff83b15c147269fe4723f52d96b301801', granted for Container 'c 241:2 rwm'
Feb 07 03:44:11 raspberrypi os-agent[444]: INFO: 2023/02/07 03:44:11 cgroup.go:51: Permission '101b805f6e13b5a8ec5fd509a435cbf28d0d3c3bee038347fff83daa25e90c2a', granted for Container 'c 241:1 rwm'
Feb 07 03:44:11 raspberrypi os-agent[444]: INFO: 2023/02/07 03:44:11 cgroup.go:51: Permission '101b805f6e13b5a8ec5fd509a435cbf28d0d3c3bee038347fff83daa25e90c2a', granted for Container 'c 241:2 rwm'
Feb 07 04:10:54 raspberrypi os-agent[444]: INFO: 2023/02/07 04:10:54 cgroup.go:51: Permission '92099aebbe88a809a156e7f1c63b70b4c04e6c467f1a57905456acd5a9408cab', granted for Container 'c 241:1 rwm'
Feb 07 04:10:54 raspberrypi os-agent[444]: INFO: 2023/02/07 04:10:54 cgroup.go:51: Permission '92099aebbe88a809a156e7f1c63b70b4c04e6c467f1a57905456acd5a9408cab', granted for Container 'c 241:2 rwm'
Feb 07 04:20:14 raspberrypi os-agent[444]: INFO: 2023/02/07 04:20:14 apparmor.go:44: Load AppArmor profile '/usr/share/hassio/apparmor/db21ed7f_radarr_nas'.
Feb 07 04:20:14 raspberrypi os-agent[444]: INFO: 2023/02/07 04:20:14 apparmor.go:52: Load profile '/usr/share/hassio/apparmor/db21ed7f_radarr_nas':
Feb 07 05:03:15 raspberrypi os-agent[444]: INFO: 2023/02/07 05:03:15 cgroup.go:51: Permission '7601702ac608c3eb267d6ba9a14b940e94d4c381932254670e6c352a6e195847', granted for Container 'c 241:1 rwm'
Feb 07 05:03:15 raspberrypi os-agent[444]: INFO: 2023/02/07 05:03:15 cgroup.go:51: Permission '7601702ac608c3eb267d6ba9a14b940e94d4c381932254670e6c352a6e195847', granted for Container 'c 241:2 rwm'

After upgrading to 1.4.1:

-- Journal begins at Sun 2023-02-05 02:30:46 CET, ends at Tue 2023-02-07 12:12:37 CET. --
Feb 05 14:26:59 raspberrypi os-agent[444]: INFO: 2023/02/05 14:26:59 apparmor.go:44: Load AppArmor profile '/usr/share/hassio/apparmor/db21ed7f_readarr_nas'.
Feb 05 14:26:59 raspberrypi os-agent[444]: INFO: 2023/02/05 14:26:59 apparmor.go:52: Load profile '/usr/share/hassio/apparmor/db21ed7f_readarr_nas':
Feb 05 19:36:29 raspberrypi os-agent[444]: INFO: 2023/02/05 19:36:29 cgroup.go:51: Permission '3ff12e21f149b5e4ef73b75bd5ab415a8ac24b24a00e880ec44b2ef66fc7bf21', granted for Container 'c 241:1 rwm'
Feb 05 19:36:30 raspberrypi os-agent[444]: INFO: 2023/02/05 19:36:30 cgroup.go:51: Permission '3ff12e21f149b5e4ef73b75bd5ab415a8ac24b24a00e880ec44b2ef66fc7bf21', granted for Container 'c 241:2 rwm'
Feb 06 14:12:06 raspberrypi os-agent[444]: INFO: 2023/02/06 14:12:06 cgroup.go:51: Permission 'a7b3fc35147a0f4af295817e29f2a19d3ebdb22d130417cc96350e0c98bfaf55', granted for Container 'c 241:0 rwm'
Feb 06 14:12:09 raspberrypi os-agent[444]: INFO: 2023/02/06 14:12:09 cgroup.go:51: Permission 'a7b3fc35147a0f4af295817e29f2a19d3ebdb22d130417cc96350e0c98bfaf55', granted for Container 'c 241:1 rwm'
Feb 06 14:12:09 raspberrypi os-agent[444]: INFO: 2023/02/06 14:12:09 cgroup.go:51: Permission 'a7b3fc35147a0f4af295817e29f2a19d3ebdb22d130417cc96350e0c98bfaf55', granted for Container 'c 241:2 rwm'
Feb 06 14:24:44 raspberrypi os-agent[444]: INFO: 2023/02/06 14:24:44 cgroup.go:51: Permission 'db0ed26b5894a4294b4027c31074753ff83b15c147269fe4723f52d96b301801', granted for Container 'c 241:0 rwm'
Feb 06 14:24:47 raspberrypi os-agent[444]: INFO: 2023/02/06 14:24:47 cgroup.go:51: Permission 'db0ed26b5894a4294b4027c31074753ff83b15c147269fe4723f52d96b301801', granted for Container 'c 241:1 rwm'
Feb 06 14:24:47 raspberrypi os-agent[444]: INFO: 2023/02/06 14:24:47 cgroup.go:51: Permission 'db0ed26b5894a4294b4027c31074753ff83b15c147269fe4723f52d96b301801', granted for Container 'c 241:2 rwm'
Feb 07 03:44:11 raspberrypi os-agent[444]: INFO: 2023/02/07 03:44:11 cgroup.go:51: Permission '101b805f6e13b5a8ec5fd509a435cbf28d0d3c3bee038347fff83daa25e90c2a', granted for Container 'c 241:1 rwm'
Feb 07 03:44:11 raspberrypi os-agent[444]: INFO: 2023/02/07 03:44:11 cgroup.go:51: Permission '101b805f6e13b5a8ec5fd509a435cbf28d0d3c3bee038347fff83daa25e90c2a', granted for Container 'c 241:2 rwm'
Feb 07 04:10:54 raspberrypi os-agent[444]: INFO: 2023/02/07 04:10:54 cgroup.go:51: Permission '92099aebbe88a809a156e7f1c63b70b4c04e6c467f1a57905456acd5a9408cab', granted for Container 'c 241:1 rwm'
Feb 07 04:10:54 raspberrypi os-agent[444]: INFO: 2023/02/07 04:10:54 cgroup.go:51: Permission '92099aebbe88a809a156e7f1c63b70b4c04e6c467f1a57905456acd5a9408cab', granted for Container 'c 241:2 rwm'
Feb 07 04:20:14 raspberrypi os-agent[444]: INFO: 2023/02/07 04:20:14 apparmor.go:44: Load AppArmor profile '/usr/share/hassio/apparmor/db21ed7f_radarr_nas'.
Feb 07 04:20:14 raspberrypi os-agent[444]: INFO: 2023/02/07 04:20:14 apparmor.go:52: Load profile '/usr/share/hassio/apparmor/db21ed7f_radarr_nas':
Feb 07 05:03:15 raspberrypi os-agent[444]: INFO: 2023/02/07 05:03:15 cgroup.go:51: Permission '7601702ac608c3eb267d6ba9a14b940e94d4c381932254670e6c352a6e195847', granted for Container 'c 241:1 rwm'
Feb 07 05:03:15 raspberrypi os-agent[444]: INFO: 2023/02/07 05:03:15 cgroup.go:51: Permission '7601702ac608c3eb267d6ba9a14b940e94d4c381932254670e6c352a6e195847', granted for Container 'c 241:2 rwm'
Feb 07 12:12:13 raspberrypi systemd[1]: Stopping Home Assistant OS Agent...
Feb 07 12:12:13 raspberrypi systemd[1]: haos-agent.service: Succeeded.
Feb 07 12:12:13 raspberrypi systemd[1]: Stopped Home Assistant OS Agent.
Feb 07 12:12:18 raspberrypi systemd[1]: Starting Home Assistant OS Agent...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 main.go:33: Start OS-Agent 1.4.1
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 main.go:123: Exposing object /io/hass/os with interface io.hass.os ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 main.go:66: Listening on service io.hass.os ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: WARNING: 2023/02/07 12:12:18 datadisk.go:97: Can't find a data mount!
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 datadisk.go:145: Exposing object /io/hass/os/DataDisk with interface io.hass.os.DataDisk ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 system.go:132: Exposing object /io/hass/os/System with interface io.hass.os.System ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 apparmor.go:116: Exposing object /io/hass/os/AppArmor with interface io.hass.os.AppArmor ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 cgroup.go:136: Exposing object /io/hass/os/CGroup with interface io.hass.os.CGroup ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 boards.go:68: Exposing object /io/hass/os/Boards with interface io.hass.os.Boards ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 supervised.go:47: Exposing object /io/hass/os/Boards/Supervised with interface io.hass.os.Boards.Supervised ...
Feb 07 12:12:18 raspberrypi systemd[1]: Started Home Assistant OS Agent.

Interestingly, RaspberryMatic kept on running without any issues after upgrading to 1.4.1, only after restarting the addon/container, I had the issue. I check the logs of OS Agent again after restarting RaspberryMatic:

-- Journal begins at Sun 2023-02-05 02:30:46 CET, ends at Tue 2023-02-07 12:15:20 CET. --
Feb 05 14:26:59 raspberrypi os-agent[444]: INFO: 2023/02/05 14:26:59 apparmor.go:44: Load AppArmor profile '/usr/share/hassio/apparmor/db21ed7f_readarr_nas'.
Feb 05 14:26:59 raspberrypi os-agent[444]: INFO: 2023/02/05 14:26:59 apparmor.go:52: Load profile '/usr/share/hassio/apparmor/db21ed7f_readarr_nas':
Feb 05 19:36:29 raspberrypi os-agent[444]: INFO: 2023/02/05 19:36:29 cgroup.go:51: Permission '3ff12e21f149b5e4ef73b75bd5ab415a8ac24b24a00e880ec44b2ef66fc7bf21', granted for Container 'c 241:1 rwm'
Feb 05 19:36:30 raspberrypi os-agent[444]: INFO: 2023/02/05 19:36:30 cgroup.go:51: Permission '3ff12e21f149b5e4ef73b75bd5ab415a8ac24b24a00e880ec44b2ef66fc7bf21', granted for Container 'c 241:2 rwm'
Feb 06 14:12:06 raspberrypi os-agent[444]: INFO: 2023/02/06 14:12:06 cgroup.go:51: Permission 'a7b3fc35147a0f4af295817e29f2a19d3ebdb22d130417cc96350e0c98bfaf55', granted for Container 'c 241:0 rwm'
Feb 06 14:12:09 raspberrypi os-agent[444]: INFO: 2023/02/06 14:12:09 cgroup.go:51: Permission 'a7b3fc35147a0f4af295817e29f2a19d3ebdb22d130417cc96350e0c98bfaf55', granted for Container 'c 241:1 rwm'
Feb 06 14:12:09 raspberrypi os-agent[444]: INFO: 2023/02/06 14:12:09 cgroup.go:51: Permission 'a7b3fc35147a0f4af295817e29f2a19d3ebdb22d130417cc96350e0c98bfaf55', granted for Container 'c 241:2 rwm'
Feb 06 14:24:44 raspberrypi os-agent[444]: INFO: 2023/02/06 14:24:44 cgroup.go:51: Permission 'db0ed26b5894a4294b4027c31074753ff83b15c147269fe4723f52d96b301801', granted for Container 'c 241:0 rwm'
Feb 06 14:24:47 raspberrypi os-agent[444]: INFO: 2023/02/06 14:24:47 cgroup.go:51: Permission 'db0ed26b5894a4294b4027c31074753ff83b15c147269fe4723f52d96b301801', granted for Container 'c 241:1 rwm'
Feb 06 14:24:47 raspberrypi os-agent[444]: INFO: 2023/02/06 14:24:47 cgroup.go:51: Permission 'db0ed26b5894a4294b4027c31074753ff83b15c147269fe4723f52d96b301801', granted for Container 'c 241:2 rwm'
Feb 07 03:44:11 raspberrypi os-agent[444]: INFO: 2023/02/07 03:44:11 cgroup.go:51: Permission '101b805f6e13b5a8ec5fd509a435cbf28d0d3c3bee038347fff83daa25e90c2a', granted for Container 'c 241:1 rwm'
Feb 07 03:44:11 raspberrypi os-agent[444]: INFO: 2023/02/07 03:44:11 cgroup.go:51: Permission '101b805f6e13b5a8ec5fd509a435cbf28d0d3c3bee038347fff83daa25e90c2a', granted for Container 'c 241:2 rwm'
Feb 07 04:10:54 raspberrypi os-agent[444]: INFO: 2023/02/07 04:10:54 cgroup.go:51: Permission '92099aebbe88a809a156e7f1c63b70b4c04e6c467f1a57905456acd5a9408cab', granted for Container 'c 241:1 rwm'
Feb 07 04:10:54 raspberrypi os-agent[444]: INFO: 2023/02/07 04:10:54 cgroup.go:51: Permission '92099aebbe88a809a156e7f1c63b70b4c04e6c467f1a57905456acd5a9408cab', granted for Container 'c 241:2 rwm'
Feb 07 04:20:14 raspberrypi os-agent[444]: INFO: 2023/02/07 04:20:14 apparmor.go:44: Load AppArmor profile '/usr/share/hassio/apparmor/db21ed7f_radarr_nas'.
Feb 07 04:20:14 raspberrypi os-agent[444]: INFO: 2023/02/07 04:20:14 apparmor.go:52: Load profile '/usr/share/hassio/apparmor/db21ed7f_radarr_nas':
Feb 07 05:03:15 raspberrypi os-agent[444]: INFO: 2023/02/07 05:03:15 cgroup.go:51: Permission '7601702ac608c3eb267d6ba9a14b940e94d4c381932254670e6c352a6e195847', granted for Container 'c 241:1 rwm'
Feb 07 05:03:15 raspberrypi os-agent[444]: INFO: 2023/02/07 05:03:15 cgroup.go:51: Permission '7601702ac608c3eb267d6ba9a14b940e94d4c381932254670e6c352a6e195847', granted for Container 'c 241:2 rwm'
Feb 07 12:12:13 raspberrypi systemd[1]: Stopping Home Assistant OS Agent...
Feb 07 12:12:13 raspberrypi systemd[1]: haos-agent.service: Succeeded.
Feb 07 12:12:13 raspberrypi systemd[1]: Stopped Home Assistant OS Agent.
Feb 07 12:12:18 raspberrypi systemd[1]: Starting Home Assistant OS Agent...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 main.go:33: Start OS-Agent 1.4.1
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 main.go:123: Exposing object /io/hass/os with interface io.hass.os ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 main.go:66: Listening on service io.hass.os ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: WARNING: 2023/02/07 12:12:18 datadisk.go:97: Can't find a data mount!
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 datadisk.go:145: Exposing object /io/hass/os/DataDisk with interface io.hass.os.DataDisk ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 system.go:132: Exposing object /io/hass/os/System with interface io.hass.os.System ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 apparmor.go:116: Exposing object /io/hass/os/AppArmor with interface io.hass.os.AppArmor ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 cgroup.go:136: Exposing object /io/hass/os/CGroup with interface io.hass.os.CGroup ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 boards.go:68: Exposing object /io/hass/os/Boards with interface io.hass.os.Boards ...
Feb 07 12:12:18 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:12:18 supervised.go:47: Exposing object /io/hass/os/Boards/Supervised with interface io.hass.os.Boards.Supervised ...
Feb 07 12:12:18 raspberrypi systemd[1]: Started Home Assistant OS Agent.
Feb 07 12:14:36 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:14:36 cgroup.go:66: Successfully called runc for '9c70ac0f9b5e11942a6d4a32838553893766a3b597511bad5bea2b66d3c571d1', output
Feb 07 12:14:36 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:14:36 cgroup.go:69: Permission '9c70ac0f9b5e11942a6d4a32838553893766a3b597511bad5bea2b66d3c571d1', granted for Container 'c 241:0 rwm' via runc
ciB89 commented 1 year ago

In case it has any relevance (which I doubt), the container ID of RaspberryMatic is: d604ac4db1db63d0415df9ac5483e1443d08eeac27f930606b90972e5e94ec9d

agners commented 1 year ago

Hm, it seems to use runc. Something must be wrong with the cgroups detection :thinking:

Feb 07 12:14:36 raspberrypi os-agent[10371]: INFO: 2023/02/07 12:14:36 cgroup.go:69: Permission '9c70ac0f9b5e11942a6d4a32838553893766a3b597511bad5bea2b66d3c571d1', granted for Container 'c 241:0 rwm' via runc
ciB89 commented 1 year ago

Updated to 1.5.1 and can confirm there aren't any issues now anymore.