operating-system: QEMU agent command 'guest-fsfreeze-freeze' issue with HAOS 12.1

Describe the issue you are experiencing

With HAOS 12.1 when trying to snapshot the VM running HAOS for backup it failed with the error; “unable to execute QEMU agent command ‘guest-fsfreeze-freeze’: fsfreeze hook has failed with status 1”. Tried shutting down the VM, starting it and trying the backup again but got the same error. The QEMU agent is running correctly in general. After restoring a backup of the VM so it is back to HAOS 12.0 the snapshot and therefore backup run fine again.

The hypervisor is KVM with in the Unraid OS.

What operating system image do you use?

ova (for Virtual Machines)

What version of Home Assistant Operating System is installed?

12.1

Did you upgrade the Operating System.

Yes

Steps to reproduce the issue

1.Upgrade to 12.1 2.Try to to back up the VM via the VM backup plugin in Unraid 3. …

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

I don't think so but here is the out put only a minute or so after the issue occurs;

24-03-14 19:34:02 WARNING (SyncWorker_0) [supervisor.host.sound] Can't update PulseAudio data: Failed to connect to pulseaudio server
24-03-14 19:34:02 WARNING (MainThread) [supervisor.host.network] Requested to update interface enp1s0 which does not exist or is disabled.
24-03-14 19:34:02 INFO (MainThread) [supervisor.host.apparmor] Loading AppArmor Profiles: {'hassio-supervisor'}
24-03-14 19:34:02 INFO (MainThread) [supervisor.mounts.manager] Initializing all user-configured mounts
24-03-14 19:34:02 INFO (MainThread) [supervisor.docker.monitor] Started docker events monitor
24-03-14 19:34:02 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
24-03-14 19:34:03 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-cli with version 2024.03.1
24-03-14 19:34:03 INFO (MainThread) [supervisor.plugins.cli] Starting CLI plugin
24-03-14 19:34:03 INFO (MainThread) [supervisor.docker.cli] Starting CLI ghcr.io/home-assistant/amd64-hassio-cli with version 2024.03.1 - 172.30.32.5
24-03-14 19:34:03 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-dns with version 2024.03.0
24-03-14 19:34:03 INFO (MainThread) [supervisor.plugins.dns] Starting CoreDNS plugin
24-03-14 19:34:03 INFO (MainThread) [supervisor.docker.dns] Starting DNS ghcr.io/home-assistant/amd64-hassio-dns with version 2024.03.0 - 172.30.32.3
24-03-14 19:34:03 INFO (MainThread) [supervisor.plugins.dns] Updated /etc/resolv.conf
24-03-14 19:34:03 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-audio with version 2023.12.0
24-03-14 19:34:03 INFO (MainThread) [supervisor.plugins.audio] Starting Audio plugin
24-03-14 19:34:04 INFO (MainThread) [supervisor.docker.audio] Starting Audio ghcr.io/home-assistant/amd64-hassio-audio with version 2023.12.0 - 172.30.32.4
24-03-14 19:34:04 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-observer with version 2023.06.0
24-03-14 19:34:04 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-multicast with version 2024.03.0
24-03-14 19:34:04 INFO (MainThread) [supervisor.plugins.multicast] Starting Multicast plugin
24-03-14 19:34:04 INFO (MainThread) [supervisor.docker.multicast] Starting Multicast ghcr.io/home-assistant/amd64-hassio-multicast with version 2024.03.0 - Host
24-03-14 19:34:04 INFO (MainThread) [supervisor.homeassistant.secrets] Loaded 1 Home Assistant secrets
24-03-14 19:34:04 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/qemux86-64-homeassistant with version 2024.3.1
24-03-14 19:34:04 INFO (MainThread) [supervisor.os.manager] Detect Home Assistant Operating System 12.1 / BootSlot A
24-03-14 19:34:04 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/5c53de3b repository
24-03-14 19:34:04 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/core repository
24-03-14 19:34:04 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/f4f71350 repository
24-03-14 19:34:04 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/a0d7b954 repository
24-03-14 19:34:05 INFO (MainThread) [supervisor.store] Loading add-ons from store: 78 all - 78 new - 0 remove
24-03-14 19:34:05 INFO (MainThread) [supervisor.addons.manager] Found 6 installed add-ons
24-03-14 19:34:05 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/node-red/amd64 with version 17.0.9
24-03-14 19:34:05 INFO (MainThread) [supervisor.docker.interface] Attaching to homeassistant/amd64-addon-ssh with version 9.10.0
24-03-14 19:34:05 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/vscode/amd64 with version 5.15.0
24-03-14 19:34:05 INFO (MainThread) [supervisor.docker.interface] Attaching to f4f71350/amd64-addon-ewelink_smart_home_slug with version 1.4.3
24-03-14 19:34:05 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/esphome/esphome-hassio with version 2024.2.2
24-03-14 19:34:05 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/zwave-js-ui/amd64 with version 3.4.1
24-03-14 19:34:05 INFO (MainThread) [supervisor.backups.manager] Found 46 backup files
24-03-14 19:34:06 INFO (MainThread) [supervisor.discovery] Loaded 2 messages
24-03-14 19:34:06 INFO (MainThread) [supervisor.ingress] Loaded 0 ingress sessions
24-03-14 19:34:06 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state setup
24-03-14 19:34:06 INFO (MainThread) [supervisor.resolution.check] System checks complete
24-03-14 19:34:06 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state setup
24-03-14 19:34:06 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-03-14 19:34:06 INFO (MainThread) [supervisor.jobs] 'ResolutionFixup.run_autofix' blocked from execution, system is not running - setup
24-03-14 19:34:06 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state setup
24-03-14 19:34:06 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-03-14 19:34:06 INFO (MainThread) [__main__] Running Supervisor
24-03-14 19:34:06 INFO (MainThread) [supervisor.os.manager] Rauc: A - marked slot kernel.0 as good
24-03-14 19:34:06 INFO (MainThread) [supervisor.addons.manager] Phase 'initialize' starting 0 add-ons
24-03-14 19:34:06 INFO (MainThread) [supervisor.addons.manager] Phase 'system' starting 1 add-ons
24-03-14 19:34:06 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/zwave-js-ui/amd64 with version 3.4.1
24-03-14 19:34:36 INFO (MainThread) [supervisor.addons.manager] Phase 'services' starting 1 add-ons
24-03-14 19:34:37 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on ghcr.io/esphome/esphome-hassio with version 2024.2.2
24-03-14 19:34:42 INFO (MainThread) [supervisor.core] Start Home Assistant Core
24-03-14 19:34:42 INFO (SyncWorker_0) [supervisor.docker.manager] Starting homeassistant
24-03-14 19:34:42 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
24-03-14 19:34:43 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state startup
24-03-14 19:34:43 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-03-14 19:34:47 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
24-03-14 19:34:47 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to NOT_RUNNING
24-03-14 19:34:57 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to RUNNING
24-03-14 19:34:57 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance
24-03-14 19:34:57 INFO (MainThread) [supervisor.addons.manager] Phase 'application' starting 2 add-ons
24-03-14 19:34:57 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/node-red/amd64 with version 17.0.9
24-03-14 19:34:58 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on f4f71350/amd64-addon-ewelink_smart_home_slug with version 1.4.3
24-03-14 19:34:59 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
24-03-14 19:34:59 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
24-03-14 19:34:59 INFO (MainThread) [supervisor.api.proxy] WebSocket access from f4f71350_ewelink_smart_home_slug
24-03-14 19:34:59 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
24-03-14 19:35:01 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
24-03-14 19:35:01 INFO (MainThread) [supervisor.api.proxy] WebSocket access from f4f71350_ewelink_smart_home_slug
24-03-14 19:35:01 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
24-03-14 19:35:07 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
24-03-14 19:35:07 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
24-03-14 19:35:07 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
24-03-14 19:35:27 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled
24-03-14 19:35:27 INFO (MainThread) [supervisor.core] Supervisor is up and running
24-03-14 19:35:27 INFO (MainThread) [supervisor.host.info] Updating local host information
24-03-14 19:35:27 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state running
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for ipv4_connection_problem/system
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for security/core
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for free_space/system
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for pwned/addon
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_failed/dns_server
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for no_current_backup/system
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.module] Create new suggestion create_full_backup - system / None
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.module] Create new issue no_current_backup - system / None
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for docker_config/system
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_ipv6_error/dns_server
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.checks.base] Run check for trust/supervisor
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.check] System checks complete
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
24-03-14 19:35:27 INFO (MainThread) [supervisor.host.services] Updating service information
24-03-14 19:35:27 INFO (MainThread) [supervisor.host.network] Updating local network information
24-03-14 19:35:27 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
24-03-14 19:35:27 INFO (MainThread) [supervisor.host.manager] Host information reload completed
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running
24-03-14 19:35:27 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete

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

