<html><body><div style="font-family: courier new,courier,monaco,monospace,sans-serif; font-size: 10pt; color: #000000"><div>Hello,<br></div><div><br data-mce-bogus="1"></div><div>I'm having trouble with the vzdump backup process timing out. It works fine when I run backup manually, but when a scheduled run happens it times out every time. I'm running Proxmox 3.4-11. Here is the log file output<br data-mce-bogus="1"></div><div><br data-mce-bogus="1"></div><div><br data-mce-bogus="1"></div><div><div><table border="1" cellpadding="3"><tbody><tr><td>VMID</td><td>NAME</td><td>STATUS</td><td>TIME</td><td>SIZE</td><td>FILENAME</td></tr><tr><td>101</td><td>vm-101<br></td><td><span style="color: red;" data-mce-style="color: red;" color="red">FAILED</span></td><td>00:12:07</td><td colspan="2">VM 101 qmp command 'query-backup' failed - got timeout</td></tr><tr><td>102</td><td>vm-102<br></td><td><span style="color: red;" data-mce-style="color: red;" color="red">FAILED</span></td><td>01:24:37</td><td colspan="2">VM 102 qmp command 'query-backup' failed - got timeout</td></tr><tr><td colspan="3" align="left">TOTAL</td><td>01:36:44</td><td>0KB</td><td></td></tr></tbody></table><br><br> Detailed backup logs:<br> <br><pre>vzdump 101 102 --mailnotification always --quiet 1 --mailto <span class="Object" role="link" id="OBJ_PREFIX_DWT5981_ZmEmailObjectHandler">user@email.com</span> --mode snapshot --compress lzo --storage vmbackup
101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5982_com_zimbra_date">Nov 07</span> 05:00:01 INFO: Starting Backup of VM 101 (qemu)
101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5983_com_zimbra_date">Nov 07</span> 05:00:01 INFO: status = running
101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5984_com_zimbra_date">Nov 07</span> 05:00:01 INFO: update VM 101: -lock backup
101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5985_com_zimbra_date">Nov 07</span> 05:00:01 INFO: backup mode: snapshot
101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5986_com_zimbra_date">Nov 07</span> 05:00:01 INFO: ionice priority: 7
101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5987_com_zimbra_date">Nov 07</span> 05:00:01 INFO: snapshots found (not included into backup)
101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5988_com_zimbra_date">Nov 07</span> 05:00:01 INFO: creating archive '/mnt/vmbackup/vmbackup/dump/vzdump-qemu-101-2015_11_07-05_00_01.vma.lzo'
101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5989_com_zimbra_date">Nov 07</span> 05:00:01 INFO: started backup task '3570da3c-12cf-4729-9a2c-54d3ce6d5999'
101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5990_com_zimbra_date">Nov 07</span> 05:00:04 INFO: status: 0% (89784320/966367641600), sparse 0% (18362368), duration 3, 29/23 MB/s
101: <span style="color: red;" data-mce-style="color: red;" color="red"><span class="Object" role="link" id="OBJ_PREFIX_DWT5991_com_zimbra_date">Nov 07</span> 05:12:01 ERROR: VM 101 qmp command 'query-backup' failed - got timeout
</span>101: <span class="Object" role="link" id="OBJ_PREFIX_DWT5992_com_zimbra_date">Nov 07</span> 05:12:01 INFO: aborting backup job
101: <span style="color: red;" data-mce-style="color: red;" color="red"><span class="Object" role="link" id="OBJ_PREFIX_DWT5993_com_zimbra_date">Nov 07</span> 05:12:08 ERROR: Backup of VM 101 failed - VM 101 qmp command 'query-backup' failed - got timeout
</span>
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT5994_com_zimbra_date">Nov 07</span> 05:12:08 INFO: Starting Backup of VM 102 (qemu)
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT5995_com_zimbra_date">Nov 07</span> 05:12:08 INFO: status = running
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT5996_com_zimbra_date">Nov 07</span> 05:12:08 INFO: update VM 102: -lock backup
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT5997_com_zimbra_date">Nov 07</span> 05:12:08 INFO: backup mode: snapshot
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT5998_com_zimbra_date">Nov 07</span> 05:12:08 INFO: ionice priority: 7
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT5999_com_zimbra_date">Nov 07</span> 05:12:08 INFO: snapshots found (not included into backup)
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6000_com_zimbra_date">Nov 07</span> 05:12:08 INFO: creating archive '/mnt/vmbackup/vmbackup/dump/vzdump-qemu-102-2015_11_07-05_12_08.vma.lzo'
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6001_com_zimbra_date">Nov 07</span> 05:12:08 INFO: started backup task 'b68d833a-5d30-4c86-96af-7bc51eba2a40'
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6002_com_zimbra_date">Nov 07</span> 05:12:11 INFO: status: 0% (155910144/34359738368), sparse 0% (32190464), duration 3, 51/41 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6003_com_zimbra_date">Nov 07</span> 05:12:14 INFO: status: 1% (480772096/34359738368), sparse 0% (257622016), duration 6, 108/33 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6004_com_zimbra_date">Nov 07</span> 05:12:19 INFO: status: 2% (757596160/34359738368), sparse 0% (327237632), duration 11, 55/41 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6005_com_zimbra_date">Nov 07</span> 05:12:24 INFO: status: 3% (1072496640/34359738368), sparse 1% (442957824), duration 16, 62/39 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6006_com_zimbra_date">Nov 07</span> 05:12:27 INFO: status: 4% (1520762880/34359738368), sparse 2% (778567680), duration 19, 149/37 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6007_com_zimbra_date">Nov 07</span> 05:12:30 INFO: status: 6% (2307260416/34359738368), sparse 4% (1399537664), duration 22, 262/55 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6008_com_zimbra_date">Nov 07</span> 05:12:33 INFO: status: 15% (5380374528/34359738368), sparse 12% (4320960512), duration 25, 1024/50 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6009_com_zimbra_date">Nov 07</span> 05:12:36 INFO: status: 16% (5709234176/34359738368), sparse 12% (4320960512), duration 28, 109/109 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6010_com_zimbra_date">Nov 07</span> 05:12:39 INFO: status: 17% (6025576448/34359738368), sparse 12% (4320960512), duration 31, 105/105 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6011_com_zimbra_date">Nov 07</span> 05:12:42 INFO: status: 18% (6337789952/34359738368), sparse 12% (4320960512), duration 34, 104/104 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6012_com_zimbra_date">Nov 07</span> 05:12:45 INFO: status: 19% (6534725632/34359738368), sparse 12% (4321046528), duration 37, 65/65 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6013_com_zimbra_date">Nov 07</span> 05:12:49 INFO: status: 20% (6898581504/34359738368), sparse 12% (4451659776), duration 41, 90/58 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6014_com_zimbra_date">Nov 07</span> 05:12:54 INFO: status: 21% (7218397184/34359738368), sparse 12% (4453482496), duration 46, 63/63 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6015_com_zimbra_date">Nov 07</span> 05:12:58 INFO: status: 22% (7628783616/34359738368), sparse 12% (4453482496), duration 50, 102/102 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6016_com_zimbra_date">Nov 07</span> 05:13:01 INFO: status: 23% (7951286272/34359738368), sparse 12% (4453539840), duration 53, 107/107 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6017_com_zimbra_date">Nov 07</span> 05:13:04 INFO: status: 24% (8299151360/34359738368), sparse 12% (4453539840), duration 56, 115/115 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6018_com_zimbra_date">Nov 07</span> 05:13:07 INFO: status: 25% (8620933120/34359738368), sparse 12% (4453539840), duration 59, 107/107 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6019_com_zimbra_date">Nov 07</span> 05:13:11 INFO: status: 26% (9005498368/34359738368), sparse 13% (4578058240), duration 63, 96/65 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6020_com_zimbra_date">Nov 07</span> 05:13:15 INFO: status: 27% (9389604864/34359738368), sparse 14% (4811132928), duration 67, 96/37 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6021_com_zimbra_date">Nov 07</span> 05:13:18 INFO: status: 32% (11152326656/34359738368), sparse 18% (6514954240), duration 70, 587/19 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6022_com_zimbra_date">Nov 07</span> 05:13:22 INFO: status: 37% (12923699200/34359738368), sparse 23% (8167755776), duration 74, 442/29 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6023_com_zimbra_date">Nov 07</span> 05:13:25 INFO: status: 44% (15330770944/34359738368), sparse 30% (10496708608), duration 77, 802/26 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6024_com_zimbra_date">Nov 07</span> 05:13:28 INFO: status: 45% (15584067584/34359738368), sparse 30% (10590322688), duration 80, 84/53 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6025_com_zimbra_date">Nov 07</span> 05:13:31 INFO: status: 51% (17590910976/34359738368), sparse 36% (12529782784), duration 83, 668/22 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6026_com_zimbra_date">Nov 07</span> 05:13:37 INFO: status: 52% (18109235200/34359738368), sparse 37% (12835610624), duration 89, 86/35 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6027_com_zimbra_date">Nov 07</span> 05:13:40 INFO: status: 63% (21735669760/34359738368), sparse 47% (16426790912), duration 92, 1208/11 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6028_com_zimbra_date">Nov 07</span> 05:13:43 INFO: status: 69% (24018681856/34359738368), sparse 54% (18647670784), duration 95, 761/20 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6029_com_zimbra_date">Nov 07</span> 05:13:46 INFO: status: 70% (24122163200/34359738368), sparse 54% (18647748608), duration 98, 34/34 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6030_com_zimbra_date">Nov 07</span> 05:13:54 INFO: status: 71% (24424153088/34359738368), sparse 54% (18647949312), duration 106, 37/37 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6031_com_zimbra_date">Nov 07</span> 05:14:01 INFO: status: 72% (24744361984/34359738368), sparse 54% (18655653888), duration 113, 45/44 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6032_com_zimbra_date">Nov 07</span> 05:14:11 INFO: status: 73% (25091178496/34359738368), sparse 54% (18660720640), duration 123, 34/34 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6033_com_zimbra_date">Nov 07</span> 05:14:19 INFO: status: 74% (25450774528/34359738368), sparse 54% (18667048960), duration 131, 44/44 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6034_com_zimbra_date">Nov 07</span> 05:14:25 INFO: status: 75% (25770065920/34359738368), sparse 54% (18693558272), duration 137, 53/48 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6035_com_zimbra_date">Nov 07</span> 05:14:32 INFO: status: 76% (26179338240/34359738368), sparse 54% (18852700160), duration 144, 58/35 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6036_com_zimbra_date">Nov 07</span> 05:14:36 INFO: status: 77% (26544570368/34359738368), sparse 54% (18874642432), duration 148, 91/85 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6037_com_zimbra_date">Nov 07</span> 05:14:39 INFO: status: 78% (27035369472/34359738368), sparse 55% (19177672704), duration 151, 163/62 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6038_com_zimbra_date">Nov 07</span> 05:14:42 INFO: status: 79% (27337424896/34359738368), sparse 55% (19177672704), duration 154, 100/100 MB/s
102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6039_com_zimbra_date">Nov 07</span> 05:14:45 INFO: status: 80% (27601862656/34359738368), sparse 55% (19180879872), duration 157, 88/87 MB/s
102: <span style="color: red;" data-mce-style="color: red;" color="red"><span class="Object" role="link" id="OBJ_PREFIX_DWT6040_com_zimbra_date">Nov 07</span> 06:36:38 ERROR: VM 102 qmp command 'query-backup' failed - got timeout
</span>102: <span class="Object" role="link" id="OBJ_PREFIX_DWT6041_com_zimbra_date">Nov 07</span> 06:36:38 INFO: aborting backup job
102: <span style="color: red;" data-mce-style="color: red;" color="red"><span class="Object" role="link" id="OBJ_PREFIX_DWT6042_com_zimbra_date">Nov 07</span> 06:36:45 ERROR: Backup of VM 102 failed - VM 102 qmp command 'query-backup' failed - got timeout
</span>
</pre></div></div><div><br></div><div data-marker="__SIG_PRE__"><br></div></div></body></html>