Hi. vzdump creates tar archive with zero filled files on a nfs storage. It was working few a pair of weeks ago, but after some time it started to behave like this. Backup to a local storage is ok. The only thing I remember was changed is the pve-kernel.
NFS export
NFS mount
NFS storage (archive file size: 2MB)
Local storage (archive file size: 789MB)
NFS export
Code:
/srv/data/backup/proxmox 10.0.1.251(rw,no_root_squash)
NFS mount
Code:
storage:/srv/data/backup/proxmox on /mnt/pve/storage type nfs (rw,relatime,vers=3,rsize=1048576,wsize=1048576,namlen=255,hard,proto=tcp,timeo=600,retrans=2,sec=sys,mountaddr=10.0.0.11,mountvers=3,mountport=43618,mountproto=udp,local_lock=none,addr=10.0.0.11)
NFS storage (archive file size: 2MB)
Code:
Aug 03 08:28:51 INFO: Starting Backup of VM 105 (openvz)
Aug 03 08:28:51 INFO: CTID 105 exist mounted running
Aug 03 08:28:51 INFO: status = running
Aug 03 08:28:51 INFO: backup mode: suspend
Aug 03 08:28:51 INFO: ionice priority: 7
Aug 03 08:28:51 INFO: starting first sync /var/lib/vz/private/105/ to /mnt/pve/storage/dump/vzdump-openvz-105-2013_08_03-08_28_51.tmp
Aug 03 08:35:10 INFO: Number of files: 57667
Aug 03 08:35:10 INFO: Number of files transferred: 47331
Aug 03 08:35:10 INFO: Total file size: 1662810236 bytes
Aug 03 08:35:10 INFO: Total transferred file size: 1647259723 bytes
Aug 03 08:35:10 INFO: Literal data: 1647259723 bytes
Aug 03 08:35:10 INFO: Matched data: 0 bytes
Aug 03 08:35:10 INFO: File list size: 1220786
Aug 03 08:35:10 INFO: File list generation time: 0.001 seconds
Aug 03 08:35:10 INFO: File list transfer time: 0.000 seconds
Aug 03 08:35:10 INFO: Total bytes sent: 1650952367
Aug 03 08:35:10 INFO: Total bytes received: 1168733
Aug 03 08:35:11 INFO: sent 1650952367 bytes received 1168733 bytes 4353415.28 bytes/sec
Aug 03 08:35:11 INFO: total size is 1662810236 speedup is 1.01
Aug 03 08:35:11 INFO: first sync finished (380 seconds)
Aug 03 08:35:11 INFO: suspend vm
Aug 03 08:35:11 INFO: Setting up checkpoint...
Aug 03 08:35:11 INFO: suspend...
Aug 03 08:35:11 INFO: get context...
Aug 03 08:35:11 INFO: Checkpointing completed successfully
Aug 03 08:35:11 INFO: starting final sync /var/lib/vz/private/105/ to /mnt/pve/storage/dump/vzdump-openvz-105-2013_08_03-08_28_51.tmp
Aug 03 08:35:20 INFO: Number of files: 57668
Aug 03 08:35:20 INFO: Number of files transferred: 3
Aug 03 08:35:20 INFO: Total file size: 1662810308 bytes
Aug 03 08:35:20 INFO: Total transferred file size: 17397 bytes
Aug 03 08:35:20 INFO: Literal data: 1472 bytes
Aug 03 08:35:20 INFO: Matched data: 15925 bytes
Aug 03 08:35:20 INFO: File list size: 1220822
Aug 03 08:35:20 INFO: File list generation time: 0.001 seconds
Aug 03 08:35:20 INFO: File list transfer time: 0.000 seconds
Aug 03 08:35:20 INFO: Total bytes sent: 1228599
Aug 03 08:35:20 INFO: Total bytes received: 6292
Aug 03 08:35:20 INFO: sent 1228599 bytes received 6292 bytes 129988.53 bytes/sec
Aug 03 08:35:20 INFO: total size is 1662810308 speedup is 1346.52
Aug 03 08:35:20 INFO: final sync finished (9 seconds)
Aug 03 08:35:20 INFO: resume vm
Aug 03 08:35:20 INFO: Resuming...
Aug 03 08:35:20 INFO: vm is online again after 9 seconds
Aug 03 08:35:20 INFO: creating archive '/mnt/pve/storage/dump/vzdump-openvz-105-2013_08_03-08_28_51.tar.lzo'
Aug 03 08:36:34 INFO: Total bytes written: 34836480 (34MiB, 637KiB/s)
Aug 03 08:36:34 INFO: archive file size: 2MB
Aug 03 08:37:24 INFO: Finished Backup of VM 105 (00:08:33)
Local storage (archive file size: 789MB)
Code:
Aug 03 09:11:17 INFO: Starting Backup of VM 105 (openvz)
Aug 03 09:11:17 INFO: CTID 105 exist mounted running
Aug 03 09:11:17 INFO: status = running
Aug 03 09:11:17 INFO: backup mode: suspend
Aug 03 09:11:17 INFO: ionice priority: 7
Aug 03 09:11:17 INFO: starting first sync /var/lib/vz/private/105/ to /var/lib/vz/dump/vzdump-openvz-105-2013_08_03-09_11_17.tmp
Aug 03 09:12:31 INFO: Number of files: 57705
Aug 03 09:12:31 INFO: Number of files transferred: 47369
Aug 03 09:12:31 INFO: Total file size: 1663057950 bytes
Aug 03 09:12:31 INFO: Total transferred file size: 1647507437 bytes
Aug 03 09:12:31 INFO: Literal data: 1647507437 bytes
Aug 03 09:12:31 INFO: Matched data: 0 bytes
Aug 03 09:12:31 INFO: File list size: 1222155
Aug 03 09:12:31 INFO: File list generation time: 0.001 seconds
Aug 03 09:12:31 INFO: File list transfer time: 0.000 seconds
Aug 03 09:12:31 INFO: Total bytes sent: 1651204241
Aug 03 09:12:31 INFO: Total bytes received: 1170584
Aug 03 09:12:31 INFO: sent 1651204241 bytes received 1170584 bytes 22179527.85 bytes/sec
Aug 03 09:12:31 INFO: total size is 1663057950 speedup is 1.01
Aug 03 09:12:31 INFO: first sync finished (74 seconds)
Aug 03 09:12:31 INFO: suspend vm
Aug 03 09:12:31 INFO: Setting up checkpoint...
Aug 03 09:12:31 INFO: suspend...
Aug 03 09:12:31 INFO: get context...
Aug 03 09:12:31 INFO: Checkpointing completed successfully
Aug 03 09:12:31 INFO: starting final sync /var/lib/vz/private/105/ to /var/lib/vz/dump/vzdump-openvz-105-2013_08_03-09_11_17.tmp
Aug 03 09:12:33 INFO: Number of files: 57705
Aug 03 09:12:33 INFO: Number of files transferred: 0
Aug 03 09:12:33 INFO: Total file size: 1663057950 bytes
Aug 03 09:12:33 INFO: Total transferred file size: 0 bytes
Aug 03 09:12:33 INFO: Literal data: 0 bytes
Aug 03 09:12:33 INFO: Matched data: 0 bytes
Aug 03 09:12:33 INFO: File list size: 1222155
Aug 03 09:12:33 INFO: File list generation time: 0.001 seconds
Aug 03 09:12:33 INFO: File list transfer time: 0.000 seconds
Aug 03 09:12:33 INFO: Total bytes sent: 1228228
Aug 03 09:12:33 INFO: Total bytes received: 6072
Aug 03 09:12:33 INFO: sent 1228228 bytes received 6072 bytes 493720.00 bytes/sec
Aug 03 09:12:33 INFO: total size is 1663057950 speedup is 1347.37
Aug 03 09:12:33 INFO: final sync finished (2 seconds)
Aug 03 09:12:33 INFO: resume vm
Aug 03 09:12:33 INFO: Resuming...
Aug 03 09:12:33 INFO: vm is online again after 2 seconds
Aug 03 09:12:33 INFO: creating archive '/var/lib/vz/dump/vzdump-openvz-105-2013_08_03-09_11_17.tar.lzo'
Aug 03 09:13:37 INFO: Total bytes written: 1694822400 (1.6GiB, 26MiB/s)
Aug 03 09:13:37 INFO: archive file size: 789MB
Aug 03 09:13:41 INFO: Finished Backup of VM 105 (00:02:24)
Code:
pve-manager: 3.0-23 (pve-manager/3.0/957f0862)
running kernel: 2.6.32-22-pve
proxmox-ve-2.6.32: 3.0-107
pve-kernel-2.6.32-20-pve: 2.6.32-100
pve-kernel-2.6.32-22-pve: 2.6.32-107
lvm2: 2.02.95-pve3
clvm: 2.02.95-pve3
corosync-pve: 1.4.5-1
openais-pve: 1.1.4-3
libqb0: 0.11.1-2
redhat-cluster-pve: 3.2.0-2
resource-agents-pve: 3.9.2-4
fence-agents-pve: 4.0.0-1
pve-cluster: 3.0-4
qemu-server: 3.0-20
pve-firmware: 1.0-23
libpve-common-perl: 3.0-4
libpve-access-control: 3.0-4
libpve-storage-perl: 3.0-8
vncterm: 1.1-4
vzctl: 4.0-1pve3
vzprocps: 2.0.11-2
vzquota: 3.1-2
pve-qemu-kvm: 1.4-13
ksm-control-daemon: 1.1-1
Last edited: