Backup ended but finished flag is not set

[stkw]

Seit heute schlägt das Backup eines Containers wiederholt fehl:

2021-05-07T09:24:52+02:00: starting new backup on datastore 'nextbackup': "ct/107/2021-05-07T07:24:52Z"
2021-05-07T09:24:52+02:00: download 'index.json.blob' from previous backup.
2021-05-07T09:24:52+02:00: add blob "/mnt/nextbackup/ct/107/2021-05-07T07:24:52Z/pct.conf.blob" (262 bytes, comp: 262)
2021-05-07T09:24:52+02:00: add blob "/mnt/nextbackup/ct/107/2021-05-07T07:24:52Z/fw.conf.blob" (155 bytes, comp: 155)
2021-05-07T09:24:52+02:00: register chunks in 'root.pxar.didx' from previous backup.
2021-05-07T09:24:52+02:00: created new dynamic index 1 ("ct/107/2021-05-07T07:24:52Z/catalog.pcat1.didx")
2021-05-07T09:24:52+02:00: download 'root.pxar.didx' from previous backup.
2021-05-07T09:24:52+02:00: created new dynamic index 2 ("ct/107/2021-05-07T07:24:52Z/root.pxar.didx")
2021-05-07T09:31:02+02:00: backup ended and finish failed: backup ended but finished flag is not set.
2021-05-07T09:31:02+02:00: removing unfinished backup
2021-05-07T09:31:02+02:00: TASK ERROR: backup ended but finished flag is not set.

Habe danach versucht sowohl PVE als auch PBS auf die aktuellen Versionen zu aktualisieren, hat das Problem aber nicht gelöst, der Fehler bleibt der gleiche.

 

[dcsapak]

was sagt denn der log auf der client seite?

 

[stkw]

INFO: Starting backup: ct/107/2021-05-07T07:34:44Z
INFO: Client name: pve
INFO: Starting backup protocol: Fri May  7 09:34:49 2021
INFO: Downloading previous manifest (Thu May  6 03:20:53 2021)
INFO: Upload config file '/var/tmp/vzdumptmp20715_107/etc/vzdump/pct.conf' to 'root@pam@pbs.kw.at:8007:nextbackup' as pct.conf.blob
INFO: Upload config file '/var/tmp/vzdumptmp20715_107/etc/vzdump/pct.fw' to 'root@pam@pbs.kw.at:8007:nextbackup' as fw.conf.blob
INFO: Upload directory '/mnt/vzsnap0' to 'root@pam@pbs.kw.at:8007:nextbackup' as root.pxar.didx
INFO: cleanup temporary 'vzdump' snapshot
zfs error: cannot destroy snapshot nvme/subvol-107-disk-0@vzdump: dataset is busy
ERROR: Backup of VM 107 failed - command 'lxc-usernsexec -m u:0:100000:65536 -m g:0:100000:65536 -- /usr/bin/proxmox-backup-client backup '--crypt-mode=none' pct.conf:/var/tmp/vzdumptmp20715_107/etc/vzdump/pct.conf fw.conf:/var/tmp/vzdumptmp20715_107/etc/vzdump/pct.fw root.pxar:/mnt/vzsnap0 --include-dev /mnt/vzsnap0/./ --skip-lost-and-found '--exclude=/tmp/?*' '--exclude=/var/tmp/?*' '--exclude=/var/run/?*.pid' --backup-type ct --backup-id 107 --backup-time 1620372884 --repository root@pam@pbs.kw.at:nextbackup' failed: interrupted by signal
INFO: Failed at 2021-05-07 10:15:26

Gerade die Forenbeiträge zu ähnlichen Problemen gelesen - die Installation ist mehrere Monate alt, vermute daher nicht dass es mit LVM und ZFS zu tun hat. Auch ein Neustart hat das Problem nicht gelöst. Was kann man noch machen?

 

[CoolTux]

was sagt denn der log auf der client seite?

 

[stkw]

Welcher Client? Das Log des PVE ist im vorigen Beitrag.

 

[CoolTux]

INFO: Client name: pve

Es gibt immer auch ein Log vom Client welcher gesichert wird. In Deinem Fall heißt er pve

 

[stkw]

Ja eh - genau das Log hab ich ja schon geschickt - was wird denn noch benötigt?

INFO: Starting backup: ct/107/2021-05-07T07:34:44Z
INFO: Client name: pve
INFO: Starting backup protocol: Fri May  7 09:34:49 2021
INFO: Downloading previous manifest (Thu May  6 03:20:53 2021)
INFO: Upload config file '/var/tmp/vzdumptmp20715_107/etc/vzdump/pct.conf' to 'root@pam@pbs.kw.at:8007:nextbackup' as pct.conf.blob
INFO: Upload config file '/var/tmp/vzdumptmp20715_107/etc/vzdump/pct.fw' to 'root@pam@pbs.kw.at:8007:nextbackup' as fw.conf.blob
INFO: Upload directory '/mnt/vzsnap0' to 'root@pam@pbs.kw.at:8007:nextbackup' as root.pxar.didx
INFO: cleanup temporary 'vzdump' snapshot
zfs error: cannot destroy snapshot nvme/subvol-107-disk-0@vzdump: dataset is busy
ERROR: Backup of VM 107 failed - command 'lxc-usernsexec -m u:0:100000:65536 -m g:0:100000:65536 -- /usr/bin/proxmox-backup-client backup '--crypt-mode=none' pct.conf:/var/tmp/vzdumptmp20715_107/etc/vzdump/pct.conf fw.conf:/var/tmp/vzdumptmp20715_107/etc/vzdump/pct.fw root.pxar:/mnt/vzsnap0 --include-dev /mnt/vzsnap0/./ --skip-lost-and-found '--exclude=/tmp/?*' '--exclude=/var/tmp/?*' '--exclude=/var/run/?*.pid' --backup-type ct --backup-id 107 --backup-time 1620372884 --repository root@pam@pbs.kw.at:nextbackup' failed: interrupted by signal
INFO: Failed at 2021-05-07 10:15:26

 

[dcsapak]

zfs error: cannot destroy snapshot nvme/subvol-107-disk-0@vzdump: dataset is busy

hier hat offenbar zfs ein problem den snapshot zu löschen... was sagt denn das log/dmesg zu dieser zeit?

 

[stkw]

Im Log finden sich leider gar keine Meldungen mit Bezug auf dieses Problem, verschieden Nachrichten die andere Container betreffen aber nichts was auf Probleme mit ZFS, dem Container oder ähnliches hindeutet.