borgbase / vorta

Desktop Backup Client for Borg Backup
https://vorta.borgbase.com
GNU General Public License v3.0
2.01k stars 131 forks source link

Sometimes two hourly backups are created #1128

Closed kntasks closed 2 years ago

kntasks commented 2 years ago

Hi,

Vorta/Borg sometimes makes two hourly backups, instead of one. When it does do one backup, a notification appears on my desktop saying 'error during backup creation'. However, when I check the 'archives' tab I can see an archive created (only one - instead of the two). When two archives are created no error appears.

No particular steps are needed to reproduce issue. This has been happening since I installed vorta/borg.

If only one backup is created then this is the error that appears on my desktop:

scrot 2021 12 02_14 15 32--cropped-redacted-again

this is the error that can be seen on vorta interface:

scrot 2021 11 29_22 53 44--archive-already-exists-cropped

When multiple (two) backups are done by vorta (this is decided by Vorta - I've not configured Borg/Vorta at any point to do two hourly backups) the log (within Vorta interface) reports:

scrot 2021 12 05_09 37 03--log-returncode-cropped

The vorta.log file:

2021-12-04 23:47:15,257 - vorta.scheduler - DEBUG - Refreshing all scheduler timers
2021-12-04 23:47:15,264 - vorta.scheduler - INFO - Setting timer for profile 4
2021-12-04 23:47:15,265 - vorta.scheduler - DEBUG - Scheduling next run for 2021-12-05 00:28:00
2021-12-05 00:02:15,257 - vorta.scheduler - DEBUG - Refreshing all scheduler timers
2021-12-05 00:02:15,264 - vorta.scheduler - INFO - Setting timer for profile 4
2021-12-05 00:02:15,265 - vorta.scheduler - DEBUG - Scheduling next run for 2021-12-05 00:28:00
2021-12-05 00:17:15,258 - vorta.scheduler - DEBUG - Refreshing all scheduler timers
2021-12-05 00:17:15,264 - vorta.scheduler - INFO - Setting timer for profile 4
2021-12-05 00:17:15,266 - vorta.scheduler - DEBUG - Scheduling next run for 2021-12-05 00:28:00
2021-12-05 00:28:00,258 - vorta.scheduler - DEBUG - Start scheduled backup for profile 4
2021-12-05 00:28:00,263 - vorta.scheduler - INFO - Starting background backup for backup-home
2021-12-05 00:28:00,266 - vorta.notifications - DEBUG - success notifications suppressed
2021-12-05 00:28:00,271 - vorta.keyring.abc - DEBUG - Only available on macOS
2021-12-05 00:28:00,276 - asyncio - DEBUG - Using selector: EpollSelector
2021-12-05 00:28:00,277 - vorta.borg.borg_job - DEBUG - Using VortaSecretStorageKeyring keyring to store passwords.
2021-12-05 00:28:00,282 - asyncio - DEBUG - Using selector: EpollSelector
2021-12-05 00:28:00,286 - root - DEBUG - Found 1 passwords matching repo URL.
2021-12-05 00:28:00,372 - vorta.scheduler - INFO - Preparation for backup successful.
2021-12-05 00:28:00,373 - vorta.scheduler - DEBUG - Start scheduled backup for profile 4
2021-12-05 00:28:00,375 - vorta.scheduler - INFO - Starting background backup for backup-home
2021-12-05 00:28:00,377 - vorta.notifications - DEBUG - success notifications suppressed
2021-12-05 00:28:00,379 - vorta.borg.borg_job - INFO - Running command /app/bin/borg create --list --progress --info --log-json --json --filter=AM -C lz4 --exclude-from /tmp/tmp_j64zheh /media/data1/backup/borg::k-void-backup-home-2021-12-05T00:28:00 /home/ /media/data/Documents_data_drive/
2021-12-05 00:28:00,379 - vorta.keyring.abc - DEBUG - Only available on macOS
2021-12-05 00:28:00,391 - asyncio - DEBUG - Using selector: EpollSelector
2021-12-05 00:28:00,393 - vorta.borg.borg_job - DEBUG - Using VortaSecretStorageKeyring keyring to store passwords.
2021-12-05 00:28:00,396 - asyncio - DEBUG - Using selector: EpollSelector
2021-12-05 00:28:00,400 - root - DEBUG - Found 1 passwords matching repo URL.
2021-12-05 00:28:00,439 - vorta.scheduler - INFO - Preparation for backup successful.
2021-12-05 00:28:01,297 - vorta.borg.borg_job - INFO - Creating archive at "/media/data1/backup/borg::k-void-backup-home-2021-12-05T00:28:00"
2021-12-05 00:28:23,456 - vorta.borg.borg_job - WARNING - /home/user1: scandir: [Errno 13] Permission denied: '/home/user1'
2021-12-05 00:28:28,367 - vorta.borg.borg_job - INFO - Running command /app/bin/borg create --list --progress --info --log-json --json --filter=AM -C lz4 --exclude-from /tmp/tmpd92hshyx /media/data1/backup/borg::k-void-backup-home-2021-12-05T00:28:00 /home/ /media/data/Documents_data_drive/
2021-12-05 00:28:28,767 - vorta.notifications - DEBUG - success notifications suppressed
2021-12-05 00:28:28,767 - vorta.scheduler - INFO - Backup creation successful.
2021-12-05 00:28:28,769 - vorta.scheduler - INFO - Doing post-backup jobs for backup-home
2021-12-05 00:28:28,771 - vorta.keyring.abc - DEBUG - Only available on macOS
2021-12-05 00:28:28,774 - asyncio - DEBUG - Using selector: EpollSelector
2021-12-05 00:28:28,775 - vorta.borg.borg_job - DEBUG - Using VortaSecretStorageKeyring keyring to store passwords.
2021-12-05 00:28:28,778 - asyncio - DEBUG - Using selector: EpollSelector
2021-12-05 00:28:28,781 - root - DEBUG - Found 1 passwords matching repo URL.
2021-12-05 00:28:28,798 - vorta.keyring.abc - DEBUG - Only available on macOS
2021-12-05 00:28:28,801 - asyncio - DEBUG - Using selector: EpollSelector
2021-12-05 00:28:28,802 - vorta.borg.borg_job - DEBUG - Using VortaSecretStorageKeyring keyring to store passwords.
2021-12-05 00:28:28,803 - asyncio - DEBUG - Using selector: EpollSelector
2021-12-05 00:28:28,807 - root - DEBUG - Found 1 passwords matching repo URL.
2021-12-05 00:28:28,825 - vorta.scheduler - INFO - Finished background task for profile backup-home
2021-12-05 00:28:28,828 - vorta.scheduler - INFO - Setting timer for profile 4
2021-12-05 00:28:28,829 - vorta.scheduler - DEBUG - Scheduling next run for 2021-12-05 01:28:00
2021-12-05 00:28:28,884 - vorta.borg.borg_job - INFO - Creating archive at "/media/data1/backup/borg::k-void-backup-home-2021-12-05T00:28:00"
2021-12-05 00:28:29,841 - vorta.borg.borg_job - ERROR - Archive k-void-backup-home-2021-12-05T00:28:00 already exists
2021-12-05 00:28:29,989 - vorta.borg.borg_job - INFO - Running command /app/bin/borg prune --list --info --log-json --keep-hourly 3 --keep-daily 3 --keep-weekly 1 --keep-monthly 3 --keep-yearly 2 --prefix k-void-backup-home- --keep-within 10H /media/data1/backup/borg
2021-12-05 00:28:30,369 - vorta.notifications - DEBUG - notification not suppressed
2021-12-05 00:28:30,373 - vorta.scheduler - ERROR - Error during backup creation.
2021-12-05 00:28:30,377 - vorta.scheduler - INFO - Setting timer for profile 4
2021-12-05 00:28:30,379 - vorta.scheduler - DEBUG - Scheduling next run for 2021-12-05 01:28:00
m3nu commented 2 years ago

Please update to the latest released version 0.8.2

kntasks commented 2 years ago

Please update to the latest released version 0.8.2

I updated using:

sudo flatpak update

Now only 1 backup archive created every hour.

Thank you.

m3nu commented 2 years ago

Excellent! Closing this issue.