Duplicati fails to connect after a while when running

Overtone7760 · March 14, 2025, 12:54pm

Hey there, I need some assistance. Duplicati disconnects and then crashes every time I run a backup or try to run a command (such as delete).
My target disk is full, so that might be the cause but I cannot run a delete command to get rid of old backup versions when it crashes everytime.

These are some recent logs below:

code = Busy (5), message = System.Data.SQLite.SQLiteException (0x800007AF): database is locked

database is locked

   at System.Data.SQLite.SQLite3.Step(SQLiteStatement stmt)

   at System.Data.SQLite.SQLiteDataReader.NextResult()

   at System.Data.SQLite.SQLiteDataReader..ctor(SQLiteCommand cmd, CommandBehavior behave)

   at System.Data.SQLite.SQLiteCommand.ExecuteReader(CommandBehavior behavior)

   at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64(IDbCommand self, Boolean writeLog, String cmd, Int64 defaultvalue, Object[] values)

   at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64(IDbCommand self, String cmd, Int64 defaultvalue, Object[] values)

   at Duplicati.Library.Main.Database.LocalDatabase..ctor(IDbConnection connection, String operation)

   at Duplicati.Library.Main.Database.LocalDatabase..ctor(String path, String operation, Boolean shouldclose)

   at Duplicati.Library.Main.Database.LocalListDatabase..ctor(String path)

   at Duplicati.Library.Main.Operation.ListFilesHandler.Run(IEnumerable`1 filterstrings, IFilter compositefilter)

   at Duplicati.Library.Main.Controller.<>c__DisplayClass24_0.<List>b__0(ListResults result)

   at Duplicati.Library.Main.Controller.RunAction[T](T result, String[]& paths, IFilter& filter, Action`1 method)

   at Duplicati.Library.Main.Controller.RunAction[T](T result, IFilter& filter, Action`1 method)

   at Duplicati.Library.Main.Controller.List(IEnumerable`1 filterstrings, IFilter filter)



   at Duplicati.Server.Runner.Run(IRunnerData data, Boolean fromQueue)

