B2 SNAPSHOT_DELETE failed to delete existing snapshot: File not present

Support
,
#1

Prune on B2 storage failed to delete an existing snapshot:

Running prune command from /share/CACHEDEV1_DATA/.qpkg/Duplicacy/.duplicacy-web/repositories/localhost/all
Options: [-log prune -storage Backblaze-B2 -id Carol -keep 30:365 -keep 7:90 -keep 1:7]
2021-06-18 00:36:06.130 INFO STORAGE_SET Storage set to b2://kgorlen-NAS0
2021-06-18 00:36:06.350 INFO BACKBLAZE_URL download URL is: https://f000.backblazeb2.com
2021-06-18 00:36:06.660 INFO RETENTION_POLICY Keep 1 snapshot every 30 day(s) if older than 365 day(s)
2021-06-18 00:36:06.660 INFO RETENTION_POLICY Keep 1 snapshot every 7 day(s) if older than 90 day(s)
2021-06-18 00:36:06.660 INFO RETENTION_POLICY Keep 1 snapshot every 1 day(s) if older than 7 day(s)
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 941 found
2021-06-18 00:36:28.049 INFO SNAPSHOT_INACTIVE Ignore snapshot Carol-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 941 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 942 found
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 942 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 943 found
2021-06-18 00:36:28.049 INFO SNAPSHOT_INACTIVE Ignore snapshot Keith-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 943 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 944 found
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 944 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 945 found
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 945 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 946 found
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 946 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO FOSSIL_COLLECT Fossil collection 947 found
2021-06-18 00:36:28.050 INFO SNAPSHOT_INACTIVE Ignore snapshot Household-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 947 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO FOSSIL_COLLECT Fossil collection 948 found
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 948 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO FOSSIL_COLLECT Fossil collection 949 found
2021-06-18 00:36:28.050 INFO SNAPSHOT_INACTIVE Ignore snapshot Joint-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 949 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO FOSSIL_COLLECT Fossil collection 950 found
2021-06-18 00:36:28.050 INFO SNAPSHOT_INACTIVE Ignore snapshot Household-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 950 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO SNAPSHOT_DELETE Deleting snapshot Carol at revision 159
2021-06-18 00:39:15.753 INFO FOSSIL_COLLECT Fossil collection 951 saved
2021-06-18 00:48:21.257 ERROR SNAPSHOT_DELETE Failed to delete the snapshot Carol at revision 159: URL request 'https://api000.backblazeb2.com/b2api/v1/b2_delete_file_version' returned 400 File not present: snapshots/Carol/159 4_zb3083158c24667c375580610_f114254b916f843c0_d20210320_m101411_c000_v0001078_t0048
Failed to delete the snapshot Carol at revision 159: URL request 'https://api000.backblazeb2.com/b2api/v1/b2_delete_file_version' returned 400 File not present: snapshots/Carol/159 4_zb3083158c24667c375580610_f114254b916f843c0_d20210320_m101411_c000_v0001078_t0048

The subsequent check command succeeded.

The previous copy command indicates that the snapshot existed at the time of the prune:

copy-20210617-022448.log:2021-06-17 02:24:49.446 INFO SNAPSHOT_EXIST Snapshot Carol at revision 159 already exists at the destination storage

I reran the prune command, but it didn’t attempt to delete snapshot Carol at revision 159.

#2

This is usually caused by two prune jobs running at the same time. The first prune job may have already deleted revision 159 and the second prune job couldn’t find this file.

#3

Only one prune command was running. Is the default to run more than one thread?

