Backups servers Loops

H

hemant

Member
Feb 28, 2022
9
0
6
52
Hi All,

I installed a fresh server in proxmox 7 VE with a VM. Addes my storage (NFS on synology) and scheduled a backup.
My schedule is Mon-Friday @ 14:00 and retention is set to last 2 backups.

When the backup starts (takes about 5 min) it restarts again once backup is completed while Next run is scheduled for the next they it continuously backups my VM.
While I don't have a job that backups constantly but only once a days.

Does anyone know why is?

Hemant
 
ok looks normal enough, can you also post the task logs of some of those backup jobs? (the first few lines (~10-20) each should be enough)
 
no i meant the content of the task logs. please double click on a task and copy the first few lines
please post those of 2 or 3 tasks

also what i just noticed:

hemant said:
I installed a fresh server in proxmox 7 VE with a VM.
Click to expand...
can you show the output of 'pveversion -v' ? maybe do an upgrade to the latest 7.0 version?
 
ok her tasklog:

Initial job:
INFO: starting new backup job: vzdump 100 --mailnotification always --node proxmox --compress zstd --mode snapshot --mailto XXX --all 0 --storage HAGPAZ1-S-10
INFO: Starting Backup of VM 100 (qemu)
INFO: Backup started at 2022-02-28 16:02:51
INFO: status = running
INFO: VM Name: HAGPAZ1-S-07
INFO: include disk 'sata0' 'local:100/vm-100-disk-0.raw' 52M
INFO: include disk 'sata1' 'local:100/vm-100-disk-1.raw' 25G
INFO: backup mode: snapshot
INFO: ionice priority: 7
INFO: creating vzdump archive '/mnt/pve/HAGPAZ1-S-10/dump/vzdump-qemu-100-2022_02_28-16_02_51.vma.zst'
INFO: started backup task 'fa8b001d-b440-40ff-b680-4f5238ffd017'
INFO: resuming VM again
INFO: backup is sparse: 17.56 GiB (70%) total zero data
INFO: transferred 25.05 GiB in 222 seconds (115.5 MiB/s)
INFO: archive file size: 2.90GB
INFO: prune older backups with retention: keep-last=2
INFO: removing backup 'HAGPAZ1-S-10:backup/vzdump-qemu-100-2022_02_28-16_02_51.vma.zst'
INFO: pruned 1 backup(s) not covered by keep-retention policy
INFO: Finished Backup of VM 100 (00:03:44)
INFO: Backup finished at 2022-02-28 16:15:02
INFO: Backup job finished successfully
TASK OK

Once finished restarts:
INFO: starting new backup job: vzdump 100 --storage HAGPAZ1-S-10 --compress zstd --quiet 1 --mailto xxx --node proxmox --prune-backups 'keep-last=2' --mode snapshot --mailnotification always
INFO: Starting Backup of VM 100 (qemu)
INFO: Backup started at 2022-02-28 16:07:19
INFO: status = running
INFO: VM Name: HAGPAZ1-S-07
INFO: include disk 'sata0' 'local:100/vm-100-disk-0.raw' 52M
INFO: include disk 'sata1' 'local:100/vm-100-disk-1.raw' 25G
INFO: backup mode: snapshot
INFO: ionice priority: 7
INFO: creating vzdump archive '/mnt/pve/HAGPAZ1-S-10/dump/vzdump-qemu-100-2022_02_28-16_07_19.vma.zst'
INFO: started backup task '4bbd0b5d-775f-40e5-b110-1a12d6d02355'
INFO: resuming VM again
INFO: backup is sparse: 17.56 GiB (70%) total zero data
INFO: transferred 25.05 GiB in 220 seconds (116.6 MiB/s)
INFO: archive file size: 2.91GB
INFO: prune older backups with retention: keep-last=2
INFO: removing backup 'HAGPAZ1-S-10:backup/vzdump-qemu-100-2022_02_28-15_00_04.vma.zst'
INFO: removing backup 'HAGPAZ1-S-10:backup/vzdump-qemu-100-2022_02_28-15_04_04.vma.zst'
INFO: removing backup 'HAGPAZ1-S-10:backup/vzdump-qemu-100-2022_02_28-15_08_29.vma.zst'
INFO: removing backup 'HAGPAZ1-S-10:backup/vzdump-qemu-100-2022_02_28-15_17_03.vma.zst'
INFO: removing backup 'HAGPAZ1-S-10:backup/vzdump-qemu-100-2022_02_28-15_36_17.vma.zst'
INFO: pruned 5 backup(s) not covered by keep-retention policy
INFO: Finished Backup of VM 100 (00:03:50)
INFO: Backup finished at 2022-02-28 16:11:09
INFO: Backup job finished successfully
TASK OK

