Problems with Backup - got timeout

[riti03]

Hello guys!

I need a little bit help about my backup job which doesnt work. The problem is that the process stops at about 50% and gives me a job error.




INFO: starting new backup job: vzdump 100 --mailnotification failure --node pve --compress lzo --quiet 1 --mode snapshot --storage local
INFO: Starting Backup of VM 100 (qemu)
INFO: Backup started at 2022-05-18 23:15:01
INFO: status = running
INFO: update VM 100: -lock backup
INFO: VM Name: --------
INFO: include disk 'scsi0' 'local-zfs:vm-100-disk-0' 800G
INFO: backup mode: snapshot
INFO: ionice priority: 7
INFO: creating archive '/var/lib/vz/dump/vzdump-qemu-100-2022_05_18-23_15_01.vma.lzo'
INFO: started backup task 'abaec3e4-68f9-4e44-81c8-22ff0c2ad3cc'
INFO: status: 0% (335937536/858993459200), sparse 0% (42393600), duration 3, read/write 111/97 MB/s
INFO: status: 1% (8597471232/858993459200), sparse 0% (203317248), duration 253, read/write 33/32 MB/s
INFO: status: 2% (17307795456/858993459200), sparse 0% (383119360), duration 506, read/write 34/33 MB/s
INFO: status: 3% (25859129344/858993459200), sparse 0% (559923200), duration 662, read/write 54/53 MB/s
INFO: status: 4% (34582364160/858993459200), sparse 0% (1105010688), duration 808, read/write 59/56 MB/s
INFO: status: 5% (43079499776/858993459200), sparse 0% (1914765312), duration 895, read/write 97/88 MB/s
INFO: status: 6% (51684376576/858993459200), sparse 0% (2278408192), duration 1083, read/write 45/43 MB/s
INFO: status: 7% (60164341760/858993459200), sparse 0% (3052077056), duration 1195, read/write 75/68 MB/s
INFO: status: 8% (68754604032/858993459200), sparse 0% (3817574400), duration 1328, read/write 64/58 MB/s
INFO: status: 9% (77358956544/858993459200), sparse 0% (4260134912), duration 1500, read/write 50/47 MB/s
INFO: status: 10% (85994373120/858993459200), sparse 0% (4676014080), duration 1692, read/write 44/42 MB/s
INFO: status: 11% (94544658432/858993459200), sparse 0% (5125955584), duration 1851, read/write 53/50 MB/s
INFO: status: 12% (103138197504/858993459200), sparse 0% (5691232256), duration 2036, read/write 46/43 MB/s
INFO: status: 13% (111707422720/858993459200), sparse 0% (6054514688), duration 2206, read/write 50/48 MB/s
INFO: status: 14% (120314003456/858993459200), sparse 0% (6477496320), duration 2385, read/write 48/45 MB/s
INFO: status: 15% (128877527040/858993459200), sparse 0% (7078903808), duration 2572, read/write 45/42 MB/s
INFO: status: 16% (137472245760/858993459200), sparse 0% (7686373376), duration 2761, read/write 45/42 MB/s
INFO: status: 17% (146029084672/858993459200), sparse 0% (8100249600), duration 2923, read/write 52/50 MB/s
INFO: status: 18% (154701201408/858993459200), sparse 1% (8635097088), duration 3086, read/write 53/49 MB/s
INFO: status: 19% (163269378048/858993459200), sparse 1% (8843653120), duration 3258, read/write 49/48 MB/s
INFO: status: 20% (171817369600/858993459200), sparse 1% (9132863488), duration 3433, read/write 48/47 MB/s
INFO: status: 21% (180408352768/858993459200), sparse 1% (9475977216), duration 3644, read/write 40/39 MB/s
INFO: status: 22% (189061201920/858993459200), sparse 1% (9902587904), duration 3822, read/write 48/46 MB/s
INFO: status: 23% (197572427776/858993459200), sparse 1% (10501562368), duration 3978, read/write 54/50 MB/s
INFO: status: 24% (206234845184/858993459200), sparse 1% (10814832640), duration 4147, read/write 51/49 MB/s
INFO: status: 25% (214749937664/858993459200), sparse 1% (11366502400), duration 4320, read/write 49/46 MB/s
INFO: status: 26% (223400689664/858993459200), sparse 1% (11837927424), duration 4515, read/write 44/41 MB/s
INFO: status: 27% (231993901056/858993459200), sparse 1% (12327768064), duration 4700, read/write 46/43 MB/s
INFO: status: 28% (240541302784/858993459200), sparse 1% (12825186304), duration 4885, read/write 46/43 MB/s
INFO: status: 29% (249210011648/858993459200), sparse 1% (13400535040), duration 5051, read/write 52/48 MB/s
INFO: status: 30% (257700134912/858993459200), sparse 1% (13941284864), duration 5245, read/write 43/40 MB/s
INFO: status: 31% (267147214848/858993459200), sparse 2% (21462970368), duration 5297, read/write 181/37 MB/s
INFO: status: 32% (275495583744/858993459200), sparse 3% (29811322880), duration 5304, read/write 1192/0 MB/s
INFO: status: 33% (283831369728/858993459200), sparse 4% (38147092480), duration 5311, read/write 1190/0 MB/s
INFO: status: 34% (292163420160/858993459200), sparse 5% (46479130624), duration 5318, read/write 1190/0 MB/s
INFO: status: 35% (301604143104/858993459200), sparse 6% (55919833088), duration 5326, read/write 1180/0 MB/s
INFO: status: 36% (309905653760/858993459200), sparse 7% (64221261824), duration 5333, read/write 1185/0 MB/s
INFO: status: 37% (318060363776/858993459200), sparse 8% (72375959552), duration 5340, read/write 1164/0 MB/s
INFO: status: 38% (327523237888/858993459200), sparse 9% (81838682112), duration 5348, read/write 1182/0 MB/s
INFO: status: 39% (335807315968/858993459200), sparse 10% (90122014720), duration 5355, read/write 1183/0 MB/s
INFO: status: 40% (344151752704/858993459200), sparse 11% (98466435072), duration 5362, read/write 1192/0 MB/s
INFO: status: 41% (352448348160/858993459200), sparse 12% (106762952704), duration 5369, read/write 1185/0 MB/s
INFO: status: 42% (360827387904/858993459200), sparse 12% (107137028096), duration 5608, read/write 35/33 MB/s
INFO: status: 43% (369413783552/858993459200), sparse 12% (107580534784), duration 5783, read/write 49/46 MB/s
INFO: status: 44% (377970425856/858993459200), sparse 12% (108216217600), duration 5943, read/write 53/49 MB/s
INFO: status: 45% (386607480832/858993459200), sparse 12% (108580179968), duration 6140, read/write 43/41 MB/s
INFO: status: 46% (395213602816/858993459200), sparse 12% (109099565056), duration 6306, read/write 51/48 MB/s
INFO: status: 47% (403759235072/858993459200), sparse 12% (109727502336), duration 6500, read/write 44/40 MB/s
INFO: status: 48% (412318498816/858993459200), sparse 12% (110215389184), duration 6708, read/write 41/38 MB/s
INFO: status: 49% (420923834368/858993459200), sparse 12% (110716325888), duration 6949, read/write 35/33 MB/s
INFO: status: 50% (429553811456/858993459200), sparse 12% (111125037056), duration 7245, read/write 29/27 MB/s
ERROR: VM 100 qmp command 'query-backup' failed - got timeout
INFO: aborting backup job
ERROR: Backup of VM 100 failed - VM 100 qmp command 'query-backup' failed - got timeout
INFO: Failed at 2022-05-19 02:33:46
INFO: Backup job finished with errors