I don't think so but here is the out put only a minute or so after the issue occurs;

Mar 14 19:34:04 DBHA01 systemd[1]: Bluetooth service was skipped because of an unmet condition check (ConditionPathIsDirectory=/sys/class/bluetooth).
Mar 14 19:34:06 DBHA01 os-agent[156]: INFO: 2024/03/14 19:34:06 main.go:95: Diagnostics is now true
Mar 14 19:34:06 DBHA01 systemd[1]: var-lib-docker-overlay2-d47359532d23a4634dfce17de424411c70ab5781aa21f59f0feff2f26cfde450\x2dinit-merged.mount: Deactivated successfully.
Mar 14 19:34:06 DBHA01 systemd[1]: mnt-data-docker-overlay2-d47359532d23a4634dfce17de424411c70ab5781aa21f59f0feff2f26cfde450\x2dinit-merged.mount: Deactivated successfully.
Mar 14 19:34:06 DBHA01 kernel: hassio: port 6(veth31abb86) entered blocking state
Mar 14 19:34:06 DBHA01 kernel: hassio: port 6(veth31abb86) entered disabled state
Mar 14 19:34:06 DBHA01 kernel: veth31abb86: entered allmulticast mode
Mar 14 19:34:06 DBHA01 kernel: veth31abb86: entered promiscuous mode
Mar 14 19:34:06 DBHA01 NetworkManager[311]: <info>  [1710444846.5600] manager: (veth802255f): new Veth device (/org/freedesktop/NetworkManager/Devices/17)
Mar 14 19:34:06 DBHA01 NetworkManager[311]: <info>  [1710444846.5608] manager: (veth31abb86): new Veth device (/org/freedesktop/NetworkManager/Devices/18)
Mar 14 19:34:06 DBHA01 systemd[1]: Started libcontainer container e84ba2ef5eb89bc1a5ba92179cca9bcbf199871b45c49a1410b5a45108d10cbd.
Mar 14 19:34:06 DBHA01 kernel: eth0: renamed from veth802255f
Mar 14 19:34:06 DBHA01 kernel: hassio: port 6(veth31abb86) entered blocking state
Mar 14 19:34:06 DBHA01 kernel: hassio: port 6(veth31abb86) entered forwarding state
Mar 14 19:34:06 DBHA01 NetworkManager[311]: <info>  [1710444846.7201] device (veth31abb86): carrier: link connected
Mar 14 19:34:09 DBHA01 systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Mar 14 19:34:32 DBHA01 systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Mar 14 19:34:32 DBHA01 kernel: kauditd_printk_skb: 213 callbacks suppressed
Mar 14 19:34:32 DBHA01 kernel: audit: type=1334 audit(1710444872.852:186): prog-id=14 op=UNLOAD
Mar 14 19:34:32 DBHA01 kernel: audit: type=1334 audit(1710444872.852:187): prog-id=13 op=UNLOAD
Mar 14 19:34:32 DBHA01 kernel: audit: type=1334 audit(1710444872.852:188): prog-id=12 op=UNLOAD
Mar 14 19:34:32 DBHA01 systemd[1]: systemd-timedated.service: Deactivated successfully.
Mar 14 19:34:32 DBHA01 kernel: audit: type=1334 audit(1710444872.911:189): prog-id=25 op=UNLOAD
Mar 14 19:34:32 DBHA01 kernel: audit: type=1334 audit(1710444872.911:190): prog-id=24 op=UNLOAD
Mar 14 19:34:32 DBHA01 kernel: audit: type=1334 audit(1710444872.911:191): prog-id=23 op=UNLOAD
Mar 14 19:34:36 DBHA01 systemd[1]: var-lib-docker-overlay2-e3f7c5e767ef6fa1ee15f65c0c8cd9887150cf5bf9507f926df8380bbf410d65\x2dinit-merged.mount: Deactivated successfully.
Mar 14 19:34:36 DBHA01 systemd[1]: mnt-data-docker-overlay2-e3f7c5e767ef6fa1ee15f65c0c8cd9887150cf5bf9507f926df8380bbf410d65\x2dinit-merged.mount: Deactivated successfully.
Mar 14 19:34:36 DBHA01 systemd[1]: Started libcontainer container 488dab42c7fd662ebe5104e491c9daddede85c725169764be3281f18a1af2d6b.
Mar 14 19:34:36 DBHA01 kernel: audit: type=1334 audit(1710444876.975:192): prog-id=46 op=LOAD
Mar 14 19:34:36 DBHA01 kernel: audit: type=1334 audit(1710444876.976:193): prog-id=47 op=LOAD
Mar 14 19:34:36 DBHA01 kernel: audit: type=1300 audit(1710444876.976:193): arch=c000003e syscall=321 success=yes exit=16 a0=5 a1=c00018d988 a2=78 a3=0 items=0 ppid=2071 pid=2081 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="runc" exe="/usr/bin/runc" subj=unconfined key=(null)
Mar 14 19:34:36 DBHA01 kernel: audit: type=1327 audit(1710444876.976:193): proctitle=72756E63002D2D726F6F74002F7661722F72756E2F646F636B65722F72756E74696D652D72756E632F6D6F6279002D2D6C6F67002F72756E2F636F6E7461696E6572642F696F2E636F6E7461696E6572642E72756E74696D652E76322E7461736B2F6D6F62792F34383864616234326337666436363265626535313034653439
Mar 14 19:34:42 DBHA01 systemd[1]: Started libcontainer container ed9403d54863ddd32bce6c2a7d8471c2158ef9b876c74ffb00648679a8668f1e.
Mar 14 19:34:42 DBHA01 kernel: kauditd_printk_skb: 12 callbacks suppressed
Mar 14 19:34:42 DBHA01 kernel: audit: type=1334 audit(1710444882.112:198): prog-id=50 op=LOAD
Mar 14 19:34:42 DBHA01 kernel: audit: type=1300 audit(1710444882.112:198): arch=c000003e syscall=321 success=yes exit=15 a0=5 a1=c00018d988 a2=78 a3=0 items=0 ppid=2541 pid=2551 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="runc" exe="/usr/bin/runc" subj=unconfined key=(null)
Mar 14 19:34:42 DBHA01 kernel: audit: type=1327 audit(1710444882.112:198): proctitle=72756E63002D2D726F6F74002F7661722F72756E2F646F636B65722F72756E74696D652D72756E632F6D6F6279002D2D6C6F67002F72756E2F636F6E7461696E6572642F696F2E636F6E7461696E6572642E72756E74696D652E76322E7461736B2F6D6F62792F65643934303364353438363364646433326263653663326137
Mar 14 19:34:42 DBHA01 kernel: audit: type=1334 audit(1710444882.112:199): prog-id=51 op=LOAD
Mar 14 19:34:42 DBHA01 kernel: audit: type=1300 audit(1710444882.112:199): arch=c000003e syscall=321 success=yes exit=17 a0=5 a1=c00018d720 a2=78 a3=0 items=0 ppid=2541 pid=2551 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="runc" exe="/usr/bin/runc" subj=unconfined key=(null)
Mar 14 19:34:42 DBHA01 kernel: audit: type=1327 audit(1710444882.112:199): proctitle=72756E63002D2D726F6F74002F7661722F72756E2F646F636B65722F72756E74696D652D72756E632F6D6F6279002D2D6C6F67002F72756E2F636F6E7461696E6572642F696F2E636F6E7461696E6572642E72756E74696D652E76322E7461736B2F6D6F62792F65643934303364353438363364646433326263653663326137
Mar 14 19:34:42 DBHA01 kernel: audit: type=1334 audit(1710444882.112:200): prog-id=51 op=UNLOAD
Mar 14 19:34:42 DBHA01 kernel: audit: type=1300 audit(1710444882.112:200): arch=c000003e syscall=3 success=yes exit=0 a0=11 a1=0 a2=0 a3=0 items=0 ppid=2541 pid=2551 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="runc" exe="/usr/bin/runc" subj=unconfined key=(null)
Mar 14 19:34:42 DBHA01 kernel: audit: type=1327 audit(1710444882.112:200): proctitle=72756E63002D2D726F6F74002F7661722F72756E2F646F636B65722F72756E74696D652D72756E632F6D6F6279002D2D6C6F67002F72756E2F636F6E7461696E6572642F696F2E636F6E7461696E6572642E72756E74696D652E76322E7461736B2F6D6F62792F65643934303364353438363364646433326263653663326137
Mar 14 19:34:42 DBHA01 kernel: audit: type=1334 audit(1710444882.112:201): prog-id=50 op=UNLOAD
Mar 14 19:34:44 DBHA01 systemd[1]: Bluetooth service was skipped because of an unmet condition check (ConditionPathIsDirectory=/sys/class/bluetooth).
Mar 14 19:34:44 DBHA01 kernel: Bluetooth: Core ver 2.22
Mar 14 19:34:44 DBHA01 kernel: NET: Registered PF_BLUETOOTH protocol family
Mar 14 19:34:44 DBHA01 kernel: Bluetooth: HCI device and connection manager initialized
Mar 14 19:34:44 DBHA01 kernel: Bluetooth: HCI socket layer initialized
Mar 14 19:34:44 DBHA01 kernel: Bluetooth: L2CAP socket layer initialized
Mar 14 19:34:44 DBHA01 kernel: Bluetooth: SCO socket layer initialized
Mar 14 19:34:57 DBHA01 systemd[1]: var-lib-docker-overlay2-e4e7043df76849161ea35669ae4c44956604c1c64b02877609da2852c75d26d1\x2dinit-merged.mount: Deactivated successfully.
Mar 14 19:34:57 DBHA01 systemd[1]: mnt-data-docker-overlay2-e4e7043df76849161ea35669ae4c44956604c1c64b02877609da2852c75d26d1\x2dinit-merged.mount: Deactivated successfully.
Mar 14 19:34:57 DBHA01 systemd[1]: Started libcontainer container 0ebe00e6b5da31be656658965834aee6d292cadc2efa414a2ecb379fe833c8a3.
Mar 14 19:34:57 DBHA01 kernel: kauditd_printk_skb: 5 callbacks suppressed
Mar 14 19:34:57 DBHA01 kernel: audit: type=1334 audit(1710444897.560:203): prog-id=53 op=LOAD
Mar 14 19:34:57 DBHA01 kernel: audit: type=1334 audit(1710444897.560:204): prog-id=54 op=LOAD
Mar 14 19:34:57 DBHA01 kernel: audit: type=1300 audit(1710444897.560:204): arch=c000003e syscall=321 success=yes exit=16 a0=5 a1=c00018d988 a2=78 a3=0 items=0 ppid=2716 pid=2726 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="runc" exe="/usr/bin/runc" subj=unconfined key=(null)
Mar 14 19:34:57 DBHA01 kernel: audit: type=1327 audit(1710444897.560:204): proctitle=72756E63002D2D726F6F74002F7661722F72756E2F646F636B65722F72756E74696D652D72756E632F6D6F6279002D2D6C6F67002F72756E2F636F6E7461696E6572642F696F2E636F6E7461696E6572642E72756E74696D652E76322E7461736B2F6D6F62792F30656265303065366235646133316265363536363538393635
Mar 14 19:34:57 DBHA01 kernel: audit: type=1334 audit(1710444897.560:205): prog-id=55 op=LOAD
Mar 14 19:34:57 DBHA01 kernel: audit: type=1300 audit(1710444897.560:205): arch=c000003e syscall=321 success=yes exit=18 a0=5 a1=c00018d720 a2=78 a3=0 items=0 ppid=2716 pid=2726 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="runc" exe="/usr/bin/runc" subj=unconfined key=(null)
Mar 14 19:34:57 DBHA01 kernel: audit: type=1327 audit(1710444897.560:205): proctitle=72756E63002D2D726F6F74002F7661722F72756E2F646F636B65722F72756E74696D652D72756E632F6D6F6279002D2D6C6F67002F72756E2F636F6E7461696E6572642F696F2E636F6E7461696E6572642E72756E74696D652E76322E7461736B2F6D6F62792F30656265303065366235646133316265363536363538393635
Mar 14 19:34:57 DBHA01 kernel: audit: type=1334 audit(1710444897.560:206): prog-id=55 op=UNLOAD
Mar 14 19:34:57 DBHA01 kernel: audit: type=1300 audit(1710444897.560:206): arch=c000003e syscall=3 success=yes exit=0 a0=12 a1=0 a2=0 a3=0 items=0 ppid=2716 pid=2726 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="runc" exe="/usr/bin/runc" subj=unconfined key=(null)
Mar 14 19:34:57 DBHA01 kernel: audit: type=1327 audit(1710444897.560:206): proctitle=72756E63002D2D726F6F74002F7661722F72756E2F646F636B65722F72756E74696D652D72756E632F6D6F6279002D2D6C6F67002F72756E2F636F6E7461696E6572642F696F2E636F6E7461696E6572642E72756E74696D652E76322E7461736B2F6D6F62792F30656265303065366235646133316265363536363538393635
Mar 14 19:34:57 DBHA01 kernel: hassio: port 7(veth943cb12) entered blocking state
Mar 14 19:34:57 DBHA01 kernel: hassio: port 7(veth943cb12) entered disabled state
Mar 14 19:34:57 DBHA01 kernel: veth943cb12: entered allmulticast mode
Mar 14 19:34:57 DBHA01 kernel: veth943cb12: entered promiscuous mode
Mar 14 19:34:57 DBHA01 NetworkManager[311]: <info>  [1710444897.7732] manager: (vetha31bc63): new Veth device (/org/freedesktop/NetworkManager/Devices/19)
Mar 14 19:34:57 DBHA01 NetworkManager[311]: <info>  [1710444897.7740] manager: (veth943cb12): new Veth device (/org/freedesktop/NetworkManager/Devices/20)
Mar 14 19:34:57 DBHA01 systemd[1]: Started libcontainer container 7fb7985a067b046dbba4745461f06c12d820df447bd987b9976e96a2834c11d6.
Mar 14 19:34:58 DBHA01 kernel: eth0: renamed from vetha31bc63
Mar 14 19:34:58 DBHA01 kernel: hassio: port 7(veth943cb12) entered blocking state
Mar 14 19:34:58 DBHA01 kernel: hassio: port 7(veth943cb12) entered forwarding state
Mar 14 19:34:58 DBHA01 NetworkManager[311]: <info>  [1710444898.0143] device (veth943cb12): carrier: link connected
Mar 14 19:35:27 DBHA01 kernel: kauditd_printk_skb: 58 callbacks suppressed
Mar 14 19:35:27 DBHA01 kernel: audit: type=1334 audit(1710444927.632:227): prog-id=61 op=LOAD
Mar 14 19:35:27 DBHA01 kernel: audit: type=1334 audit(1710444927.632:228): prog-id=62 op=LOAD
Mar 14 19:35:27 DBHA01 kernel: audit: type=1334 audit(1710444927.633:229): prog-id=63 op=LOAD
Mar 14 19:35:27 DBHA01 systemd[1]: Starting Hostname Service...
Mar 14 19:35:27 DBHA01 systemd[1]: Started Hostname Service.
Mar 14 19:35:27 DBHA01 kernel: audit: type=1334 audit(1710444927.771:230): prog-id=64 op=LOAD
Mar 14 19:35:27 DBHA01 kernel: audit: type=1334 audit(1710444927.771:231): prog-id=65 op=LOAD
Mar 14 19:35:27 DBHA01 kernel: audit: type=1334 audit(1710444927.771:232): prog-id=66 op=LOAD
Mar 14 19:35:27 DBHA01 systemd[1]: Starting Time & Date Service...
Mar 14 19:35:27 DBHA01 systemd[1]: Started Time & Date Service.
Mar 14 19:35:57 DBHA01 systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Mar 14 19:35:57 DBHA01 kernel: audit: type=1334 audit(1710444957.796:233): prog-id=63 op=UNLOAD
Mar 14 19:35:57 DBHA01 kernel: audit: type=1334 audit(1710444957.796:234): prog-id=62 op=UNLOAD
Mar 14 19:35:57 DBHA01 kernel: audit: type=1334 audit(1710444957.796:235): prog-id=61 op=UNLOAD
Mar 14 19:35:57 DBHA01 systemd[1]: systemd-timedated.service: Deactivated successfully.
Mar 14 19:35:57 DBHA01 kernel: audit: type=1334 audit(1710444957.925:236): prog-id=66 op=UNLOAD
Mar 14 19:35:57 DBHA01 kernel: audit: type=1334 audit(1710444957.925:237): prog-id=65 op=UNLOAD
Mar 14 19:35:57 DBHA01 kernel: audit: type=1334 audit(1710444957.925:238): prog-id=64 op=UNLOAD
Mar 14 19:39:58 DBHA01 systemd[1]: run-docker-runtime\x2drunc-moby-0ebe00e6b5da31be656658965834aee6d292cadc2efa414a2ecb379fe833c8a3-runc.yhokBa.mount: Deactivated successfully.
Mar 14 19:41:33 DBHA01 kernel: audit: type=1334 audit(1710445293.920:239): prog-id=67 op=LOAD
Mar 14 19:41:33 DBHA01 systemd-timesyncd[370]: Network configuration changed, trying to establish connection.
Mar 14 19:41:33 DBHA01 systemd[1]: Started Journal Gateway Service.
Mar 14 19:41:33 DBHA01 systemd-timesyncd[370]: Contacted time server 162.159.200.1:123 (time.cloudflare.com).
Mar 14 19:41:34 DBHA01 systemd-journal-gatewayd[3751]: microhttpd: MHD_OPTION_EXTERNAL_LOGGER is not the first option specified for the daemon. Some messages may be printed by the standard MHD logger.