And again:
INFO: starting new backup job: vzdump 100 --quiet 1 --storage HAGPAZ1-S-10 --compress zstd --prune-backups 'keep-last=2' --mode snapshot --mailnotification always --mailto xxx --node proxmox
INFO: Starting Backup of VM 100 (qemu)
INFO: Backup started at 2022-02-28 16:11:18
INFO: status = running
INFO: VM Name: HAGPAZ1-S-07
INFO: include disk 'sata0' 'local:100/vm-100-disk-0.raw' 52M
INFO: include disk 'sata1' 'local:100/vm-100-disk-1.raw' 25G
INFO: backup mode: snapshot
INFO: ionice priority: 7
INFO: creating vzdump archive '/mnt/pve/HAGPAZ1-S-10/dump/vzdump-qemu-100-2022_02_28-16_11_18.vma.zst'
INFO: started backup task 'abde2b00-b957-48c0-8acb-e7134e6de2d1'
INFO: resuming VM again
INFO: backup is sparse: 17.56 GiB (70%) total zero data
INFO: transferred 25.05 GiB in 213 seconds (120.4 MiB/s)
INFO: archive file size: 2.90GB
INFO: Finished Backup of VM 100 (00:03:34)
INFO: Backup finished at 2022-02-28 16:06:25
INFO: Backup job finished successfully
TASK OK


Currently disabled the job
 
Last edited:
can you post the output of
Code: 
cat /etc/pve/jobs.cfg
cat /var/lib/pve-manager/jobs/vzdump-backup-*.json
 
Hi Sure:

vzdump: backup-adce29aa-699b
schedule mon..fri 14:00
compress zstd
enabled 0
mailnotification always
mailto xxxxxxx
mode snapshot
node proxmox
prune-backups keep-last=2
storage HAGPAZ1-S-10
vmid 100

and:

{"msg":"OK","state":"stopped","upid":"UPID:proxmox:000E2B95:0295E20C:621CEBF3:vzdump:100:root@pam:","updated":1646064327}root@proxmox:~



Job is disabled at the moment.

Cheers,
Hemant
 
looks all normal... can you post the journal from a time where it ran multiple times? maybe there is something useful in there...
 
you can print the journal with 'journalctl' and can limit it to a timeframe with the '--since' and '--until' options
 
thanks!

Here you go!