[~/.duplicacy-web/logs] # more prune-20210618-003*.log
Running prune command from /share/CACHEDEV1_DATA/.qpkg/Duplicacy/.duplicacy-web/repositories/localhost/all
Options: [-log prune -storage NAS0 -id Carol -keep 30:365 -keep 7:90 -keep 1:7]
2021-06-18 00:31:33.918 INFO STORAGE_SET Storage set to /share/TR-004/Duplicacy/storage
2021-06-18 00:31:33.920 INFO RETENTION_POLICY Keep 1 snapshot every 30 day(s) if older than 365 day(s)
2021-06-18 00:31:33.920 INFO RETENTION_POLICY Keep 1 snapshot every 7 day(s) if older than 90 day(s)
2021-06-18 00:31:33.920 INFO RETENTION_POLICY Keep 1 snapshot every 1 day(s) if older than 7 day(s)
2021-06-18 00:32:24.400 INFO FOSSIL_COLLECT Fossil collection 1 found
2021-06-18 00:32:24.400 INFO FOSSIL_POSTPONE Fossils from collection 1 can't be deleted because deletion criteria aren't met
2021-06-18 00:32:24.400 INFO FOSSIL_COLLECT Fossil collection 2 found
2021-06-18 00:32:24.400 INFO FOSSIL_POSTPONE Fossils from collection 2 can't be deleted because deletion criteria aren't met
2021-06-18 00:32:24.401 INFO SNAPSHOT_DELETE Deleting snapshot Carol at revision 159
2021-06-18 00:36:05.675 INFO FOSSIL_COLLECT Fossil collection 3 saved
2021-06-18 00:36:05.675 INFO SNAPSHOT_DELETE The snapshot Carol at revision 159 has been removed
Running prune command from /share/CACHEDEV1_DATA/.qpkg/Duplicacy/.duplicacy-web/repositories/localhost/all
Options: [-log prune -storage Backblaze-B2 -id Carol -keep 30:365 -keep 7:90 -keep 1:7]
2021-06-18 00:36:06.130 INFO STORAGE_SET Storage set to b2://kgorlen-NAS0
2021-06-18 00:36:06.350 INFO BACKBLAZE_URL download URL is: https://f000.backblazeb2.com
2021-06-18 00:36:06.660 INFO RETENTION_POLICY Keep 1 snapshot every 30 day(s) if older than 365 day(s)
2021-06-18 00:36:06.660 INFO RETENTION_POLICY Keep 1 snapshot every 7 day(s) if older than 90 day(s)
2021-06-18 00:36:06.660 INFO RETENTION_POLICY Keep 1 snapshot every 1 day(s) if older than 7 day(s)
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 941 found
2021-06-18 00:36:28.049 INFO SNAPSHOT_INACTIVE Ignore snapshot Carol-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 941 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 942 found
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 942 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 943 found
2021-06-18 00:36:28.049 INFO SNAPSHOT_INACTIVE Ignore snapshot Keith-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 943 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 944 found
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 944 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 945 found
2021-06-18 00:36:28.049 INFO FOSSIL_POSTPONE Fossils from collection 945 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.049 INFO FOSSIL_COLLECT Fossil collection 946 found
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 946 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO FOSSIL_COLLECT Fossil collection 947 found
2021-06-18 00:36:28.050 INFO SNAPSHOT_INACTIVE Ignore snapshot Household-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 947 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO FOSSIL_COLLECT Fossil collection 948 found
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 948 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO FOSSIL_COLLECT Fossil collection 949 found
2021-06-18 00:36:28.050 INFO SNAPSHOT_INACTIVE Ignore snapshot Joint-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 949 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO FOSSIL_COLLECT Fossil collection 950 found
2021-06-18 00:36:28.050 INFO SNAPSHOT_INACTIVE Ignore snapshot Household-Archive whose last revision was created 7 days ago
2021-06-18 00:36:28.050 INFO FOSSIL_POSTPONE Fossils from collection 950 can't be deleted because deletion criteria aren't met
2021-06-18 00:36:28.050 INFO SNAPSHOT_DELETE Deleting snapshot Carol at revision 159
2021-06-18 00:39:15.753 INFO FOSSIL_COLLECT Fossil collection 951 saved
2021-06-18 00:48:21.257 ERROR SNAPSHOT_DELETE Failed to delete the snapshot Carol at revision 159: URL request 'https://api000.backblazeb2.com/b2api/v1/b2_delete_file_version' returned 400 File not present: snapshots/Carol/159 4_zb3083158c24667c375580610_f114254b916f843c0_d20210320_m101411_c000_v0001078_t0048
Failed to delete the snapshot Carol at revision 159: URL request 'https://api000.backblazeb2.com/b2api/v1/b2_delete_file_version' returned 400 File not present: snapshots/Carol/159 4_zb3083158c24667c375580610_f114254b916f843c0_d20210320_m101411_c000_v0001078_t0048