System information

System Information

version	core-2024.3.1
installation_type	Home Assistant OS
dev	false
hassio	true
docker	true
user	root
virtualenv	false
python_version	3.12.2
os_name	Linux
os_version	6.6.20-haos
arch	x86_64
timezone	Europe/London
config_dir	/config

Home Assistant Community Store

GitHub API	ok
GitHub Content	ok
GitHub Web	ok
GitHub API Calls Remaining	4913
Installed Version	1.34.0
Stage	running
Available Repositories	1399
Downloaded Repositories	4

Home Assistant Cloud

logged_in	true
subscription_expiration	15 March 2024 at 00:00
relayer_connected	true
relayer_region	eu-central-1
remote_enabled	true
remote_connected	true
alexa_enabled	true
google_enabled	true
remote_server	eu-central-1-10.ui.nabu.casa
certificate_status	ready
instance_id	45fd9f3fa0764ccca8f73de93c3e5ea3
can_reach_cert_server	ok
can_reach_cloud_auth	ok
can_reach_cloud	ok

Home Assistant Supervisor

host_os	Home Assistant OS 12.1
update_channel	stable
supervisor_version	supervisor-2024.03.0
agent_version	1.6.0
docker_version	24.0.7
disk_total	30.8 GB
disk_used	9.8 GB
healthy	true
supported	true
board	ova
supervisor_api	ok
version_api	ok
installed_addons	Node-RED (17.0.9), Terminal & SSH (9.10.0), eWeLink Smart Home (1.4.3), Studio Code Server (5.15.0), Z-Wave JS UI (3.4.1), ESPHome (2024.2.2)