System.AggregateException: One or more errors occurred. (There's not enough disk space. : '\\?\D:\duplicati-i3c12e260d5844232aeb9a7b725879423.dindex.zip.aes' (There's not enough disk space. : '\\?\D:\duplicati-i3c12e260d5844232aeb9a7b725879423.dindex.zip.aes') (One or more errors occurred. (There's not enough disk space. : '\\?\D:\duplicati-i3c12e260d5844232aeb9a7b725879423.dindex.zip.aes')))

 ---> System.AggregateException: There's not enough disk space. : '\\?\D:\duplicati-i3c12e260d5844232aeb9a7b725879423.dindex.zip.aes' (There's not enough disk space. : '\\?\D:\duplicati-i3c12e260d5844232aeb9a7b725879423.dindex.zip.aes') (One or more errors occurred. (There's not enough disk space. : '\\?\D:\duplicati-i3c12e260d5844232aeb9a7b725879423.dindex.zip.aes'))

 ---> System.IO.IOException: There's not enough disk space. : '\\?\D:\duplicati-i3c12e260d5844232aeb9a7b725879423.dindex.zip.aes'

   at System.IO.RandomAccess.WriteAtOffset(SafeFileHandle handle, ReadOnlySpan`1 buffer, Int64 fileOffset)

   at System.IO.RandomAccess.<>c.<WriteAtOffsetAsync>b__21_0(ValueTuple`4 state)

   at System.Threading.AsyncOverSyncWithIoCancellation.InvokeAsync[TState](Action`1 action, TState state, CancellationToken cancellationToken)

   at System.Runtime.CompilerServices.PoolingAsyncValueTaskMethodBuilder`1.StateMachineBox`1.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)

   at System.Threading.Tasks.ValueTask.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)

--- End of stack trace from previous location ---

   at Duplicati.Library.Utility.Utility.CopyStreamAsync(Stream source, Stream target, Boolean tryRewindSource, CancellationToken cancelToken, Byte[] buf)

   at Duplicati.Library.Backend.File.PutAsync(String targetFilename, Stream sourceStream, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.DoPut(FileEntryItem item, IBackend backend, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.<>c__DisplayClass20_0.<<UploadFileAsync>b__0>d.MoveNext()

--- End of stack trace from previous location ---

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.DoWithRetry(Func`1 method, FileEntryItem item, Worker worker, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.DoWithRetry(Func`1 method, FileEntryItem item, Worker worker, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.UploadFileAsync(FileEntryItem item, Worker worker, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.UploadVolumeWriter(VolumeWriterBase volumeWriter, Worker worker, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.<Run>b__13_0(<>f__AnonymousType2`1 self)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.<Run>b__13_0(<>f__AnonymousType2`1 self)

   at CoCoL.AutomationExtensions.RunTask[T](T channels, Func`2 method, Boolean catchRetiredExceptions)

   at Duplicati.Library.Main.Operation.BackupHandler.FlushBackend(BackupResults result, IWriteChannel`1 uploadtarget, Task uploader)

   at Duplicati.Library.Main.Operation.BackupHandler.RunAsync(String[] sources, IFilter filter, CancellationToken token)

   --- End of inner exception stack trace ---

   at Duplicati.Library.Main.Operation.BackupHandler.RunAsync(String[] sources, IFilter filter, CancellationToken token)

 ---> (Inner Exception #1) System.AggregateException: One or more errors occurred. (There's not enough disk space. : '\\?\D:\duplicati-i3c12e260d5844232aeb9a7b725879423.dindex.zip.aes')

 ---> System.IO.IOException: There's not enough disk space. : '\\?\D:\duplicati-i3c12e260d5844232aeb9a7b725879423.dindex.zip.aes'

   at System.IO.RandomAccess.WriteAtOffset(SafeFileHandle handle, ReadOnlySpan`1 buffer, Int64 fileOffset)

   at System.IO.RandomAccess.<>c.<WriteAtOffsetAsync>b__21_0(ValueTuple`4 state)

   at System.Threading.AsyncOverSyncWithIoCancellation.InvokeAsync[TState](Action`1 action, TState state, CancellationToken cancellationToken)

   at System.Runtime.CompilerServices.PoolingAsyncValueTaskMethodBuilder`1.StateMachineBox`1.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token)

   at System.Threading.Tasks.ValueTask.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)

--- End of stack trace from previous location ---

   at Duplicati.Library.Utility.Utility.CopyStreamAsync(Stream source, Stream target, Boolean tryRewindSource, CancellationToken cancelToken, Byte[] buf)

   at Duplicati.Library.Backend.File.PutAsync(String targetFilename, Stream sourceStream, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.DoPut(FileEntryItem item, IBackend backend, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.<>c__DisplayClass20_0.<<UploadFileAsync>b__0>d.MoveNext()

--- End of stack trace from previous location ---

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.DoWithRetry(Func`1 method, FileEntryItem item, Worker worker, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.DoWithRetry(Func`1 method, FileEntryItem item, Worker worker, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.UploadFileAsync(FileEntryItem item, Worker worker, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.UploadVolumeWriter(VolumeWriterBase volumeWriter, Worker worker, CancellationToken cancelToken)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.<Run>b__13_0(<>f__AnonymousType2`1 self)

   at Duplicati.Library.Main.Operation.Backup.BackendUploader.<Run>b__13_0(<>f__AnonymousType2`1 self)

   at CoCoL.AutomationExtensions.RunTask[T](T channels, Func`2 method, Boolean catchRetiredExceptions)

   at Duplicati.Library.Main.Operation.BackupHandler.FlushBackend(BackupResults result, IWriteChannel`1 uploadtarget, Task uploader)

   at Duplicati.Library.Main.Operation.BackupHandler.RunAsync(String[] sources, IFilter filter, CancellationToken token)

   --- End of inner exception stack trace ---<---



   --- End of inner exception stack trace ---

   at CoCoL.ChannelExtensions.WaitForTaskOrThrow(Task task)

   at Duplicati.Library.Main.Operation.BackupHandler.Run(String[] sources, IFilter filter, CancellationToken token)

   at Duplicati.Library.Main.Controller.<>c__DisplayClass17_0.<Backup>b__0(BackupResults result)

   at Duplicati.Library.Main.Controller.RunAction[T](T result, String[]& paths, IFilter& filter, Action`1 method)

   at Duplicati.Library.Main.Controller.Backup(String[] inputsources, IFilter filter)

   at Duplicati.Server.Runner.Run(IRunnerData data, Boolean fromQueue)

kenkendk · March 16, 2025, 9:23pm

This looks like you are on Windows and somehow the database file is locked.
Easiest solution is usually to restart Windows, and the problem should go away.

If that is not an option, try restart Duplicati, as it is the most likely culprit.
If that does not work either, you need to figure out what other program has locked the database.

Overtone7760 · March 25, 2025, 5:32pm

Thank you @kenkendk for the help!

I tried restarting both my PC and Duplicati, no luck. Then I tried to look at the database in %/AppData/Local/Duplicati (Duplicati-server.sqlite) with File Locksmith from Windows Powertoys as per your advice but also couldn’t find any other process holding the database locked.

So I tried copying the database to another folder and then replaced the original database with itself from the other folder - it seemed to have worked for a while and then the connection got broken again…

Duplicati started executing the delete commands I tried to do last week or it was automatic I don’t know but it just stopped in media res I guess.

Most recent log:

Mar 25, 2025 6:13 PM: ExecuteNonQuery: PRAGMA optimize took 0:00:00:00.000
Mar 25, 2025 6:13 PM: Starting - ExecuteNonQuery: PRAGMA optimize
Mar 25, 2025 6:13 PM: ExecuteReader: SELECT "ID", "Timestamp" FROM "Operation" ORDER BY "Timestamp" DESC LIMIT 1 took 0:00:00:00.000
Mar 25, 2025 6:13 PM: Starting - ExecuteReader: SELECT "ID", "Timestamp" FROM "Operation" ORDER BY "Timestamp" DESC LIMIT 1
Mar 25, 2025 6:13 PM: Backup operation failed with an error: One or more errors occurred. (There's not enough disk space. : '\\?\D:\duplicati-id65d84081c1a4003b9cf0744724f2440.dindex.zip.aes' (There's not enough disk space. : '\\?\D:\duplicati-id65d84081c1a4003b9cf0744724f2440.dindex.zip.aes') (One or more errors occurred. (There's not enough disk space. : '\\?\D:\duplicati-id65d84081c1a4003b9cf0744724f2440.dindex.zip.aes')))
Mar 25, 2025 6:13 PM: Running Backup took 0:00:10:17.570
Mar 25, 2025 6:13 PM: Fatal error
Mar 25, 2025 6:13 PM: ExecuteNonQuery: PRAGMA optimize took 0:00:00:00.002
Mar 25, 2025 6:13 PM: Starting - ExecuteNonQuery: PRAGMA optimize
Mar 25, 2025 6:13 PM: Uploading a new fileset took 0:00:00:01.711
Mar 25, 2025 6:13 PM: CommitUpdateRemoteVolume took 0:00:00:00.236
Mar 25, 2025 6:13 PM: Starting - CommitUpdateRemoteVolume
Mar 25, 2025 6:13 PM: Starting - Uploading a new fileset
Mar 25, 2025 6:13 PM: UpdateChangeStatistics took 0:00:00:18.343
Mar 25, 2025 6:13 PM: ExecuteNonQuery: DROP TABLE IF EXISTS "TmpFileList-C73FDD5C6C374B46AC9BA808296BEDC3"; took 0:00:00:00.009
Mar 25, 2025 6:13 PM: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "TmpFileList-C73FDD5C6C374B46AC9BA808296BEDC3";
Mar 25, 2025 6:13 PM: ExecuteNonQuery: DROP TABLE IF EXISTS "TmpFileList-DC24448C52C808478151B8BA839E19A2"; took 0:00:00:00.517
Mar 25, 2025 6:13 PM: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "TmpFileList-DC24448C52C808478151B8BA839E19A2";
Mar 25, 2025 6:13 PM: ExecuteScalarInt64: SELECT COUNT(*) FROM "TmpFileList-DC24448C52C808478151B8BA839E19A2" A, "TmpFileList-C73FDD5C6C374B46AC9BA808296BEDC3" B WHERE "A"."Path" = "B"."Path" AND ("A"."Filehash" != "B"."Filehash" OR "A"."Metahash" != "B"."Metahash") took 0:00:00:00.724
Mar 25, 2025 6:13 PM: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM "TmpFileList-DC24448C52C808478151B8BA839E19A2" A, "TmpFileList-C73FDD5C6C374B46AC9BA808296BEDC3" B WHERE "A"."Path" = "B"."Path" AND ("A"."Filehash" != "B"."Filehash" OR "A"."Metahash" != "B"."Metahash")
Mar 25, 2025 6:13 PM: ExecuteScalarInt64: SELECT COUNT(*) FROM "TmpFileList-DC24448C52C808478151B8BA839E19A2" WHERE "TmpFileList-DC24448C52C808478151B8BA839E19A2"."Path" NOT IN (SELECT "Path" FROM "File" INNER JOIN "FilesetEntry" ON "File"."ID" = "FilesetEntry"."FileID" WHERE "FilesetEntry"."FilesetID" = 86) took 0:00:00:01.099
Mar 25, 2025 6:13 PM: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM "TmpFileList-DC24448C52C808478151B8BA839E19A2" WHERE "TmpFileList-DC24448C52C808478151B8BA839E19A2"."Path" NOT IN (SELECT "Path" FROM "File" INNER JOIN "FilesetEntry" ON "File"."ID" = "FilesetEntry"."FileID" WHERE "FilesetEntry"."FilesetID" = 86)
Mar 25, 2025 6:13 PM: ExecuteScalarInt64: SELECT COUNT(*) FROM "File" INNER JOIN "FilesetEntry" ON "File"."ID" = "FilesetEntry"."FileID" WHERE "FilesetEntry"."FilesetID" = 86 AND "File"."BlocksetID" != -100 AND "File"."BlocksetID" != -200 AND NOT "File"."Path" IN (SELECT "Path" FROM "TmpFileList-DC24448C52C808478151B8BA839E19A2") took 0:00:00:00.043
Mar 25, 2025 6:13 PM: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM "File" INNER JOIN "FilesetEntry" ON "File"."ID" = "FilesetEntry"."FileID" WHERE "FilesetEntry"."FilesetID" = 86 AND "File"."BlocksetID" != -100 AND "File"."BlocksetID" != -200 AND NOT "File"."Path" IN (SELECT "Path" FROM "TmpFileList-DC24448C52C808478151B8BA839E19A2")
Mar 25, 2025 6:13 PM: ExecuteNonQuery: CREATE INDEX "nn_tmpName2" ON "TmpFileList-C73FDD5C6C374B46AC9BA808296BEDC3" ("Path") took 0:00:00:00.040
Mar 25, 2025 6:13 PM: Starting - ExecuteNonQuery: CREATE INDEX "nn_tmpName2" ON "TmpFileList-C73FDD5C6C374B46AC9BA808296BEDC3" ("Path")
Mar 25, 2025 6:13 PM: ExecuteNonQuery: CREATE INDEX "nn_tmpName1" ON "TmpFileList-DC24448C52C808478151B8BA839E19A2" ("Path") took 0:00:00:01.980
Mar 25, 2025 6:13 PM: Starting - ExecuteNonQuery: CREATE INDEX "nn_tmpName1" ON "TmpFileList-DC24448C52C808478151B8BA839E19A2" ("Path")
Mar 25, 2025 6:13 PM: ExecuteNonQuery: CREATE TEMPORARY TABLE "TmpFileList-C73FDD5C6C374B46AC9BA808296BEDC3" AS SELECT "File"."Path" AS "Path", "A"."Fullhash" AS "Filehash", "B"."Fullhash" AS "Metahash" FROM "File", "FilesetEntry", "Blockset" A, "Blockset" B, "Metadataset" WHERE "File"."ID" = "FilesetEntry"."FileID" AND "A"."ID" = "File"."BlocksetID" AND "FilesetEntry"."FilesetID" = 86 AND "File"."MetadataID" = "Metadataset"."ID" AND "Metadataset"."BlocksetID" = "B"."ID" took 0:00:00:00.083

Overtone7760 · March 26, 2025, 10:40am

So, now it gets stuck on ScalarInt64 and Vacuuming database… if the connection gets lost I can restart the TrayIcon and it connects, but also starts whole process again.

Now I would like to just delete the remote backup files (external HDD) and try to create the backup from scratch, because maybe it gets stuck because of lack of space?

But I can’t because Duplicati won’t respond to stop requests. (and I am afraid I would fuck up the database if I just formatted the backup disk outright)
Did anyone got through something similar?

If I look into Profiling level of log it keeps doing ExecuteScalarInt64

Mar 26, 2025 11:07 AM: Starting - ExecuteNonQuery: VACUUM
Mar 26, 2025 11:07 AM: ExecuteScalarInt64: INSERT INTO "Operation" ("Description", "Timestamp") VALUES ("Vacuum", 1742983620); SELECT last_insert_rowid(); took 0:00:00:00.364
Mar 26, 2025 11:07 AM: Starting - ExecuteScalarInt64: INSERT INTO "Operation" ("Description", "Timestamp") VALUES ("Vacuum", 1742983620); SELECT last_insert_rowid();
Mar 26, 2025 11:07 AM: ExecuteNonQuery: DELETE FROM "RemoteOperation" WHERE "Timestamp" < 1740391620 took 0:00:00:00.000
Mar 26, 2025 11:07 AM: Starting - ExecuteNonQuery: DELETE FROM "RemoteOperation" WHERE "Timestamp" < 1740391620
Mar 26, 2025 11:07 AM: ExecuteNonQuery: DELETE FROM "LogData" WHERE "Timestamp" < 1740391620 took 0:00:00:00.000
Mar 26, 2025 11:07 AM: Starting - ExecuteNonQuery: DELETE FROM "LogData" WHERE "Timestamp" < 1740391620
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT "ID", "Name", "Size", "Hash", "VerificationCount" FROM "Remotevolume" WHERE "Type" = "Blocks" AND "State" IN ("Uploaded", "Verified") took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT "ID", "Name", "Size", "Hash", "VerificationCount" FROM "Remotevolume" WHERE "Type" = "Blocks" AND "State" IN ("Uploaded", "Verified")
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT "ID", "Name", "Size", "Hash", "VerificationCount" FROM "Remotevolume" WHERE "Type" = "Index" AND "State" IN ("Uploaded", "Verified") took 0:00:00:00.004
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT "ID", "Name", "Size", "Hash", "VerificationCount" FROM "Remotevolume" WHERE "Type" = "Index" AND "State" IN ("Uploaded", "Verified")
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT "A"."VolumeID", "A"."Name", "A"."Size", "A"."Hash", "A"."VerificationCount" FROM (SELECT "ID" AS "VolumeID", "Name", "Size", "Hash", "VerificationCount" FROM "Remotevolume" WHERE "State" IN ("Uploaded", "Verified")) A, "Fileset" WHERE "A"."VolumeID" = "Fileset"."VolumeID" ORDER BY "Fileset"."Timestamp" took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT "A"."VolumeID", "A"."Name", "A"."Size", "A"."Hash", "A"."VerificationCount" FROM (SELECT "ID" AS "VolumeID", "Name", "Size", "Hash", "VerificationCount" FROM "Remotevolume" WHERE "State" IN ("Uploaded", "Verified")) A, "Fileset" WHERE "A"."VolumeID" = "Fileset"."VolumeID" ORDER BY "Fileset"."Timestamp"
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: SELECT MAX("VerificationCount") FROM "RemoteVolume" took 0:00:00:00.012
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: SELECT MAX("VerificationCount") FROM "RemoteVolume"
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT "ID", "Name", "Size", "Hash", "VerificationCount" FROM "Remotevolume" WHERE ("State" = "Verified" OR "State" = "Uploaded") AND ("Hash" = '' OR "Hash" IS NULL OR "Size" <= 0) took 0:00:00:00.016
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT "ID", "Name", "Size", "Hash", "VerificationCount" FROM "Remotevolume" WHERE ("State" = "Verified" OR "State" = "Uploaded") AND ("Hash" = '' OR "Hash" IS NULL OR "Size" <= 0)
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC
Mar 26, 2025 11:06 AM: RemoteOperationTerminate took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - RemoteOperationTerminate
Mar 26, 2025 11:06 AM: AfterBackupVerify took 0:00:00:01.231
Mar 26, 2025 11:06 AM: keeping protected incomplete remote file listed as Temporary: duplicati-20250326T100422Z.dlist.zip.aes
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT DISTINCT "Name", "State" FROM "Remotevolume" WHERE "Name" IN (SELECT "Name" FROM "Remotevolume" WHERE "State" IN ('Deleted', 'Deleting')) AND NOT "State" IN ('Deleted', 'Deleting') took 0:00:00:00.010
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT DISTINCT "Name", "State" FROM "Remotevolume" WHERE "Name" IN (SELECT "Name" FROM "Remotevolume" WHERE "State" IN ('Deleted', 'Deleting')) AND NOT "State" IN ('Deleted', 'Deleting')
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC
Mar 26, 2025 11:06 AM: RemoteOperationList took 0:00:00:00.256
Mar 26, 2025 11:06 AM: Backend event: List - Completed: (74,55 KB)
Mar 26, 2025 11:06 AM: Backend event: List - Started: ()
Mar 26, 2025 11:06 AM: Starting - RemoteOperationList
Mar 26, 2025 11:06 AM: Starting - AfterBackupVerify
Mar 26, 2025 11:06 AM: CommitFinalizingBackup took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - CommitFinalizingBackup
Mar 26, 2025 11:06 AM: CommitAfterUpload took 0:00:00:00.513
Mar 26, 2025 11:06 AM: Starting - CommitAfterUpload
Mar 26, 2025 11:06 AM: VerifyConsistency took 0:00:00:12.903
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: SELECT COUNT(*) FROM "FileLookup" WHERE "BlocksetID" != -100 AND "BlocksetID" != -200 AND NOT "BlocksetID" IN (SELECT "ID" FROM "Blockset") took 0:00:00:00.391
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM "FileLookup" WHERE "BlocksetID" != -100 AND "BlocksetID" != -200 AND NOT "BlocksetID" IN (SELECT "ID" FROM "Blockset")
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: SELECT COUNT(*) FROM "Blockset" WHERE "Length" > 0 AND "ID" NOT IN (SELECT "BlocksetId" FROM "BlocksetEntry") took 0:00:00:00.977
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM "Blockset" WHERE "Length" > 0 AND "ID" NOT IN (SELECT "BlocksetId" FROM "BlocksetEntry")
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: SELECT COUNT(*) FROM (SELECT * FROM (SELECT "N"."BlocksetID", (("N"."BlockCount" + 3200 - 1) / 3200) AS "BlocklistHashCountExpected", CASE WHEN "G"."BlocklistHashCount" IS NULL THEN 0 ELSE "G"."BlocklistHashCount" END AS "BlocklistHashCountActual" FROM (SELECT "BlocksetID", COUNT(*) AS "BlockCount" FROM "BlocksetEntry" GROUP BY "BlocksetID") "N" LEFT OUTER JOIN (SELECT "BlocksetID", COUNT(*) AS "BlocklistHashCount" FROM "BlocklistHash" GROUP BY "BlocksetID") "G" ON "N"."BlocksetID" = "G"."BlocksetID" WHERE "N"."BlockCount" > 1) WHERE "BlocklistHashCountExpected" != "BlocklistHashCountActual") took 0:00:00:02.530
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM (SELECT * FROM (SELECT "N"."BlocksetID", (("N"."BlockCount" + 3200 - 1) / 3200) AS "BlocklistHashCountExpected", CASE WHEN "G"."BlocklistHashCount" IS NULL THEN 0 ELSE "G"."BlocklistHashCount" END AS "BlocklistHashCountActual" FROM (SELECT "BlocksetID", COUNT(*) AS "BlockCount" FROM "BlocksetEntry" GROUP BY "BlocksetID") "N" LEFT OUTER JOIN (SELECT "BlocksetID", COUNT(*) AS "BlocklistHashCount" FROM "BlocklistHash" GROUP BY "BlocksetID") "G" ON "N"."BlocksetID" = "G"."BlocksetID" WHERE "N"."BlockCount" > 1) WHERE "BlocklistHashCountExpected" != "BlocklistHashCountActual")
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: SELECT Count(*) FROM (SELECT DISTINCT "BlocksetID", "Index" FROM "BlocklistHash") took 0:00:00:00.010
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: SELECT Count(*) FROM (SELECT DISTINCT "BlocksetID", "Index" FROM "BlocklistHash")
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: SELECT Count(*) FROM "BlocklistHash" took 0:00:00:00.002
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: SELECT Count(*) FROM "BlocklistHash"
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT "CalcLen", "Length", "A"."BlocksetID", "File"."Path" FROM ( SELECT "A"."ID" AS "BlocksetID", IFNULL("B"."CalcLen", 0) AS "CalcLen", "A"."Length" FROM "Blockset" A LEFT OUTER JOIN ( SELECT "BlocksetEntry"."BlocksetID", SUM("Block"."Size") AS "CalcLen" FROM "BlocksetEntry" LEFT OUTER JOIN "Block" ON "Block"."ID" = "BlocksetEntry"."BlockID" GROUP BY "BlocksetEntry"."BlocksetID" ) B ON "A"."ID" = "B"."BlocksetID" ) A, "File" WHERE "A"."BlocksetID" = "File"."BlocksetID" AND "A"."CalcLen" != "A"."Length" took 0:00:00:08.990
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT "CalcLen", "Length", "A"."BlocksetID", "File"."Path" FROM ( SELECT "A"."ID" AS "BlocksetID", IFNULL("B"."CalcLen", 0) AS "CalcLen", "A"."Length" FROM "Blockset" A LEFT OUTER JOIN ( SELECT "BlocksetEntry"."BlocksetID", SUM("Block"."Size") AS "CalcLen" FROM "BlocksetEntry" LEFT OUTER JOIN "Block" ON "Block"."ID" = "BlocksetEntry"."BlockID" GROUP BY "BlocksetEntry"."BlocksetID" ) B ON "A"."ID" = "B"."BlocksetID" ) A, "File" WHERE "A"."BlocksetID" = "File"."BlocksetID" AND "A"."CalcLen" != "A"."Length"
Mar 26, 2025 11:06 AM: Starting - VerifyConsistency
Mar 26, 2025 11:06 AM: Including source path: C:\Program Files (x86)\
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: INSERT INTO "Fileset" ("OperationID", "Timestamp", "VolumeID", "IsFullBackup") VALUES (155, 1742983462, 80397, 0); SELECT last_insert_rowid(); took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: INSERT INTO "Fileset" ("OperationID", "Timestamp", "VolumeID", "IsFullBackup") VALUES (155, 1742983462, 80397, 0); SELECT last_insert_rowid();
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (155, "duplicati-20250326T100422Z.dlist.zip.aes", "Files", "Temporary", -1, 0, 0); SELECT last_insert_rowid(); took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (155, "duplicati-20250326T100422Z.dlist.zip.aes", "Files", "Temporary", -1, 0, 0); SELECT last_insert_rowid();
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: SELECT "ID" FROM "Remotevolume" WHERE "Name" = "duplicati-20250326T100422Z.dlist.zip.aes" took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: SELECT "ID" FROM "Remotevolume" WHERE "Name" = "duplicati-20250326T100422Z.dlist.zip.aes"
Mar 26, 2025 11:06 AM: ExecuteScalarInt64: SELECT "ID" FROM "Remotevolume" WHERE "Name" = "duplicati-20250326T100422Z.dlist.zip.aes" took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteScalarInt64: SELECT "ID" FROM "Remotevolume" WHERE "Name" = "duplicati-20250326T100422Z.dlist.zip.aes"
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT "Name" FROM "RemoteVolume" WHERE "Type" = "Blocks" AND NOT "ID" IN (SELECT "BlockVolumeID" FROM "IndexBlockLink") AND "State" IN ("Uploaded","Verified") took 0:00:00:00.035
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT "Name" FROM "RemoteVolume" WHERE "Type" = "Blocks" AND NOT "ID" IN (SELECT "BlockVolumeID" FROM "IndexBlockLink") AND "State" IN ("Uploaded","Verified")
Mar 26, 2025 11:06 AM: ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC
Mar 26, 2025 11:06 AM: PreBackupVerify took 0:00:02:22.455
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DROP TABLE IF EXISTS "DelMetadataFilesetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "DelMetadataFilesetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7"
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DROP TABLE IF EXISTS "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7"
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DROP TABLE IF EXISTS "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7"
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7"
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7"
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM Fileset WHERE Fileset.ID IN (SELECT DISTINCT FilesetID FROM "DelMetadataFilesetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7") AND Fileset.ID NOT IN (SELECT DISTINCT FilesetID FROM FilesetEntry) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM Fileset WHERE Fileset.ID IN (SELECT DISTINCT FilesetID FROM "DelMetadataFilesetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7") AND Fileset.ID NOT IN (SELECT DISTINCT FilesetID FROM FilesetEntry)
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM Fileset WHERE VolumeID IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM Fileset WHERE VolumeID IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM FilesetEntry WHERE FilesetID IN (SELECT ID FROM Fileset WHERE VolumeID IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM FilesetEntry WHERE FilesetID IN (SELECT ID FROM Fileset WHERE VolumeID IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ))
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "ChangeJournalData" WHERE "FilesetID" IN (SELECT "ID" FROM "Fileset" WHERE "VolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "ChangeJournalData" WHERE "FilesetID" IN (SELECT "ID" FROM "Fileset" WHERE "VolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ))
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "DeletedBlock" WHERE "VolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) took 0:00:00:00.024
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "DeletedBlock" WHERE "VolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "Block" WHERE "VolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "Block" WHERE "VolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "IndexBlockLink" WHERE "BlockVolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) OR "IndexVolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) took 0:00:00:00.004
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "IndexBlockLink" WHERE "BlockVolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) OR "IndexVolumeID" IN (SELECT "ID" FROM "DelVolSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "BlocklistHash" WHERE "BlocklistHash"."BlocksetID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "BlocklistHash" WHERE "BlocklistHash"."BlocksetID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "BlocksetEntry" WHERE "BlocksetID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "BlocksetEntry" WHERE "BlocksetID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "Blockset" WHERE "ID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "Blockset" WHERE "ID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "FileLookup" WHERE "BlocksetID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "FileLookup" WHERE "BlocksetID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )
Mar 26, 2025 11:06 AM: ExecuteNonQuery: DELETE FROM "Metadataset" WHERE "BlocksetID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" ) took 0:00:00:00.000
Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "Metadataset" WHERE "BlocksetID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )

----The rest of the log is deleted because of post character limit----

Overtone7760 · March 26, 2025, 10:41am

But if I look into verbose level, it is stuck on “keeping protected incomplete remote file”
It always gets here but no further, even after restarting Duplicati GUI and TrayIcon multiple times and pausing backup - after restart it is not paused and immediately starts again the same schtick…

Mar 26, 2025 10:51 AM: keeping protected incomplete remote file listed as Temporary: duplicati-20250326T094809Z.dlist.zip.aes
Mar 26, 2025 10:51 AM: removing file listed as Temporary: duplicati-20250326T094048Z.dlist.zip.aes
Mar 26, 2025 10:51 AM: Backend event: List - Completed: (74,55 KB)
Mar 26, 2025 10:51 AM: Backend event: List - Started: ()
Mar 26, 2025 10:50 AM: Including source path: C:\Program Files (x86)\
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-i67e11cf7664f4521a0f0f0a14c78a2f9.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-i67e11cf7664f4521a0f0f0a14c78a2f9.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-i67e11cf7664f4521a0f0f0a14c78a2f9.dindex.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-bdf8fb6ee3c57494cab0ff70b93354a9b.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-bdf8fb6ee3c57494cab0ff70b93354a9b.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-bdf8fb6ee3c57494cab0ff70b93354a9b.dblock.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-bde1d9a9710e249808b2bf7a0e198e510.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-bde1d9a9710e249808b2bf7a0e198e510.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-bde1d9a9710e249808b2bf7a0e198e510.dblock.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-bb2d29f1359d044e4b8ba337a56bd46c8.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-bb2d29f1359d044e4b8ba337a56bd46c8.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-bb2d29f1359d044e4b8ba337a56bd46c8.dblock.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-i5d8e36d05a314976adf74c8407e8445a.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-i5d8e36d05a314976adf74c8407e8445a.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-i5d8e36d05a314976adf74c8407e8445a.dindex.zip.aes
Mar 26, 2025 10:50 AM: scheduling missing file for deletion, currently listed as Uploading: duplicati-b27911b39743b43689470db8d825ae548.dblock.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-b2d22d777ea3c4124b06c32e5bcdec5f9.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-b2d22d777ea3c4124b06c32e5bcdec5f9.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-b2d22d777ea3c4124b06c32e5bcdec5f9.dblock.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-bb8f233e2eb6a40e184876cca67167d5f.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-bb8f233e2eb6a40e184876cca67167d5f.dblock.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-bb8f233e2eb6a40e184876cca67167d5f.dblock.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-iea0a450ff42447dfabfa658ae61d876f.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-iea0a450ff42447dfabfa658ae61d876f.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-iea0a450ff42447dfabfa658ae61d876f.dindex.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-b3bd339f697b14fa28316388d28ebf006.dblock.zip.aes (49,87 MB)
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-b3bd339f697b14fa28316388d28ebf006.dblock.zip.aes (49,87 MB)
Mar 26, 2025 10:50 AM: removing incomplete remote file listed as Uploading: duplicati-b3bd339f697b14fa28316388d28ebf006.dblock.zip.aes
Mar 26, 2025 10:50 AM: removing file listed as Deleting: duplicati-b17e7a5cb10e141a28b0a0131d08b5be8.dblock.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-i3a1cc9f2d48e4b2b8178c921fa9e1c4d.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-i3a1cc9f2d48e4b2b8178c921fa9e1c4d.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-i3a1cc9f2d48e4b2b8178c921fa9e1c4d.dindex.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-bef9bebc1b1b84aa196ad125525321d59.dblock.zip.aes (49,91 MB)
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-bef9bebc1b1b84aa196ad125525321d59.dblock.zip.aes (49,91 MB)
Mar 26, 2025 10:50 AM: removing incomplete remote file listed as Uploading: duplicati-bef9bebc1b1b84aa196ad125525321d59.dblock.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-i23a4a334cc41427eba9d6b6b7edd1a74.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-i23a4a334cc41427eba9d6b6b7edd1a74.dindex.zip.aes ()
Mar 26, 2025 10:50 AM: removing remote file listed as Deleting: duplicati-i23a4a334cc41427eba9d6b6b7edd1a74.dindex.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-b4e36be33974b4db9954ea944a1fd51a1.dblock.zip.aes (49,93 MB)
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-b4e36be33974b4db9954ea944a1fd51a1.dblock.zip.aes (49,93 MB)
Mar 26, 2025 10:50 AM: removing incomplete remote file listed as Uploading: duplicati-b4e36be33974b4db9954ea944a1fd51a1.dblock.zip.aes
Mar 26, 2025 10:50 AM: keeping protected incomplete remote file listed as Uploading: duplicati-20250326T094048Z.dlist.zip.aes
Mar 26, 2025 10:50 AM: Backend event: Delete - Completed: duplicati-i93290547cfba45f2952b0e055faa25d5.dindex.zip.aes (127,75 KB)
Mar 26, 2025 10:50 AM: Backend event: Delete - Started: duplicati-i93290547cfba45f2952b0e055faa25d5.dindex.zip.aes (127,75 KB)
Mar 26, 2025 10:50 AM: removing incomplete remote file listed as Uploading: duplicati-i93290547cfba45f2952b0e055faa25d5.dindex.zip.aes
Mar 26, 2025 10:50 AM: Backend quota has been exceeded: Using 1,82 TB of 1,82 TB (0 bajtů available)
Mar 26, 2025 10:50 AM: Backend event: List - Completed: (74,57 KB)
Mar 26, 2025 10:50 AM: Backend event: List - Started: ()

Overtone7760 · March 27, 2025, 12:50pm

I got pissed and nuked the remote files to free up space - the job started up again like nothing was wrong, but everytime I try to cancel the backup so that I can rebuild the database from scratch or create another job from the config backup it crashes the connection.
When I restart TrayIcon the backup starts again, even if I paused it before crashing…

Maybe I need to reinstall duplicati and import the config?

kenkendk · March 31, 2025, 11:48am

Overtone7760:

Mar 26, 2025 10:50 AM: removing incomplete remote file listed as Uploading: duplicati-i93290547cfba45f2952b0e055faa25d5.dindex.zip.aes
Mar 26, 2025 10:50 AM: Backend quota has been exceeded: Using 1,82 TB of 1,82 TB (0 bajtů available)
Mar 26, 2025 10:50 AM: Backend event: List - Completed: (74,57 KB)

Is this a wrong message, or are you actually out of space?

Overtone7760:

Mar 26, 2025 11:06 AM: Starting - ExecuteNonQuery: DELETE FROM "Metadataset" WHERE "BlocksetID" IN (SELECT DISTINCT "ID" FROM "DelBlockSetIds-C24A9CE5A0EE0D4D8DD4265BFD8B8CE7" )

----The rest of the log is deleted because of post character limit----

Did you delete the start of the log or the end? I think the end is the most interresting (as that is where the hang happens). The traces you see here are from the deletion of data related to a remote file, but I would be surprised if it gets stuck there, because it is a simple query.

Previously, you stated that vacuum was causing the locked file, but this appears to have been resolved?

Not sure what is happening here exactly. Why is the backup running? And how can it run if something is wrong?

The connection that crashes, is that the browser saying “Connection lost” ? If so, it usually means that Duplicati itself crashes, and you may be able to see further details in the Windows EventViewer.

That sounds like a different bug. You can go in and remove the schedule on the job (or set the next time in the future), then restart Duplicati and it will not start automatically, but it should stay paused if you pause it and restart.

Overtone7760 · April 12, 2025, 6:01pm

Is this a wrong message, or are you actually out of space?

Yeah, I was out of space. I thought I set a threshold warning that would warn me with enough space left to delete some older backup versions (via the delete command). But the warning came too late and the external drive filled up completely with no room for delete operations I guess.

Did you delete the start of the log or the end? I think the end is the most interresting (as that is where the hang happens). The traces you see here are from the deletion of data related to a remote file, but I would be surprised if it gets stuck there, because it is a simple query.
Previously, you stated that vacuum was causing the locked file, but this appears to have been resolved?

I unfortunately deleted the end I think
I had a problem with some Duplicati component - every time I started/restarted duplicati it started to run a job (because it was unfinished from last time) but it got hung up somewhere in the process, that day it was always during the “Vacuuming database…” message on the top progress bar in the browser/localhost component. I just got stuck on one message, whether it was Vacuuming or some other message and it was still there when I came from work.

Not sure what is happening here exactly. Why is the backup running? And how can it run if something is wrong?
The connection that crashes, is that the browser saying “Connection lost” ? If so, it usually means that Duplicati itself crashes, and you may be able to see further details in the Windows EventViewer.

The unfinished job from last time always started right after Duplicati.TrayIcon restart to finish the pending operations or something.
I don’t know why the backup was running when I turned the schedule off via the pause options in the browser. Unfortunately I didn’t have the idea to disable schedule in job settings.
In EventViewer I also did not find any clues.

Either way, I did “repair” it in the end by saving my configuration, re-installing Duplicati and letting it do the database building and backup from scratch. I was a bit scared and it took two close to two days but now it seems to be running all right once more.

Thank you @kenkendk for your guidance and the time you gave me, all the same.