[pve-devel] Backup fails

[Michael Rasmussen]

Hi all,

Recently I am beginning to see logs like can be seen below. It is the
automatic backup which fails backing up some of the VMs but it seems
completely random which VM will fail backup when backup is performed.

Another observation is this warning given when every backup starts: Use
of uninitialized value $cmd[8] in exec

INFO: starting new backup job: vzdump 109 114 115 117 128 113 143 144
148 --quiet 1 --mailto foo at bar.tld --mode snapshot --compress lzo
--storage qnap_nfs INFO: skip external VMs: 109, 114, 117, 113, 144
INFO: Starting Backup of VM 115 (qemu) 
INFO: status = running
INFO: update VM 115: -lock backup 
Use of uninitialized value $cmd[8] in exec
at /usr/share/perl/5.14/IPC/Open3.pm line 186. 
Use of uninitialized value $cmd[8] in exec
at /usr/share/perl/5.14/IPC/Open3.pm line 186. 
INFO: backup mode: snapshot INFO: bandwidth limit: 50000 KB/s 
INFO: ionice priority: 7 
INFO: skip unused drive 'omnios_nfs:115/vm-115-disk-1.qcow2' (not
included into backup) INFO: creating archive
'/mnt/pve/qnap_nfs/dump/vzdump-qemu-115-2014_09_13-05_15_01.vma.lzo'
INFO: started backup task 'c94ecd45-6ffa-44fd-9cb9-7433982ddccf' 
INFO: status: 0% (111869952/21474836480), sparse 0% (13647872),
duration 3, 37/32 MB/s 
INFO: status: 1% (217513984/21474836480), sparse
0% (41357312), duration 6, 35/25 MB/s 
INFO: status: 2% (466550784/21474836480), sparse 0% (41754624),
duration 13, 35/35 MB/s 
INFO: status: 3% (664010752/21474836480), sparse 0% (41779200),
duration 19, 32/32 MB/s 
INFO: status: 4% (866779136/21474836480), sparse 0% (41779200),
duration 26, 28/28 MB/s 
INFO: status: 5% (1075707904/21474836480), sparse 0% (42557440),
duration 36, 20/20 MB/s 
INFO: status: 6% (1308884992/21474836480), sparse 0% (42557440),
duration 55, 12/12 MB/s 
INFO: status: 7% (1521549312/21474836480), sparse 0% (42557440),
duration 67, 17/17 MB/s 
INFO: status: 8% (1730674688/21474836480), sparse 0% (53166080),
duration 73, 34/33 MB/s 
INFO: status: 9% (1935212544/21474836480), sparse 0% (53321728),
duration 79, 34/34 MB/s 
INFO: status: 10% (2172780544/21474836480), sparse 0% (53321728),
duration 85, 39/39 MB/s 
INFO: status: 11% (2390884352/21474836480), sparse 0% (83255296),
duration 106, 10/8 MB/s 
INFO: status: 12% (2577137664/21474836480), sparse 0% (83255296),
duration 117, 16/16 MB/s 
INFO: status: 13% (2828009472/21474836480), sparse 0% (83255296),
duration 124, 35/35 MB/s 
INFO: status: 14% (3007053824/21474836480), sparse 0% (83255296),
duration 130, 29/29 MB/s 
INFO: status: 15% (3255173120/21474836480), sparse 0% (83255296),
duration 155, 9/9 MB/s 
INFO: status: 16% (3462791168/21474836480), sparse 0% (83259392),
duration 167, 17/17 MB/s 
INFO: status: 17% (3662348288/21474836480), sparse 0% (83259392),
duration 172, 39/39 MB/s 
INFO: status: 18% (3889430528/21474836480), sparse 0% (83259392),
duration 198, 8/8 MB/s 
INFO: status: 19% (4090167296/21474836480), sparse 0% (95260672),
duration 204, 33/31 MB/s lzop: Input/output error: <stdout> 
INFO: status: 19% (4116578304/21474836480), sparse 0% (105844736),
duration 215, 2/1 MB/s 
ERROR: vma_queue_write: write error - Broken pipe INFO: aborting backup
job 
ERROR: Backup of VM 115 failed - vma_queue_write: write error - Broken
pipe

-- 
Hilsen/Regards
Michael Rasmussen

Get my public GnuPG keys:
michael <at> rasmussen <dot> cc
http://pgp.mit.edu:11371/pks/lookup?op=get&search=0xD3C9A00E
mir <at> datanom <dot> net
http://pgp.mit.edu:11371/pks/lookup?op=get&search=0xE501F51C
mir <at> miras <dot> org
http://pgp.mit.edu:11371/pks/lookup?op=get&search=0xE3E80917
--------------------------------------------------------------
/usr/games/fortune -es says:
You'd like to do it instantaneously, but that's too slow.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 181 bytes
Desc: OpenPGP digital signature
URL: <http://lists.proxmox.com/pipermail/pve-devel/attachments/20140913/6e5691a1/attachment.sig>