Dashboards

dashboards	3
resources	0
views	15
mode	storage

Recorder

oldest_recorder_run	2 March 2024 at 13:57
current_recorder_run	14 March 2024 at 19:34
estimated_db_size	373.99 MiB
database_engine	sqlite
database_version	3.44.2

Additional information

Here is the relevant section from the VM Backup plugin log; 2024-03-14 19:39:54 information: DBHA01 can be found on the system. attempting backup. 2024-03-14 19:39:54 information: creating local DBHA01.xml to work with during backup. 2024-03-14 19:39:54 information: /mnt/user/Backups/Unraid/VMs/DBHA01 exists. continuing. 2024-03-14 19:39:54 information: skip_vm_shutdown is false and use_snapshots is 1. skipping vm shutdown procedure. DBHA01 is running. can_backup_vm set to y. 2024-03-14 19:39:54 information: actually_copy_files is 1. 2024-03-14 19:39:54 information: can_backup_vm flag is y. starting backup of DBHA01 configuration, nvram, and vdisk(s). sending incremental file list DBHA01.xml

sent 6,401 bytes received 35 bytes 12,872.00 bytes/sec total size is 6,294 speedup is 0.98 2024-03-14 19:39:55 information: copy of DBHA01.xml to /mnt/user/Backups/Unraid/VMs/DBHA01/20240314_1936_DBHA01.xml complete. sending incremental file list 43c3c14f-bbb0-e3b1-af5f-fc81c78df426_VARS-pure-efi-tpm.fd

sent 540,951 bytes received 35 bytes 1,081,972.00 bytes/sec total size is 540,672 speedup is 1.00 2024-03-14 19:39:55 information: copy of /etc/libvirt/qemu/nvram/43c3c14f-bbb0-e3b1-af5f-fc81c78df426_VARS-pure-efi-tpm.fd to /mnt/user/Backups/Unraid/VMs/DBHA01/20240314_1936_43c3c14f-bbb0-e3b1-af5f-fc81c78df426_VARS-pure-efi-tpm.fd complete. 2024-03-14 19:39:55 information: able to perform snapshot for disk /mnt/user/domains/DBHA01/vdisk1.img on DBHA01. use_snapshots is 1. vm_state is running. vdisk_type is qcow2 2024-03-14 19:39:55 information: qemu agent found. enabling quiesce on snapshot. error: internal error: unable to execute QEMU agent command ‘guest-fsfreeze-freeze’: fsfreeze hook has failed with status 1

2024-03-14 19:39:55 failure: snapshot command failed on vdisk1.snap for DBHA01. 2024-03-14 19:39:57 failure: snapshot_fallback is 0. skipping backup for DBHA01 to prevent data loss. no cleanup will be performed for this vm.