VMs hang on shutdown during backup job

Please share the full system logs from the time the backup starts and the time the error first occurs for the VM, i.e. everything between
Code:
... INFO: Starting Backup of VM XYZ (qemu)
and
Code:
Apr 07 00:36:34 cl1 qmeventd[847]: error parsing vmid for 602656: no matching qemu.slice cgroup entry
Apr 07 00:36:34 cl1 qmeventd[847]: could not get vmid from pid 602656
 
  • Like
Reactions: _gabriel
Apr 07 00:00:03 cl1 pvescheduler[1332680]: <root@pam> starting task UPID:cl1:001455C9:01AFA3C3:67F2EB53:vzdump:101:root@pam:
Apr 07 00:00:03 cl1 pvescheduler[1332680]: <root@pam> starting task UPID:cl1:001455CA:01AFA3C4:67F2EB53:vzdump::root@pam:
Apr 07 00:00:04 cl1 pvescheduler[1332682]: INFO: trying to get global lock - waiting...
Apr 07 00:00:04 cl1 pvescheduler[1332681]: INFO: starting new backup job: vzdump 101 --storage sb_cl1 --node cl1 --notes-template '{{guestname}}' --mode snapshot --fleecing 0 --compress zstd --quiet 1 --prune-backups 'keep-last=2,keep-monthly=1,keep-weekly=1'
Apr 07 00:00:04 cl1 pvescheduler[1332681]: INFO: Starting Backup of VM 101 (qemu)
Apr 07 00:00:04 cl1 qmeventd[847]: error parsing vmid for 602656: no matching qemu.slice cgroup entry
Apr 07 00:00:04 cl1 qmeventd[847]: could not get vmid from pid 602656
Apr 07 00:00:09 cl1 qmeventd[847]: error parsing vmid for 602656: no matching qemu.slice cgroup entry
Apr 07 00:00:09 cl1 qmeventd[847]: could not get vmid from pid 602656
Apr 07 00:00:14 cl1 qmeventd[847]: error parsing vmid for 602656: no matching qemu.slice cgroup entry
Apr 07 00:00:14 cl1 qmeventd[847]: could not get vmid from pid 602656
Apr 07 00:00:14 cl1 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service...
Apr 07 00:00:14 cl1 systemd[1]: Starting logrotate.service - Rotate log files...
Apr 07 00:00:14 cl1 systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Apr 07 00:00:14 cl1 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service.
Apr 07 00:00:14 cl1 systemd[1]: Reloading pveproxy.service - PVE API Proxy Server...
Apr 07 00:00:15 cl1 pveproxy[1332775]: send HUP to 1474
Apr 07 00:00:15 cl1 pveproxy[1474]: received signal HUP
Apr 07 00:00:15 cl1 pveproxy[1474]: server closing
Apr 07 00:00:15 cl1 pveproxy[1474]: server shutdown (restart)
Apr 07 00:00:15 cl1 systemd[1]: Reloaded pveproxy.service - PVE API Proxy Server.
Apr 07 00:00:15 cl1 systemd[1]: Reloading spiceproxy.service - PVE SPICE Proxy Server...
Apr 07 00:00:15 cl1 spiceproxy[1332777]: send HUP to 1481
Apr 07 00:00:15 cl1 spiceproxy[1481]: received signal HUP
Apr 07 00:00:15 cl1 spiceproxy[1481]: server closing
Apr 07 00:00:15 cl1 spiceproxy[1481]: server shutdown (restart)
Apr 07 00:00:15 cl1 systemd[1]: Reloaded spiceproxy.service - PVE SPICE Proxy Server.
Apr 07 00:00:15 cl1 pvefw-logger[966338]: received terminate request (signal)
Apr 07 00:00:15 cl1 pvefw-logger[966338]: stopping pvefw logger
Apr 07 00:00:15 cl1 systemd[1]: Stopping pvefw-logger.service - Proxmox VE firewall logger...
Apr 07 00:00:15 cl1 spiceproxy[1481]: restarting server
Apr 07 00:00:15 cl1 spiceproxy[1481]: starting 1 worker(s)
Apr 07 00:00:15 cl1 spiceproxy[1481]: worker 1332786 started
Apr 07 00:00:15 cl1 pveproxy[1474]: Using '/etc/pve/local/pveproxy-ssl.pem' as certificate for the web interface.
Apr 07 00:00:15 cl1 pveproxy[1474]: restarting server
Apr 07 00:00:15 cl1 pveproxy[1474]: starting 3 worker(s)
Apr 07 00:00:15 cl1 pveproxy[1474]: worker 1332787 started
Apr 07 00:00:15 cl1 pveproxy[1474]: worker 1332788 started
Apr 07 00:00:15 cl1 pveproxy[1474]: worker 1332789 started
Apr 07 00:00:15 cl1 systemd[1]: pvefw-logger.service: Deactivated successfully.
Apr 07 00:00:15 cl1 systemd[1]: Stopped pvefw-logger.service - Proxmox VE firewall logger.
Apr 07 00:00:15 cl1 systemd[1]: pvefw-logger.service: Consumed 3.140s CPU time.
Apr 07 00:00:15 cl1 systemd[1]: Starting pvefw-logger.service - Proxmox VE firewall logger...
Apr 07 00:00:15 cl1 pvefw-logger[1332791]: starting pvefw logger
Apr 07 00:00:15 cl1 systemd[1]: Started pvefw-logger.service - Proxmox VE firewall logger.
Apr 07 00:00:15 cl1 systemd[1]: logrotate.service: Deactivated successfully.
Apr 07 00:00:15 cl1 systemd[1]: Finished logrotate.service - Rotate log files.
Apr 07 00:00:19 cl1 qmeventd[847]: error parsing vmid for 602656: no matching qemu.slice cgroup entry
Apr 07 00:00:19 cl1 qmeventd[847]: could not get vmid from pid 602656
Apr 07 00:00:20 cl1 spiceproxy[966332]: worker exit
Apr 07 00:00:20 cl1 spiceproxy[1481]: worker 966332 finished
Apr 07 00:00:20 cl1 pveproxy[1284961]: worker exit
Apr 07 00:00:20 cl1 pveproxy[1282943]: worker exit
Apr 07 00:00:20 cl1 pveproxy[1281625]: worker exit
Apr 07 00:00:20 cl1 pveproxy[1474]: worker 1282943 finished
Apr 07 00:00:20 cl1 pveproxy[1474]: worker 1284961 finished
Apr 07 00:00:20 cl1 pveproxy[1474]: worker 1281625 finished
Apr 07 00:00:24 cl1 qmeventd[847]: error parsing vmid for 602656: no matching qemu.slice cgroup entry
Apr 07 00:00:24 cl1 qmeventd[847]: could not get vmid from pid 602656
Apr 07 00:00:29 cl1 qmeventd[847]: error parsing vmid for 602656: no matching qemu.slice cgroup entry
Apr 07 00:00:29 cl1 qmeventd[847]: could not get vmid from pid 602656