Minor issues with backup

Chicken76

Well-Known Member
Jun 26, 2017
54
2
48
45
I've done a manual backup of a KVM VM (Windows) to NFS.
I chose the 'Stop' mode and Gzip compression.
Here's the tail of the log:
Code:
INFO: status: 100% (161061273600/161061273600), sparse 78% (127045570560), duration 2178, read/write 456/0 MB/s
INFO: transferred 161061 MB in 2178 seconds (73 MB/s)
INFO: archive file size: 10.27GB
INFO: vm is online again after 2208 seconds
INFO: Finished Backup of VM 100 (00:36:49)
cp: cannot create regular file '/mnt/pve/nas1-storage2/dump/vzdump-qemu-100-2017_11_24-11_02_17.log': Operation not permitted
INFO: Backup job finished successfully
TASK OK

1st issue: the log file on the NFS share is zero bytes long. I don't know why it couldn't save it, since the backup file (.gz) is there and is OK.
2nd issue: why does it say 'VM is online again after 2208 seconds' since I could ping it during the compression phase? Doesn't the 'Stop' method stop the VM, snapshot it, start it and then compress and upload the backup? It should have been offline only for a few seconds (shutdown, snapshot and bootup), right, or am I missing something?
 
Following up on this issue, it seems last night's backup job also failed. The mail I received says:

100: 2017-11-28 22:45:02 INFO: Starting Backup of VM 100 (qemu)
100: 2017-11-28 22:45:02 INFO: status = running
100: 2017-11-28 22:45:02 INFO: update VM 100: -lock backup
100: 2017-11-28 22:45:02 INFO: backup mode: stop
100: 2017-11-28 22:45:02 INFO: ionice priority: 7
100: 2017-11-28 22:45:02 INFO: VM Name: server4
100: 2017-11-28 22:45:02 INFO: include disk 'scsi0' 'local-zfs:vm-100-disk-1' 150G
100: 2017-11-28 22:45:02 INFO: stopping vm
100: 2017-11-28 22:45:36 INFO: creating archive '/mnt/pve/nas1-storage2/dump/vzdump-qemu-100-2017_11_28-22_45_02.vma.lzo'
100: 2017-11-28 22:45:36 INFO: starting kvm to execute backup task
100: 2017-11-28 22:45:37 INFO: restarting vm
100: 2017-11-28 22:45:41 INFO: vm is online again after 39 seconds
100: 2017-11-28 22:45:41 ERROR: Backup of VM 100 failed - start failed: org.freedesktop.systemd1.UnitExists: Unit 100.scope already exists.

Anyone has an idea what the error message means?