TASK ERROR: job errors

I looked in /var/lib/vz/dump and saw that actually dumps from May are excist. Are they full functional and the "error" is a bug?

Hopefully somebody could help me!

Kind regards

Richard

 

[thepece]

Hi, guys.

We have the same issue:
INFO: trying to get global lock - waiting...
INFO: got global lock
INFO: starting new backup job: vzdump 102 --notes-template '{{guestname}}' --mailnotification always --storage Backup_VM --quiet 1 --compress lzo --mode snapshot
INFO: Starting Backup of VM 102 (qemu)
INFO: Backup started at 2022-10-26 03:54:17
INFO: status = running
INFO: VM Name: VM2-0029
INFO: include disk 'sata0' 'local-lvm:vm-102-disk-0' 70G
INFO: backup mode: snapshot
INFO: ionice priority: 7
INFO: creating vzdump archive '/mnt/pve/Backup_VM/dump/vzdump-qemu-102-2022_10_26-03_54_17.vma.lzo'
INFO: issuing guest-agent 'fs-freeze' command
INFO: issuing guest-agent 'fs-thaw' command
INFO: started backup task '3c66e840-5416-4e2e-b706-03e428f6825d'
INFO: resuming VM again
INFO: 2% (1.7 GiB of 70.0 GiB) in 3s, read: 584.8 MiB/s, write: 571.7 MiB/s
INFO: 4% (2.9 GiB of 70.0 GiB) in 6s, read: 410.8 MiB/s, write: 409.1 MiB/s
INFO: 6% (4.4 GiB of 70.0 GiB) in 9s, read: 516.0 MiB/s, write: 513.7 MiB/s
INFO: 8% (5.9 GiB of 70.0 GiB) in 12s, read: 496.6 MiB/s, write: 493.3 MiB/s
INFO: 9% (6.6 GiB of 70.0 GiB) in 15s, read: 229.6 MiB/s, write: 221.1 MiB/s
ERROR: VM 102 qmp command 'query-backup' failed - got timeout
INFO: aborting backup job
INFO: resuming VM again
ERROR: Backup of VM 102 failed - VM 102 qmp command 'query-backup' failed - got timeout
INFO: Failed at 2022-10-26 05:22:39
INFO: Backup job finished with errors
TASK ERROR: job errors

pve-manager/7.2-11/b76d3178

Is there a fix?

Regards.