Feb 28 14:00:02 proxmox pvescheduler[904827]: <root@pam> starting task UPID:proxmox:000DCE7C:02879306:621CC752:vzdump:100:root@pam:
Feb 28 14:00:02 proxmox pvescheduler[904828]: INFO: starting new backup job: vzdump 100 --prune-backups 'keep-last=2' --mailnotificati>
Feb 28 14:00:03 proxmox pvescheduler[904828]: INFO: Starting Backup of VM 100 (qemu)
Feb 28 14:02:31 proxmox postfix/qmgr[1398]: 4052315FCAE: from=<root@mail.@xxxxxxxx.net>, size=12969, nrcpt=1 (queue active)
Feb 28 14:02:31 proxmox postfix/smtp[905224]: warning: relayhost configuration problem
Feb 28 14:02:31 proxmox postfix/smtp[905224]: 4052315FCAE: to=<xxxxx@@xxxxxxxx.net>, relay=none, delay=323943, delays=323943/0.38/0.01/0,>
Feb 28 14:04:08 proxmox pvescheduler[904828]: INFO: Finished Backup of VM 100 (00:04:05)
Feb 28 14:04:08 proxmox pvescheduler[904828]: INFO: Backup job finished successfully
Feb 28 14:04:08 proxmox postfix/pickup[894493]: 83FB515FCC7: uid=0 from=<root>
Feb 28 14:04:08 proxmox postfix/cleanup[905475]: 83FB515FCC7: message-id=<20220228130408.83FB515FCC7@proxmox.@xxxxxxxx.net>
Feb 28 14:04:08 proxmox postfix/qmgr[1398]: 83FB515FCC7: from=<root@mail.@xxxxxxxx.net>, size=13599, nrcpt=1 (queue active)
Feb 28 14:04:08 proxmox postfix/smtp[905224]: warning: relayhost configuration problem
Feb 28 14:04:08 proxmox postfix/smtp[905224]: 83FB515FCC7: to=<xxxxx@@xxxxxxxx.net>, relay=none, delay=0.06, delays=0.05/0/0/0, dsn=4.3.5>
Feb 28 14:04:41 proxmox pvedaemon[1434]: <root@pam> successful auth for user 'root@pam'
Feb 28 14:05:02 proxmox pvescheduler[905589]: <root@pam> starting task UPID:proxmox:000DD176:0288083C:621CC87E:vzdump:100:root@pam:
Feb 28 14:05:02 proxmox pvescheduler[905590]: INFO: starting new backup job: vzdump 100 --mode snapshot --mailnotification always --pr>
Feb 28 14:05:02 proxmox pvescheduler[905590]: INFO: Starting Backup of VM 100 (qemu)
Feb 28 14:09:09 proxmox pvescheduler[905590]: INFO: Finished Backup of VM 100 (00:04:07)
Feb 28 14:09:09 proxmox pvescheduler[905590]: INFO: Backup job finished successfully
Feb 28 14:09:09 proxmox postfix/pickup[894493]: DF5BF15FCCC: uid=0 from=<root>
Feb 28 14:09:09 proxmox postfix/cleanup[906220]: DF5BF15FCCC: message-id=<20220228130909.DF5BF15FCCC@proxmox.@xxxxxxxx.net>
Feb 28 14:09:09 proxmox postfix/qmgr[1398]: DF5BF15FCCC: from=<root@mail.@xxxxxxxx.net>, size=13603, nrcpt=1 (queue active)
Feb 28 14:09:09 proxmox postfix/smtp[906222]: warning: relayhost configuration problem
Feb 28 14:09:09 proxmox postfix/smtp[906222]: DF5BF15FCCC: to=<xxxxx@@xxxxxxxx.net>, relay=none, delay=0.08, delays=0.05/0.03/0.01/0, dsn>
Feb 28 14:10:02 proxmox pvescheduler[906336]: <root@pam> starting task UPID:proxmox:000DD461:02887D85:621CC9AA:vzdump:100:root@pam:
Feb 28 14:10:02 proxmox pvescheduler[906337]: INFO: starting new backup job: vzdump 100 --mailnotification always --mode snapshot --pr>
Feb 28 14:10:03 proxmox pvescheduler[906337]: INFO: Starting Backup of VM 100 (qemu)
Feb 28 14:12:31 proxmox postfix/qmgr[1398]: 83FB515FCC7: from=<root@mail.@xxxxxxxx.net>, size=13599, nrcpt=1 (queue active)
Feb 28 14:12:31 proxmox postfix/smtp[906722]: warning: relayhost configuration problem
Feb 28 14:12:31 proxmox postfix/smtp[906722]: 83FB515FCC7: to=<xxxxx@xxxx>, relay=none, delay=503, delays=503/0.2/0.01/0, dsn=4.>
Feb 28 14:14:00 proxmox pvescheduler[906337]: INFO: Finished Backup of VM 100 (00:03:57)
 
mhmm nothing out of the ordinary here (beside that it runs every 5 min...)
does the 'next run' column in the backup list work correctly ? (as in, what is the next time it shows?)

i really have no idea whats going wrong here, it's like the calculation of the next event is broken somehow...
can you post the full output of 'pveversion -v' maybe?
 
You must log in or register to reply here.
Top Bottom