Getting timeouts/database locks on restore screen

Thank you for your reply.
So just for sanity and insanity, I chmod -R 777 the /home and /root/.config directories already. The database is in /root/.config/Duplicati/ and the backup files are in /home/BACKUP. Still getting the same errors. I will also clarify that this is definitely not a VPS and is a bare metal server so no virtualization (like Virtuozzo) disk locks are present. Also in my original post, I mentioned Duplicati is running as root from systemd so no permissions error should be present in theory.

Ok yeah if it’s running as root then permissions shouldn’t be a problem.

Strange. Can you confirm that no other Duplicati operation is in progress when you try to do a restore?

My theory is that I am hitting some sort of timeout because of how long it takes for the directory contents to be retrieved and then it also locks the DB temporarily or it’s just a secondary warning caused by the timeout (I will note I do in fact SSL proxy through Nginx) but I was also getting the error when loading from localhost as well bypassing the SSL proxy. Currently, I am trying to do the same restore by importing the config file on my local machine (and grabbing the files remotely) and even that was throwing the same errors for a while. So far this time if I click one arrow and wait for it to load it seems fine but I was trying to expand multiple directories earlier. Almost feels like normal tech stuff where you could replicate it all day and now all of a sudden it’s mostly behaving (on the local fresh instance with the imported config). The server instance is verifying the offsite database at the moment so I can’t test that instance currently as that is gonna take a while (usually several hours to verify from experience).


Yeah, it is still happening even just looking at the interface.
No pending tasks:


Jan 14, 2022 11:41 PM: Request for http://backups.hostingportal.net/api/v1/backup/12/filesets gave error
Mono.Data.Sqlite.SqliteException (0x80004005): The database file is locked
database is locked
  at Mono.Data.Sqlite.SQLite3.Step (Mono.Data.Sqlite.SqliteStatement stmt) [0x00089] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader.NextResult () [0x00104] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader..ctor (Mono.Data.Sqlite.SqliteCommand cmd, System.Data.CommandBehavior behave) [0x0004e] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at (wrapper remoting-invoke-with-check) Mono.Data.Sqlite.SqliteDataReader..ctor(Mono.Data.Sqlite.SqliteCommand,System.Data.CommandBehavior)
  at Mono.Data.Sqlite.SqliteCommand.ExecuteReader (System.Data.CommandBehavior behavior) [0x00006] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteCommand.ExecuteDbDataReader (System.Data.CommandBehavior behavior) [0x00000] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at System.Data.Common.DbCommand.System.Data.IDbCommand.ExecuteReader () [0x00000] in <d3b33a5f75ba43539b447fcfaf55c885>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.Boolean writeLog, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00061] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.Data.IDbConnection connection, System.String operation) [0x0005e] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.String path, System.String operation, System.Boolean shouldclose) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalListDatabase..ctor (System.String path) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Operation.ListFilesHandler.Run (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter compositefilter) [0x00075] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller+<>c__DisplayClass21_0.<List>b__0 (Duplicati.Library.Main.ListResults result) [0x0001c] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, System.String[]& paths, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x0026f] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.List (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter filter) [0x00021] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Server.Runner.Run (Duplicati.Server.Runner+IRunnerData data, System.Boolean fromQueue) [0x00668] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTMethods.Backup.ListFileSets (Duplicati.Server.Serialization.Interface.IBackup backup, Duplicati.Server.WebServer.RESTMethods.RequestInfo info) [0x000a4] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTMethods.Backup.GET (System.String key, Duplicati.Server.WebServer.RESTMethods.RequestInfo info) [0x001fd] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTHandler.DoProcess (Duplicati.Server.WebServer.RESTMethods.RequestInfo info, System.String method, System.String module, System.String key) [0x00146] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
Jan 14, 2022 11:41 PM: Reporting error gave error
System.ObjectDisposedException: Can not write to a closed TextWriter.
  at System.IO.StreamWriter.Flush (System.Boolean flushStream, System.Boolean flushEncoder) [0x00008] in <533173d24dae460899d2b10975534bb0>:0 
  at System.IO.StreamWriter.Flush () [0x00006] in <533173d24dae460899d2b10975534bb0>:0 
  at Duplicati.Server.WebServer.RESTHandler.DoProcess (Duplicati.Server.WebServer.RESTMethods.RequestInfo info, System.String method, System.String module, System.String key) [0x003bc] in <3752ce5d8337471da6b77129cfa4bdbe>:0 

Are you running Duplicati in docker, or is it installed right on the host. (I’m guessing the latter based on your previous messages but just want to be sure.)

Is there anything special about the location where databases are stored? eg, is it a regular directory on a fixed local disk or is it an NFS mount?

While we’re asking questions, can you elaborate on the timeout? I wasn’t able to find the details posted.

Also, what is the browser? Most have some developer tools to look at the queries, if you’re comfortable.

On the Duplicati side, there’s quite a bit of logging available to see whether SQL is getting overly slow…

Duplicati is installed bare metal and not running in a container so a memory constraint is not the issue. The drives are soft raided (0) EXT4 and there are no NFS mounts. I have monitoring for SMART and RAID in Webmin and no alerts are currently being set off. The browser is Chrome but I’m not sure if it’s going to provide much more useful information than the logs Duplicati is throwing at the time of the error. I will reply back with console logs and network info from Chrome.

Here are the Chrome errors:



I appreciate everyone’s assistance with trying to dig further into the issue. This is a weird one for sure and I was not finding many previous issues of it.

Failed to connect: Gateway Time-out issue when restoring was me on how to increase the NGINX timeout, however in that topic it turned into a different error. For awhile though you weren’t on NGINX. Are you back?

The network status code image with the 504 is gateway timeout. Is that what you’ve always been getting? Talking about timeout in a generic way leaves too much guessing, so I was trying to get timeouts clarified.

I am not certain that the Duplicati web server can’t 504, but I doubt it. I don’t see a 504 or GatewayTimeout from a source search. I do see it returning a lot of its errors using the names from HttpStatusCode Enum.

You can watch the SQL go by in About → Show log → Live → Profiling (if that web report stays .working).
More certain (and more setup) is log-file=<path> and log-file-log-level=profiling, then tail -f your log file, while also watching for a 504. Possibly you’ll see it still in the query when NGINX gets tired of waiting on it.

If the problem is really that the response can’t meet the timeout, the only short-term fix is to raise timeout.
Note that this theory doesn’t hold too well if you’re on a direct network path to localhost:8200 (if you were).

Unfortunately, I get it on localhost as well even bypassing the Nginx proxy. The error changes from a 504 to a 500 error.


Can you see if there’s any more description on that 500 status? Maybe on Response tab. I don’t know.
At least the 500 is something I see in the web server code, and I see some attempts to send details…

It’s a JSON reply:
Message: "The database file is locked\r\ndatabase is locked.
Type: “SqliteException”

That is all it provides in the response tab.

I was wondering (still am, but less) whether that database issue was a follow-on to some other glitch.
So it looks like the files query to the server went straight to 500. Was there a timing value on reply?
This might also be a good time to try the profiling log (probably to log file) to see what the DB is doing.

The profiling log is blank. All it has is the last time I tried restarting the Duplicati service.


The best logs I got are the stored ones I have been embedding.

Jan 15, 2022 5:39 PM: Reporting error gave error
System.ObjectDisposedException: Can not write to a closed TextWriter.
  at System.IO.StreamWriter.Flush (System.Boolean flushStream, System.Boolean flushEncoder) [0x00008] in <533173d24dae460899d2b10975534bb0>:0 
  at System.IO.StreamWriter.Flush () [0x00006] in <533173d24dae460899d2b10975534bb0>:0 
  at Duplicati.Server.WebServer.RESTHandler.DoProcess (Duplicati.Server.WebServer.RESTMethods.RequestInfo info, System.String method, System.String module, System.String key) [0x003bc] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
Jan 15, 2022 5:39 PM: Request for http://127.0.0.1:8196/api/v1/backup/12/filesets gave error
Mono.Data.Sqlite.SqliteException (0x80004005): The database file is locked
database is locked
  at Mono.Data.Sqlite.SQLite3.Step (Mono.Data.Sqlite.SqliteStatement stmt) [0x00089] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader.NextResult () [0x00104] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader..ctor (Mono.Data.Sqlite.SqliteCommand cmd, System.Data.CommandBehavior behave) [0x0004e] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at (wrapper remoting-invoke-with-check) Mono.Data.Sqlite.SqliteDataReader..ctor(Mono.Data.Sqlite.SqliteCommand,System.Data.CommandBehavior)
  at Mono.Data.Sqlite.SqliteCommand.ExecuteReader (System.Data.CommandBehavior behavior) [0x00006] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteCommand.ExecuteDbDataReader (System.Data.CommandBehavior behavior) [0x00000] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at System.Data.Common.DbCommand.System.Data.IDbCommand.ExecuteReader () [0x00000] in <d3b33a5f75ba43539b447fcfaf55c885>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.Boolean writeLog, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00061] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.Data.IDbConnection connection, System.String operation) [0x0005e] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.String path, System.String operation, System.Boolean shouldclose) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalListDatabase..ctor (System.String path) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Operation.ListFilesHandler.Run (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter compositefilter) [0x00075] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller+<>c__DisplayClass21_0.<List>b__0 (Duplicati.Library.Main.ListResults result) [0x0001c] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, System.String[]& paths, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x0026f] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.List (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter filter) [0x00021] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Server.Runner.Run (Duplicati.Server.Runner+IRunnerData data, System.Boolean fromQueue) [0x00668] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTMethods.Backup.ListFileSets (Duplicati.Server.Serialization.Interface.IBackup backup, Duplicati.Server.WebServer.RESTMethods.RequestInfo info) [0x000a4] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTMethods.Backup.GET (System.String key, Duplicati.Server.WebServer.RESTMethods.RequestInfo info) [0x001fd] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTHandler.DoProcess (Duplicati.Server.WebServer.RESTMethods.RequestInfo info, System.String method, System.String module, System.String key) [0x00146] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
Jan 15, 2022 5:39 PM: Failed while executing "List" with id: 12
Mono.Data.Sqlite.SqliteException (0x80004005): The database file is locked
database is locked
  at Mono.Data.Sqlite.SQLite3.Step (Mono.Data.Sqlite.SqliteStatement stmt) [0x00089] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader.NextResult () [0x00104] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader..ctor (Mono.Data.Sqlite.SqliteCommand cmd, System.Data.CommandBehavior behave) [0x0004e] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at (wrapper remoting-invoke-with-check) Mono.Data.Sqlite.SqliteDataReader..ctor(Mono.Data.Sqlite.SqliteCommand,System.Data.CommandBehavior)
  at Mono.Data.Sqlite.SqliteCommand.ExecuteReader (System.Data.CommandBehavior behavior) [0x00006] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteCommand.ExecuteDbDataReader (System.Data.CommandBehavior behavior) [0x00000] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at System.Data.Common.DbCommand.System.Data.IDbCommand.ExecuteReader () [0x00000] in <d3b33a5f75ba43539b447fcfaf55c885>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.Boolean writeLog, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00061] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.Data.IDbConnection connection, System.String operation) [0x0005e] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.String path, System.String operation, System.Boolean shouldclose) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalListDatabase..ctor (System.String path) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Operation.ListFilesHandler.Run (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter compositefilter) [0x00075] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller+<>c__DisplayClass21_0.<List>b__0 (Duplicati.Library.Main.ListResults result) [0x0001c] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, System.String[]& paths, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x0026f] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.List (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter filter) [0x00021] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Server.Runner.Run (Duplicati.Server.Runner+IRunnerData data, System.Boolean fromQueue) [0x00374] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
Jan 15, 2022 5:38 PM: Request for http://127.0.0.1:8196/api/v1/backup/12/files/*?prefix-only=true&folder-contents=false&time=2022-01-13T07:00:00-05:00 gave error
Mono.Data.Sqlite.SqliteException (0x80004005): The database file is locked
database is locked
  at Mono.Data.Sqlite.SQLite3.Step (Mono.Data.Sqlite.SqliteStatement stmt) [0x00089] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader.NextResult () [0x00104] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader..ctor (Mono.Data.Sqlite.SqliteCommand cmd, System.Data.CommandBehavior behave) [0x0004e] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at (wrapper remoting-invoke-with-check) Mono.Data.Sqlite.SqliteDataReader..ctor(Mono.Data.Sqlite.SqliteCommand,System.Data.CommandBehavior)
  at Mono.Data.Sqlite.SqliteCommand.ExecuteReader (System.Data.CommandBehavior behavior) [0x00006] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteCommand.ExecuteNonQuery () [0x00000] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteTransaction..ctor (Mono.Data.Sqlite.SqliteConnection connection, System.Boolean deferredLock) [0x000a2] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at (wrapper remoting-invoke-with-check) Mono.Data.Sqlite.SqliteTransaction..ctor(Mono.Data.Sqlite.SqliteConnection,bool)
  at Mono.Data.Sqlite.SqliteConnection.BeginDbTransaction (System.Data.IsolationLevel isolationLevel) [0x00036] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at System.Data.Common.DbConnection.System.Data.IDbConnection.BeginTransaction () [0x00000] in <d3b33a5f75ba43539b447fcfaf55c885>:0 
  at Duplicati.Library.Main.Database.LocalDatabase.Dispose () [0x0002e] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Operation.ListFilesHandler.Run (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter compositefilter) [0x00206] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller+<>c__DisplayClass21_0.<List>b__0 (Duplicati.Library.Main.ListResults result) [0x0001c] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, System.String[]& paths, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x0026f] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.List (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter filter) [0x00021] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Server.Runner.Run (Duplicati.Server.Runner+IRunnerData data, System.Boolean fromQueue) [0x00668] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTMethods.Backup.SearchFiles (Duplicati.Server.Serialization.Interface.IBackup backup, System.String filterstring, Duplicati.Server.WebServer.RESTMethods.RequestInfo info) [0x000cb] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTMethods.Backup.GET (System.String key, Duplicati.Server.WebServer.RESTMethods.RequestInfo info) [0x001e0] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTHandler.DoProcess (Duplicati.Server.WebServer.RESTMethods.RequestInfo info, System.String method, System.String module, System.String key) [0x00146] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
Jan 15, 2022 5:38 PM: Reporting error gave error
System.ObjectDisposedException: Can not write to a closed TextWriter.
  at System.IO.StreamWriter.Flush (System.Boolean flushStream, System.Boolean flushEncoder) [0x00008] in <533173d24dae460899d2b10975534bb0>:0 
  at System.IO.StreamWriter.Flush () [0x00006] in <533173d24dae460899d2b10975534bb0>:0 
  at Duplicati.Server.WebServer.RESTHandler.DoProcess (Duplicati.Server.WebServer.RESTMethods.RequestInfo info, System.String method, System.String module, System.String key) [0x003bc] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
Jan 15, 2022 5:38 PM: Failed while executing "List" with id: 12
Mono.Data.Sqlite.SqliteException (0x80004005): The database file is locked
database is locked
  at Mono.Data.Sqlite.SQLite3.Step (Mono.Data.Sqlite.SqliteStatement stmt) [0x00089] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader.NextResult () [0x00104] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader..ctor (Mono.Data.Sqlite.SqliteCommand cmd, System.Data.CommandBehavior behave) [0x0004e] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at (wrapper remoting-invoke-with-check) Mono.Data.Sqlite.SqliteDataReader..ctor(Mono.Data.Sqlite.SqliteCommand,System.Data.CommandBehavior)
  at Mono.Data.Sqlite.SqliteCommand.ExecuteReader (System.Data.CommandBehavior behavior) [0x00006] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteCommand.ExecuteNonQuery () [0x00000] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteTransaction..ctor (Mono.Data.Sqlite.SqliteConnection connection, System.Boolean deferredLock) [0x000a2] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at (wrapper remoting-invoke-with-check) Mono.Data.Sqlite.SqliteTransaction..ctor(Mono.Data.Sqlite.SqliteConnection,bool)
  at Mono.Data.Sqlite.SqliteConnection.BeginDbTransaction (System.Data.IsolationLevel isolationLevel) [0x00036] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at System.Data.Common.DbConnection.System.Data.IDbConnection.BeginTransaction () [0x00000] in <d3b33a5f75ba43539b447fcfaf55c885>:0 
  at Duplicati.Library.Main.Database.LocalDatabase.Dispose () [0x0002e] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Operation.ListFilesHandler.Run (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter compositefilter) [0x00206] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller+<>c__DisplayClass21_0.<List>b__0 (Duplicati.Library.Main.ListResults result) [0x0001c] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, System.String[]& paths, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x0026f] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.List (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter filter) [0x00021] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Server.Runner.Run (Duplicati.Server.Runner+IRunnerData data, System.Boolean fromQueue) [0x00374] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
Jan 15, 2022 5:27 PM: Failed while executing "List" with id: 12
Mono.Data.Sqlite.SqliteException (0x80004005): The database file is locked
database is locked
  at Mono.Data.Sqlite.SQLite3.Step (Mono.Data.Sqlite.SqliteStatement stmt) [0x00089] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader.NextResult () [0x00104] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader..ctor (Mono.Data.Sqlite.SqliteCommand cmd, System.Data.CommandBehavior behave) [0x0004e] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at (wrapper remoting-invoke-with-check) Mono.Data.Sqlite.SqliteDataReader..ctor(Mono.Data.Sqlite.SqliteCommand,System.Data.CommandBehavior)
  at Mono.Data.Sqlite.SqliteCommand.ExecuteReader (System.Data.CommandBehavior behavior) [0x00006] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteCommand.ExecuteDbDataReader (System.Data.CommandBehavior behavior) [0x00000] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at System.Data.Common.DbCommand.System.Data.IDbCommand.ExecuteReader () [0x00000] in <d3b33a5f75ba43539b447fcfaf55c885>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.Boolean writeLog, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00061] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.Data.IDbConnection connection, System.String operation) [0x0005e] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.String path, System.String operation, System.Boolean shouldclose) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalListDatabase..ctor (System.String path) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Operation.ListFilesHandler.Run (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter compositefilter) [0x00075] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller+<>c__DisplayClass21_0.<List>b__0 (Duplicati.Library.Main.ListResults result) [0x0001c] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, System.String[]& paths, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x0026f] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.List (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter filter) [0x00021] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Server.Runner.Run (Duplicati.Server.Runner+IRunnerData data, System.Boolean fromQueue) [0x00374] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
Jan 15, 2022 5:27 PM: Request for http://127.0.0.1:8196/api/v1/backup/12/files/*?prefix-only=true&folder-contents=false&time=2022-01-10T07:00:01-05:00 gave error
Mono.Data.Sqlite.SqliteException (0x80004005): The database file is locked
database is locked
  at Mono.Data.Sqlite.SQLite3.Step (Mono.Data.Sqlite.SqliteStatement stmt) [0x00089] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader.NextResult () [0x00104] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteDataReader..ctor (Mono.Data.Sqlite.SqliteCommand cmd, System.Data.CommandBehavior behave) [0x0004e] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at (wrapper remoting-invoke-with-check) Mono.Data.Sqlite.SqliteDataReader..ctor(Mono.Data.Sqlite.SqliteCommand,System.Data.CommandBehavior)
  at Mono.Data.Sqlite.SqliteCommand.ExecuteReader (System.Data.CommandBehavior behavior) [0x00006] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at Mono.Data.Sqlite.SqliteCommand.ExecuteDbDataReader (System.Data.CommandBehavior behavior) [0x00000] in <83c72f6e53eb49f28420feee73a4aa07>:0 
  at System.Data.Common.DbCommand.System.Data.IDbCommand.ExecuteReader () [0x00000] in <d3b33a5f75ba43539b447fcfaf55c885>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.Boolean writeLog, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00061] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.ExtensionMethods.ExecuteScalarInt64 (System.Data.IDbCommand self, System.String cmd, System.Int64 defaultvalue, System.Object[] values) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.Data.IDbConnection connection, System.String operation) [0x0005e] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalDatabase..ctor (System.String path, System.String operation, System.Boolean shouldclose) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Database.LocalListDatabase..ctor (System.String path) [0x00000] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Operation.ListFilesHandler.Run (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter compositefilter) [0x00075] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller+<>c__DisplayClass21_0.<List>b__0 (Duplicati.Library.Main.ListResults result) [0x0001c] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, System.String[]& paths, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x0026f] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.RunAction[T] (T result, Duplicati.Library.Utility.IFilter& filter, System.Action`1[T] method) [0x00007] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Library.Main.Controller.List (System.Collections.Generic.IEnumerable`1[T] filterstrings, Duplicati.Library.Utility.IFilter filter) [0x00021] in <1d9349a5d6874088879a75d074c92b62>:0 
  at Duplicati.Server.Runner.Run (Duplicati.Server.Runner+IRunnerData data, System.Boolean fromQueue) [0x00668] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTMethods.Backup.SearchFiles (Duplicati.Server.Serialization.Interface.IBackup backup, System.String filterstring, Duplicati.Server.WebServer.RESTMethods.RequestInfo info) [0x000cb] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTMethods.Backup.GET (System.String key, Duplicati.Server.WebServer.RESTMethods.RequestInfo info) [0x001e0] in <3752ce5d8337471da6b77129cfa4bdbe>:0 
  at Duplicati.Server.WebServer.RESTHandler.DoProcess (Duplicati.Server.WebServer.RESTMethods.RequestInfo info, System.String method, System.String module, System.String key) [0x00146] in <3752ce5d8337471da6b77129cfa4bdbe>:0 

It looks like Duplicati is using a longpoll=true&duration=299s as well.


These are the service startup settings the only flag passed is the webserver port to not conflict with other services (IceCast).

service duplicati status
● duplicati.service - Duplicati web-server
     Loaded: loaded (/lib/systemd/system/duplicati.service; enabled; vendor preset: enabled)
     Active: active (running) since Sat 2022-01-15 16:30:02 EST; 1h 38min ago
   Main PID: 3051497 (mono)
      Tasks: 29 (limit: 629145)
     Memory: 138.7M
     CGroup: /system.slice/duplicati.service
             ├─3051497 DuplicatiServer /usr/lib/duplicati/Duplicati.Server.exe --webservice-port=8196
             └─3053717 /usr/bin/mono-sgen /usr/share/Duplicati/updates/2.0.6.100/Duplicati.Server.exe --webservice-port=8196

That’s live log. Generally you have to start it and then do something. Log file setup is on Options screen.

This (like any stack trace) is good to see the path to the failure, but it doesn’t show what else is going on which potentially caused the failure, for example database work from some other thread. For even more

image

Restore fails with “Failed to connect: The database file is locked database is locked” links to a GitHub issue talking about threads, but stack trace is not like yours is, and I don’t know if you ever press Stop as in issue.

I’m not sure what action that causes. There’s a fair amount of simultaneous web work going into the server.
Running Wireshark on unencrypted localhost would catch it, but then you’d spend awhile trying to sort it out.

Sometimes it’s helpful to find the smallest backup that can show an issue. If nothing else, log is smaller.
I’m not sure if current path will succeed without more developer help, and developers are very scarce…

EDIT:

For comparison to yours, here’s my maximal profiling log file when I hit Restore button and it works OK:

2022-01-15 20:11:20 -05 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation List has started
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Controller-RunList]: Starting - Running List
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: Starting - ExecuteScalarInt64: INSERT INTO "Operation" ("Description", "Timestamp") VALUES ("List", 1642295480); SELECT last_insert_rowid();
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: ExecuteScalarInt64: INSERT INTO "Operation" ("Description", "Timestamp") VALUES ("List", 1642295480); SELECT last_insert_rowid(); took 0:00:00:00.075
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "Filesets-2B71CA0705475D459B2123BF74462B40" AS SELECT DISTINCT "ID" AS "FilesetID", "IsFullBackup" AS "IsFullBackup" , "Timestamp" AS "Timestamp" FROM "Fileset" 
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "Filesets-2B71CA0705475D459B2123BF74462B40" AS SELECT DISTINCT "ID" AS "FilesetID", "IsFullBackup" AS "IsFullBackup" , "Timestamp" AS "Timestamp" FROM "Fileset"  took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE INDEX "Filesets-2B71CA0705475D459B2123BF74462B40_FilesetIDTimestampIndex" ON "Filesets-2B71CA0705475D459B2123BF74462B40" ("FilesetID", "Timestamp" DESC)
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE INDEX "Filesets-2B71CA0705475D459B2123BF74462B40_FilesetIDTimestampIndex" ON "Filesets-2B71CA0705475D459B2123BF74462B40" ("FilesetID", "Timestamp" DESC) took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT DISTINCT "ID", "IsFullBackup" FROM "Fileset" ORDER BY "Timestamp" DESC 
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT DISTINCT "ID", "IsFullBackup" FROM "Fileset" ORDER BY "Timestamp" DESC  took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "Filesets-2B71CA0705475D459B2123BF74462B40" 
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "Filesets-2B71CA0705475D459B2123BF74462B40"  took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: PRAGMA optimize
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: PRAGMA optimize took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Controller-RunList]: Running List took 0:00:00:00.084
2022-01-15 20:11:20 -05 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation List has started
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Controller-RunList]: Starting - Running List
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: Starting - ExecuteScalarInt64: INSERT INTO "Operation" ("Description", "Timestamp") VALUES ("List", 1642295480); SELECT last_insert_rowid();
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: ExecuteScalarInt64: INSERT INTO "Operation" ("Description", "Timestamp") VALUES ("List", 1642295480); SELECT last_insert_rowid(); took 0:00:00:00.084
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "Filesets-0CD3B441684AD545A7B348B8F9B54714" AS SELECT DISTINCT "ID" AS "FilesetID", "IsFullBackup" AS "IsFullBackup" , "Timestamp" AS "Timestamp" FROM "Fileset"  WHERE  "Timestamp" <= 1639969879
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "Filesets-0CD3B441684AD545A7B348B8F9B54714" AS SELECT DISTINCT "ID" AS "FilesetID", "IsFullBackup" AS "IsFullBackup" , "Timestamp" AS "Timestamp" FROM "Fileset"  WHERE  "Timestamp" <= 1639969879 took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE INDEX "Filesets-0CD3B441684AD545A7B348B8F9B54714_FilesetIDTimestampIndex" ON "Filesets-0CD3B441684AD545A7B348B8F9B54714" ("FilesetID", "Timestamp" DESC)
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE INDEX "Filesets-0CD3B441684AD545A7B348B8F9B54714_FilesetIDTimestampIndex" ON "Filesets-0CD3B441684AD545A7B348B8F9B54714" ("FilesetID", "Timestamp" DESC) took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DELETE FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714" WHERE "FilesetID" NOT IN (SELECT "FilesetID" FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714" ORDER BY "Timestamp" DESC LIMIT 1 )
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DELETE FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714" WHERE "FilesetID" NOT IN (SELECT "FilesetID" FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714" ORDER BY "Timestamp" DESC LIMIT 1 ) took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "Filenames-FB912B459B475A4DB189B19D714D3C56" ("Path" TEXT NOT NULL)
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "Filenames-FB912B459B475A4DB189B19D714D3C56" ("Path" TEXT NOT NULL) took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: INSERT INTO "Filenames-FB912B459B475A4DB189B19D714D3C56" SELECT DISTINCT "Path" FROM "File" WHERE "Path" LIKE "%"
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: INSERT INTO "Filenames-FB912B459B475A4DB189B19D714D3C56" SELECT DISTINCT "Path" FROM "File" WHERE "Path" LIKE "%" took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DELETE FROM "Filenames-FB912B459B475A4DB189B19D714D3C56" WHERE "Path" NOT IN (SELECT DISTINCT "Path" FROM "File", "FilesetEntry" WHERE "FilesetEntry"."FileID" = "File"."ID" AND "FilesetEntry"."FilesetID" IN (SELECT "FilesetID" FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714") ) 
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DELETE FROM "Filenames-FB912B459B475A4DB189B19D714D3C56" WHERE "Path" NOT IN (SELECT DISTINCT "Path" FROM "File", "FilesetEntry" WHERE "FilesetEntry"."FileID" = "File"."ID" AND "FilesetEntry"."FilesetID" IN (SELECT "FilesetID" FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714") )  took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM "Filenames-FB912B459B475A4DB189B19D714D3C56"
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: ExecuteScalarInt64: SELECT COUNT(*) FROM "Filenames-FB912B459B475A4DB189B19D714D3C56" took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM "Filenames-FB912B459B475A4DB189B19D714D3C56" WHERE SUBSTR("Path", 1, 23) = "C:\backup source\A.txt\"
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: ExecuteScalarInt64: SELECT COUNT(*) FROM "Filenames-FB912B459B475A4DB189B19D714D3C56" WHERE SUBSTR("Path", 1, 23) = "C:\backup source\A.txt\" took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM "Filenames-FB912B459B475A4DB189B19D714D3C56" WHERE SUBSTR("Path", 1, 17) = "C:\backup source\"
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: ExecuteScalarInt64: SELECT COUNT(*) FROM "Filenames-FB912B459B475A4DB189B19D714D3C56" WHERE SUBSTR("Path", 1, 17) = "C:\backup source\" took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "Filenames-FB912B459B475A4DB189B19D714D3C56" 
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "Filenames-FB912B459B475A4DB189B19D714D3C56"  took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT DISTINCT "A"."FilesetID", "A"."IsFullBackup", "B"."FileCount", "B"."FileSizes" FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714" A LEFT OUTER JOIN ( SELECT "A"."FilesetID" AS "FilesetID", COUNT(*) AS "FileCount", SUM("C"."Length") AS "FileSizes" FROM "FilesetEntry" A, "File" B, "Blockset" C WHERE "A"."FileID" = "B"."ID" AND "B"."BlocksetID" = "C"."ID" AND "A"."FilesetID" IN (SELECT DISTINCT "FilesetID" FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714") GROUP BY "A"."FilesetID"  ) B ON "A"."FilesetID" = "B"."FilesetID" ORDER BY "A"."Timestamp" DESC 
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT DISTINCT "A"."FilesetID", "A"."IsFullBackup", "B"."FileCount", "B"."FileSizes" FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714" A LEFT OUTER JOIN ( SELECT "A"."FilesetID" AS "FilesetID", COUNT(*) AS "FileCount", SUM("C"."Length") AS "FileSizes" FROM "FilesetEntry" A, "File" B, "Blockset" C WHERE "A"."FileID" = "B"."ID" AND "B"."BlocksetID" = "C"."ID" AND "A"."FilesetID" IN (SELECT DISTINCT "FilesetID" FROM "Filesets-0CD3B441684AD545A7B348B8F9B54714") GROUP BY "A"."FilesetID"  ) B ON "A"."FilesetID" = "B"."FilesetID" ORDER BY "A"."Timestamp" DESC  took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "Filesets-0CD3B441684AD545A7B348B8F9B54714" 
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "Filesets-0CD3B441684AD545A7B348B8F9B54714"  took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: PRAGMA optimize
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: PRAGMA optimize took 0:00:00:00.000
2022-01-15 20:11:20 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Controller-RunList]: Running List took 0:00:00:00.090
2022-01-15 20:11:20 -05 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation List has started
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Controller-RunList]: Starting - Running List
2022-01-15 20:11:20 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: Starting - ExecuteScalarInt64: INSERT INTO "Operation" ("Description", "Timestamp") VALUES ("List", 1642295480); SELECT last_insert_rowid();
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteScalarInt64]: ExecuteScalarInt64: INSERT INTO "Operation" ("Description", "Timestamp") VALUES ("List", 1642295480); SELECT last_insert_rowid(); took 0:00:00:00.110
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT "ID", "Timestamp" FROM "Fileset" ORDER BY "Timestamp" DESC took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" AS SELECT DISTINCT "ID" AS "FilesetID", "IsFullBackup" AS "IsFullBackup" , "Timestamp" AS "Timestamp" FROM "Fileset"  WHERE  "Timestamp" <= 1639969879
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" AS SELECT DISTINCT "ID" AS "FilesetID", "IsFullBackup" AS "IsFullBackup" , "Timestamp" AS "Timestamp" FROM "Fileset"  WHERE  "Timestamp" <= 1639969879 took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE INDEX "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E_FilesetIDTimestampIndex" ON "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" ("FilesetID", "Timestamp" DESC)
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE INDEX "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E_FilesetIDTimestampIndex" ON "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" ("FilesetID", "Timestamp" DESC) took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DELETE FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" WHERE "FilesetID" NOT IN (SELECT "FilesetID" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" ORDER BY "Timestamp" DESC LIMIT 1 )
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DELETE FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" WHERE "FilesetID" NOT IN (SELECT "FilesetID" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" ORDER BY "Timestamp" DESC LIMIT 1 ) took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT DISTINCT "A"."FilesetID", "A"."IsFullBackup", "B"."FileCount", "B"."FileSizes" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" A LEFT OUTER JOIN ( SELECT "A"."FilesetID" AS "FilesetID", COUNT(*) AS "FileCount", SUM("C"."Length") AS "FileSizes" FROM "FilesetEntry" A, "File" B, "Blockset" C WHERE "A"."FileID" = "B"."ID" AND "B"."BlocksetID" = "C"."ID" AND "A"."FilesetID" IN (SELECT DISTINCT "FilesetID" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E") GROUP BY "A"."FilesetID"  ) B ON "A"."FilesetID" = "B"."FilesetID" ORDER BY "A"."Timestamp" DESC 
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT DISTINCT "A"."FilesetID", "A"."IsFullBackup", "B"."FileCount", "B"."FileSizes" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" A LEFT OUTER JOIN ( SELECT "A"."FilesetID" AS "FilesetID", COUNT(*) AS "FileCount", SUM("C"."Length") AS "FileSizes" FROM "FilesetEntry" A, "File" B, "Blockset" C WHERE "A"."FileID" = "B"."ID" AND "B"."BlocksetID" = "C"."ID" AND "A"."FilesetID" IN (SELECT DISTINCT "FilesetID" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E") GROUP BY "A"."FilesetID"  ) B ON "A"."FilesetID" = "B"."FilesetID" ORDER BY "A"."Timestamp" DESC  took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "Filenames-558391EFCE46544098E69EDFF4871BE3" ("Path" TEXT NOT NULL)
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "Filenames-558391EFCE46544098E69EDFF4871BE3" ("Path" TEXT NOT NULL) took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: INSERT INTO "Filenames-558391EFCE46544098E69EDFF4871BE3" SELECT DISTINCT "Path" FROM "File" WHERE "Path" LIKE "C:\BACKUP SOURCE\%"
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: INSERT INTO "Filenames-558391EFCE46544098E69EDFF4871BE3" SELECT DISTINCT "Path" FROM "File" WHERE "Path" LIKE "C:\BACKUP SOURCE\%" took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DELETE FROM "Filenames-558391EFCE46544098E69EDFF4871BE3" WHERE "Path" NOT IN (SELECT DISTINCT "Path" FROM "File", "FilesetEntry" WHERE "FilesetEntry"."FileID" = "File"."ID" AND "FilesetEntry"."FilesetID" IN (SELECT "FilesetID" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E") ) 
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DELETE FROM "Filenames-558391EFCE46544098E69EDFF4871BE3" WHERE "Path" NOT IN (SELECT DISTINCT "Path" FROM "File", "FilesetEntry" WHERE "FilesetEntry"."FileID" = "File"."ID" AND "FilesetEntry"."FilesetID" IN (SELECT "FilesetID" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E") )  took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "Filenames-1BFE49F5FB7587409B162638F350F20E" ("Path" TEXT NOT NULL)
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "Filenames-1BFE49F5FB7587409B162638F350F20E" ("Path" TEXT NOT NULL) took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT DISTINCT "Path" FROM "Filenames-558391EFCE46544098E69EDFF4871BE3" 
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT DISTINCT "Path" FROM "Filenames-558391EFCE46544098E69EDFF4871BE3"  took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE INDEX "Filenames-1BFE49F5FB7587409B162638F350F20E_PathIndex" ON "Filenames-1BFE49F5FB7587409B162638F350F20E" ("Path")
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE INDEX "Filenames-1BFE49F5FB7587409B162638F350F20E_PathIndex" ON "Filenames-1BFE49F5FB7587409B162638F350F20E" ("Path") took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT "C"."Path", "D"."Length", "C"."FilesetID" FROM (SELECT "A"."Path", "B"."FilesetID" FROM "Filenames-1BFE49F5FB7587409B162638F350F20E" A, (SELECT "FilesetID", "Timestamp" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" ORDER BY "Timestamp" DESC) B ORDER BY "A"."Path" ASC, "B"."Timestamp" DESC) C LEFT OUTER JOIN (SELECT "Length", "FilesetEntry"."FilesetID", "File"."Path" FROM "Blockset", "FilesetEntry", "File" WHERE "File"."BlocksetID" = "Blockset"."ID" AND "FilesetEntry"."FileID" = "File"."ID" AND FilesetEntry."FilesetID" IN (SELECT DISTINCT "FilesetID" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E") ) D ON "C"."FilesetID" = "D"."FilesetID" AND "C"."Path" = "D"."Path"
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT "C"."Path", "D"."Length", "C"."FilesetID" FROM (SELECT "A"."Path", "B"."FilesetID" FROM "Filenames-1BFE49F5FB7587409B162638F350F20E" A, (SELECT "FilesetID", "Timestamp" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" ORDER BY "Timestamp" DESC) B ORDER BY "A"."Path" ASC, "B"."Timestamp" DESC) C LEFT OUTER JOIN (SELECT "Length", "FilesetEntry"."FilesetID", "File"."Path" FROM "Blockset", "FilesetEntry", "File" WHERE "File"."BlocksetID" = "Blockset"."ID" AND "FilesetEntry"."FileID" = "File"."ID" AND FilesetEntry."FilesetID" IN (SELECT DISTINCT "FilesetID" FROM "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E") ) D ON "C"."FilesetID" = "D"."FilesetID" AND "C"."Path" = "D"."Path" took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "Filenames-558391EFCE46544098E69EDFF4871BE3" 
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "Filenames-558391EFCE46544098E69EDFF4871BE3"  took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "Filenames-1BFE49F5FB7587409B162638F350F20E"
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "Filenames-1BFE49F5FB7587409B162638F350F20E" took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E" 
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "Filesets-43B68CC1AE30DE4ABD3A626B94D57F2E"  took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: PRAGMA optimize
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: PRAGMA optimize took 0:00:00:00.000
2022-01-15 20:11:21 -05 - [Profiling-Timer.Finished-Duplicati.Library.Main.Controller-RunList]: Running List took 0:00:00:00.117

So a backup started on the original server host and the profiling tab looks okay. Last time I tried selecting a restore though it didn’t do anything in that tab. The backup process is still cleaning up.

Jan 17, 2022 2:49 PM: Backend event: Get - Started: duplicati-b0ab6c0ded87e49ed9b8de39e6fa99c59.dblock.zip.aes (3.64 GB)
Jan 17, 2022 2:49 PM: Starting - RemoteOperationGet
Jan 17, 2022 1:23 PM: RemoteOperationDelete took 0:00:00:00.100
Jan 17, 2022 1:23 PM: Backend event: Delete - Completed: duplicati-i6bcb26fa2853477991e75a81ddd33d2c.dindex.zip.aes (53.07 MB)
Jan 17, 2022 1:23 PM: Backend event: Delete - Started: duplicati-i6bcb26fa2853477991e75a81ddd33d2c.dindex.zip.aes (53.07 MB)
Jan 17, 2022 1:23 PM: Starting - RemoteOperationDelete
Jan 17, 2022 1:23 PM: RemoteOperationDelete took 0:00:00:27.860
Jan 17, 2022 1:23 PM: Backend event: Delete - Completed: duplicati-b87571587bf3d46ac96ea8f52ee18fe5d.dblock.zip.aes (20.02 GB)
Jan 17, 2022 1:23 PM: Backend event: Delete - Started: duplicati-b87571587bf3d46ac96ea8f52ee18fe5d.dblock.zip.aes (20.02 GB)
Jan 17, 2022 1:23 PM: Starting - RemoteOperationDelete
Jan 17, 2022 1:23 PM: RemoteOperationDelete took 0:00:00:00.000
Jan 17, 2022 1:23 PM: Backend event: Delete - Completed: duplicati-id2b2915a9eaa43a79840d4faf35aa921.dindex.zip.aes (15.70 MB)
Jan 17, 2022 1:23 PM: Backend event: Delete - Started: duplicati-id2b2915a9eaa43a79840d4faf35aa921.dindex.zip.aes (15.70 MB)
Jan 17, 2022 1:23 PM: Starting - RemoteOperationDelete
Jan 17, 2022 1:23 PM: RemoteOperationDelete took 0:00:00:00.953
Jan 17, 2022 1:23 PM: Backend event: Delete - Completed: duplicati-be6e1caa820cd4a3ab0b67fa0fc7a9e26.dblock.zip.aes (2.78 GB)
Jan 17, 2022 1:23 PM: Backend event: Delete - Started: duplicati-be6e1caa820cd4a3ab0b67fa0fc7a9e26.dblock.zip.aes (2.78 GB)
Jan 17, 2022 1:23 PM: Starting - RemoteOperationDelete
Jan 17, 2022 1:23 PM: RemoteOperationDelete took 0:00:00:00.101
Jan 17, 2022 1:23 PM: Backend event: Delete - Completed: duplicati-i16dfcb12f1b347de9b5ac4887c016189.dindex.zip.aes (23.06 MB)
Jan 17, 2022 1:23 PM: Backend event: Delete - Started: duplicati-i16dfcb12f1b347de9b5ac4887c016189.dindex.zip.aes (23.06 MB)
Jan 17, 2022 1:23 PM: Starting - RemoteOperationDelete
Jan 17, 2022 1:23 PM: RemoteOperationDelete took 0:00:00:08.659
Jan 17, 2022 1:23 PM: Backend event: Delete - Completed: duplicati-b259732dc6cc0478d9aa41678b86b6d7a.dblock.zip.aes (9.05 GB)
Jan 17, 2022 1:23 PM: Backend event: Delete - Started: duplicati-b259732dc6cc0478d9aa41678b86b6d7a.dblock.zip.aes (9.05 GB)
Jan 17, 2022 1:23 PM: Starting - RemoteOperationDelete
Jan 17, 2022 1:23 PM: ExecuteReader: SELECT "C"."Name", "B"."Name", "B"."Hash", "B"."Size" FROM "IndexBlockLink" A, "RemoteVolume" B, "RemoteVolume" C WHERE "A"."IndexVolumeID" = "B"."ID" AND "A"."BlockVolumeID" = "C"."ID" AND "B"."Hash" IS NOT NULL AND "B"."Size" IS NOT NULL took 0:00:00:00.000
Jan 17, 2022 1:23 PM: Starting - ExecuteReader: SELECT "C"."Name", "B"."Name", "B"."Hash", "B"."Size" FROM "IndexBlockLink" A, "RemoteVolume" B, "RemoteVolume" C WHERE "A"."IndexVolumeID" = "B"."ID" AND "A"."BlockVolumeID" = "C"."ID" AND "B"."Hash" IS NOT NULL AND "B"."Size" IS NOT NULL
Jan 17, 2022 1:22 PM: RemoteOperationNothing took 0:00:00:00.000
Jan 17, 2022 1:22 PM: Starting - RemoteOperationNothing
Jan 17, 2022 1:22 PM: RemoteOperationPut took 0:00:00:02.879
Jan 17, 2022 1:22 PM: Backend event: Put - Completed: duplicati-i746b22218fed42159cd2a5ae1367d1a9.dindex.zip.aes (49.11 MB)
Jan 17, 2022 1:22 PM: Uploaded 49.11 MB in 00:00:00.1168150, 420.42 MB/s
Jan 17, 2022 1:22 PM: Backend event: Put - Started: duplicati-i746b22218fed42159cd2a5ae1367d1a9.dindex.zip.aes (49.11 MB)
Jan 17, 2022 1:22 PM: Starting - RemoteOperationPut
Jan 17, 2022 1:22 PM: RemoteOperationPut took 0:00:00:49.657
Jan 17, 2022 1:22 PM: Backend event: Put - Completed: duplicati-be5e0e8f2b4794da291307f5321aa42c9.dblock.zip.aes (20.02 GB)
Jan 17, 2022 1:22 PM: Uploaded 20.02 GB in 00:00:45.9673850, 445.93 MB/s
Jan 17, 2022 1:22 PM: ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (25, "duplicati-ieed54727c85e41c58a83b9ddcf8964e6.dindex.zip.aes", "Index", "Temporary", -1, 0, 0); SELECT last_insert_rowid(); took 0:00:00:00.000
Jan 17, 2022 1:22 PM: Starting - ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (25, "duplicati-ieed54727c85e41c58a83b9ddcf8964e6.dindex.zip.aes", "Index", "Temporary", -1, 0, 0); SELECT last_insert_rowid();
Jan 17, 2022 1:22 PM: ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (25, "duplicati-baf3de9b5634541378629d08a7d8497ed.dblock.zip.aes", "Blocks", "Temporary", -1, 0, 0); SELECT last_insert_rowid(); took 0:00:00:00.083
Jan 17, 2022 1:22 PM: Backend event: Put - Started: duplicati-be5e0e8f2b4794da291307f5321aa42c9.dblock.zip.aes (20.02 GB)
Jan 17, 2022 1:22 PM: Starting - ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (25, "duplicati-baf3de9b5634541378629d08a7d8497ed.dblock.zip.aes", "Blocks", "Temporary", -1, 0, 0); SELECT last_insert_rowid();
Jan 17, 2022 1:22 PM: Starting - RemoteOperationPut
Jan 17, 2022 1:21 PM: ExecuteReader: SELECT "A"."Hash", "C"."Hash" FROM (SELECT "BlocklistHash"."BlocksetID", "Block"."Hash", * FROM "BlocklistHash","Block" WHERE "BlocklistHash"."Hash" = "Block"."Hash" AND "Block"."VolumeID" = 194) A, "BlocksetEntry" B, "Block" C WHERE "B"."BlocksetID" = "A"."BlocksetID" AND "B"."Index" >= ("A"."Index" * 3200) AND "B"."Index" < (("A"."Index" + 1) * 3200) AND "C"."ID" = "B"."BlockID" ORDER BY "A"."BlocksetID", "B"."Index" took 0:00:01:31.866
Jan 17, 2022 1:19 PM: Starting - ExecuteReader: SELECT "A"."Hash", "C"."Hash" FROM (SELECT "BlocklistHash"."BlocksetID", "Block"."Hash", * FROM "BlocklistHash","Block" WHERE "BlocklistHash"."Hash" = "Block"."Hash" AND "Block"."VolumeID" = 194) A, "BlocksetEntry" B, "Block" C WHERE "B"."BlocksetID" = "A"."BlocksetID" AND "B"."Index" >= ("A"."Index" * 3200) AND "B"."Index" < (("A"."Index" + 1) * 3200) AND "C"."ID" = "B"."BlockID" ORDER BY "A"."BlocksetID", "B"."Index"
Jan 17, 2022 12:17 PM: RemoteOperationGet took 0:00:09:00.885
Jan 17, 2022 12:17 PM: Backend event: Get - Completed: duplicati-bb67cb663e1f648b784fad037e49511f8.dblock.zip.aes (20.01 GB)
Jan 17, 2022 12:17 PM: Downloaded and decrypted 20.01 GB in 00:09:00.8846850, 37.88 MB/s
Jan 17, 2022 12:08 PM: Backend event: Get - Started: duplicati-bb67cb663e1f648b784fad037e49511f8.dblock.zip.aes (20.01 GB)
Jan 17, 2022 12:08 PM: Starting - RemoteOperationGet
Jan 17, 2022 11:22 AM: RemoteOperationGet took 0:00:08:58.152
Jan 17, 2022 11:22 AM: Backend event: Get - Completed: duplicati-b39ed876d7bb748d3acbbf515f53fe0f8.dblock.zip.aes (20.01 GB)
Jan 17, 2022 11:22 AM: Downloaded and decrypted 20.01 GB in 00:08:58.1523090, 38.08 MB/s
Jan 17, 2022 11:13 AM: Backend event: Get - Started: duplicati-b39ed876d7bb748d3acbbf515f53fe0f8.dblock.zip.aes (20.01 GB)
Jan 17, 2022 11:13 AM: Starting - RemoteOperationGet
Jan 17, 2022 11:12 AM: RemoteOperationGet took 0:00:09:24.073
Jan 17, 2022 11:12 AM: Backend event: Get - Completed: duplicati-b87571587bf3d46ac96ea8f52ee18fe5d.dblock.zip.aes (20.02 GB)
Jan 17, 2022 11:12 AM: Downloaded and decrypted 20.02 GB in 00:09:24.0723860, 36.33 MB/s
Jan 17, 2022 11:02 AM: Backend event: Get - Started: duplicati-b87571587bf3d46ac96ea8f52ee18fe5d.dblock.zip.aes (20.02 GB)
Jan 17, 2022 11:02 AM: Starting - RemoteOperationGet
Jan 17, 2022 10:07 AM: RemoteOperationGet took 0:00:01:21.073
Jan 17, 2022 10:07 AM: Backend event: Get - Completed: duplicati-be6e1caa820cd4a3ab0b67fa0fc7a9e26.dblock.zip.aes (2.78 GB)
Jan 17, 2022 10:07 AM: Downloaded and decrypted 2.78 GB in 00:01:21.0735010, 35.06 MB/s
Jan 17, 2022 10:05 AM: Backend event: Get - Started: duplicati-be6e1caa820cd4a3ab0b67fa0fc7a9e26.dblock.zip.aes (2.78 GB)
Jan 17, 2022 10:05 AM: Starting - RemoteOperationGet
Jan 17, 2022 10:05 AM: RemoteOperationGet took 0:00:04:11.573
Jan 17, 2022 10:05 AM: Backend event: Get - Completed: duplicati-b259732dc6cc0478d9aa41678b86b6d7a.dblock.zip.aes (9.05 GB)
Jan 17, 2022 10:05 AM: Downloaded and decrypted 9.05 GB in 00:04:11.5732870, 36.85 MB/s
Jan 17, 2022 10:01 AM: Backend event: Get - Started: duplicati-b259732dc6cc0478d9aa41678b86b6d7a.dblock.zip.aes (9.05 GB)
Jan 17, 2022 10:01 AM: Starting - RemoteOperationGet
Jan 17, 2022 10:01 AM: ExecuteReader: SELECT "C"."Name", "B"."Name", "B"."Hash", "B"."Size" FROM "IndexBlockLink" A, "RemoteVolume" B, "RemoteVolume" C WHERE "A"."IndexVolumeID" = "B"."ID" AND "A"."BlockVolumeID" = "C"."ID" AND "B"."Hash" IS NOT NULL AND "B"."Size" IS NOT NULL took 0:00:00:00.000
Jan 17, 2022 10:01 AM: Starting - ExecuteReader: SELECT "C"."Name", "B"."Name", "B"."Hash", "B"."Size" FROM "IndexBlockLink" A, "RemoteVolume" B, "RemoteVolume" C WHERE "A"."IndexVolumeID" = "B"."ID" AND "A"."BlockVolumeID" = "C"."ID" AND "B"."Hash" IS NOT NULL AND "B"."Size" IS NOT NULL
Jan 17, 2022 10:01 AM: RemoteOperationNothing took 0:00:00:00.000
Jan 17, 2022 10:01 AM: Starting - RemoteOperationNothing
Jan 17, 2022 10:01 AM: ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (25, "duplicati-i746b22218fed42159cd2a5ae1367d1a9.dindex.zip.aes", "Index", "Temporary", -1, 0, 0); SELECT last_insert_rowid(); took 0:00:00:00.000
Jan 17, 2022 10:01 AM: Starting - ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (25, "duplicati-i746b22218fed42159cd2a5ae1367d1a9.dindex.zip.aes", "Index", "Temporary", -1, 0, 0); SELECT last_insert_rowid();
Jan 17, 2022 10:01 AM: ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (25, "duplicati-be5e0e8f2b4794da291307f5321aa42c9.dblock.zip.aes", "Blocks", "Temporary", -1, 0, 0); SELECT last_insert_rowid(); took 0:00:00:00.000
Jan 17, 2022 10:01 AM: Starting - ExecuteScalarInt64: INSERT INTO "Remotevolume" ("OperationID", "Name", "Type", "State", "Size", "VerificationCount", "DeleteGraceTime") VALUES (25, "duplicati-be5e0e8f2b4794da291307f5321aa42c9.dblock.zip.aes", "Blocks", "Temporary", -1, 0, 0); SELECT last_insert_rowid();
Jan 17, 2022 10:01 AM: Compacting because there are 21.03 GB in small volumes and the volume size is 20.00 GB
Jan 17, 2022 10:01 AM: Found 11 volume(s) with a total of 5.84% wasted space (119.35 GB of 1.99 TB)
Jan 17, 2022 10:01 AM: Found 7 small volumes(s) with a total size of 21.03 GB
Jan 17, 2022 10:01 AM: Found 0 fully deletable volume(s)
Jan 17, 2022 10:01 AM: ExecuteNonQuery: DROP TABLE IF EXISTS "UsageReport-FB409188D4B3624386D472EACA1C6488" took 0:00:00:00.000
Jan 17, 2022 10:01 AM: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "UsageReport-FB409188D4B3624386D472EACA1C6488"
Jan 17, 2022 10:01 AM: ExecuteReader: SELECT "A"."Name", "B"."ActiveSize", "B"."InactiveSize", "A"."Size" FROM "Remotevolume" A, "UsageReport-FB409188D4B3624386D472EACA1C6488" B WHERE "A"."ID" = "B"."VolumeID" ORDER BY "B"."Sorttime" ASC took 0:00:00:00.000
Jan 17, 2022 10:01 AM: Starting - ExecuteReader: SELECT "A"."Name", "B"."ActiveSize", "B"."InactiveSize", "A"."Size" FROM "Remotevolume" A, "UsageReport-FB409188D4B3624386D472EACA1C6488" B WHERE "A"."ID" = "B"."VolumeID" ORDER BY "B"."Sorttime" ASC
Jan 17, 2022 10:01 AM: ExecuteNonQuery: CREATE TEMPORARY TABLE "UsageReport-FB409188D4B3624386D472EACA1C6488" AS SELECT "VolumeID" AS "VolumeID", SUM("ActiveSize") AS "ActiveSize", SUM("InactiveSize") AS "InactiveSize", MAX("Sorttime") AS "Sorttime" FROM (SELECT "A"."ActiveSize" AS "ActiveSize", 0 AS "InactiveSize", "A"."VolumeID" AS "VolumeID", CASE WHEN "B"."Sorttime" IS NULL THEN 0 ELSE "B"."Sorttime" END AS "Sorttime" FROM (SELECT SUM("Block"."Size") AS "ActiveSize", "Block"."VolumeID" AS "VolumeID" FROM "Block", "Remotevolume" WHERE "Block"."VolumeID" = "Remotevolume"."ID" AND "Block"."ID" NOT IN (SELECT "Block"."ID" FROM "Block","DeletedBlock" WHERE "Block"."Hash" = "DeletedBlock"."Hash" AND "Block"."Size" = "DeletedBlock"."Size" AND "Block"."VolumeID" = "DeletedBlock"."VolumeID") GROUP BY "Block"."VolumeID" ) A LEFT OUTER JOIN (SELECT "VolumeID" AS "VolumeID", MIN("Sorttime") AS "Sorttime" FROM (SELECT "Block"."VolumeID" AS "VolumeID", "Fileset"."Timestamp" AS "Sorttime" FROM "Fileset", "FilesetEntry", "FileLookup", "BlocksetEntry", "Block" WHERE "FilesetEntry"."FileID" = "FileLookup"."ID" AND "FileLookup"."BlocksetID" = "BlocksetEntry"."BlocksetID" AND "BlocksetEntry"."BlockID" = "Block"."ID" AND "Fileset"."ID" = "FilesetEntry"."FilesetID" UNION SELECT "Block"."VolumeID" AS "VolumeID", "Fileset"."Timestamp" AS "Sorttime" FROM "Fileset", "FilesetEntry", "FileLookup", "BlocksetEntry", "Block", "Metadataset" WHERE "FilesetEntry"."FileID" = "FileLookup"."ID" AND "FileLookup"."MetadataID" = "Metadataset"."ID" AND "Metadataset"."BlocksetID" = "BlocksetEntry"."BlocksetID" AND "BlocksetEntry"."BlockID" = "Block"."ID" AND "Fileset"."ID" = "FilesetEntry"."FilesetID" ) GROUP BY "VolumeID" ) B ON "B"."VolumeID" = "A"."VolumeID" UNION SELECT 0 AS "ActiveSize", SUM("Size") AS "InactiveSize", "VolumeID" AS "VolumeID", 0 AS "SortScantime" FROM "DeletedBlock" GROUP BY "VolumeID" UNION SELECT 0 AS "ActiveSize", 0 AS "InactiveSize", "Remotevolume"."ID" AS "VolumeID", 0 AS "SortScantime" FROM "Remotevolume" WHERE "Remotevolume"."Type" = "Blocks" AND "Remotevolume"."State" IN ("Uploaded", "Verified") AND "Remotevolume"."ID" NOT IN (SELECT "VolumeID" FROM "Block") ) GROUP BY "VolumeID" took 0:00:04:02.677
Jan 17, 2022 9:57 AM: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "UsageReport-FB409188D4B3624386D472EACA1C6488" AS SELECT "VolumeID" AS "VolumeID", SUM("ActiveSize") AS "ActiveSize", SUM("InactiveSize") AS "InactiveSize", MAX("Sorttime") AS "Sorttime" FROM (SELECT "A"."ActiveSize" AS "ActiveSize", 0 AS "InactiveSize", "A"."VolumeID" AS "VolumeID", CASE WHEN "B"."Sorttime" IS NULL THEN 0 ELSE "B"."Sorttime" END AS "Sorttime" FROM (SELECT SUM("Block"."Size") AS "ActiveSize", "Block"."VolumeID" AS "VolumeID" FROM "Block", "Remotevolume" WHERE "Block"."VolumeID" = "Remotevolume"."ID" AND "Block"."ID" NOT IN (SELECT "Block"."ID" FROM "Block","DeletedBlock" WHERE "Block"."Hash" = "DeletedBlock"."Hash" AND "Block"."Size" = "DeletedBlock"."Size" AND "Block"."VolumeID" = "DeletedBlock"."VolumeID") GROUP BY "Block"."VolumeID" ) A LEFT OUTER JOIN (SELECT "VolumeID" AS "VolumeID", MIN("Sorttime") AS "Sorttime" FROM (SELECT "Block"."VolumeID" AS "VolumeID", "Fileset"."Timestamp" AS "Sorttime" FROM "Fileset", "FilesetEntry", "FileLookup", "BlocksetEntry", "Block" WHERE "FilesetEntry"."FileID" = "FileLookup"."ID" AND "FileLookup"."BlocksetID" = "BlocksetEntry"."BlocksetID" AND "BlocksetEntry"."BlockID" = "Block"."ID" AND "Fileset"."ID" = "FilesetEntry"."FilesetID" UNION SELECT "Block"."VolumeID" AS "VolumeID", "Fileset"."Timestamp" AS "Sorttime" FROM "Fileset", "FilesetEntry", "FileLookup", "BlocksetEntry", "Block", "Metadataset" WHERE "FilesetEntry"."FileID" = "FileLookup"."ID" AND "FileLookup"."MetadataID" = "Metadataset"."ID" AND "Metadataset"."BlocksetID" = "BlocksetEntry"."BlocksetID" AND "BlocksetEntry"."BlockID" = "Block"."ID" AND "Fileset"."ID" = "FilesetEntry"."FilesetID" ) GROUP BY "VolumeID" ) B ON "B"."VolumeID" = "A"."VolumeID" UNION SELECT 0 AS "ActiveSize", SUM("Size") AS "InactiveSize", "VolumeID" AS "VolumeID", 0 AS "SortScantime" FROM "DeletedBlock" GROUP BY "VolumeID" UNION SELECT 0 AS "ActiveSize", 0 AS "InactiveSize", "Remotevolume"."ID" AS "VolumeID", 0 AS "SortScantime" FROM "Remotevolume" WHERE "Remotevolume"."Type" = "Blocks" AND "Remotevolume"."State" IN ("Uploaded", "Verified") AND "Remotevolume"."ID" NOT IN (SELECT "VolumeID" FROM "Block") ) GROUP BY "VolumeID"
Jan 17, 2022 9:57 AM: Deleted 1 remote fileset(s)
Jan 17, 2022 9:57 AM: ExecuteNonQuery: DROP TABLE IF EXISTS "DelMetadataFilesetIds-791091F06F8C634B9ABC10431FE606AF" took 0:00:00:00.000
Jan 17, 2022 9:57 AM: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "DelMetadataFilesetIds-791091F06F8C634B9ABC10431FE606AF"
Jan 17, 2022 9:57 AM: ExecuteNonQuery: DROP TABLE IF EXISTS "DelVolSetIds-791091F06F8C634B9ABC10431FE606AF" took 0:00:00:00.000
Jan 17, 2022 9:57 AM: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "DelVolSetIds-791091F06F8C634B9ABC10431FE606AF"
Jan 17, 2022 9:57 AM: ExecuteNonQuery: DROP TABLE IF EXISTS "DelBlockSetIds-791091F06F8C634B9ABC10431FE606AF" took 0:00:00:00.000
Jan 17, 2022 9:57 AM: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "DelBlockSetIds-791091F06F8C634B9ABC10431FE606AF"
Jan 17, 2022 9:57 AM: ExecuteNonQuery: DELETE FROM "DelVolSetIds-791091F06F8C634B9ABC10431FE606AF" took 0:00:00:00.000
Jan 17, 2022 9:57 AM: Starting - ExecuteNonQuery: DELETE FROM "DelVolSetIds-791091F06F8C634B9ABC10431FE606AF"
Jan 17, 2022 9:57 AM: ExecuteNonQuery: DELETE FROM "DelBlockSetIds-791091F06F8C634B9ABC10431FE606AF" took 0:00:00:00.000
Jan 17, 2022 9:57 AM: Starting - ExecuteNonQuery: DELETE FROM "DelBlockSetIds-791091F06F8C634B9ABC10431FE606AF"

After letting a few more backups run after repairing the imported configs and DB files i was able to get a restore running on the main Duplicati setup however the occasional DB locked error pops up it does appear to be running.

Select a log level and see messages as they happen:

* Jan 20, 2022 3:16 AM: Creating missing folder /home/01062022/var/lib/libvirt/images for file /home/01062022/var/lib/libvirt/images/masternk.qcow2

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityUnprocessableMedia.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityStationRequest.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityStationPlaylistFolder.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityStationMediaCustomField.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntitySongHistory.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntitySftpUser.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityRole.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityPodcastMedia.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityPodcastEpisode.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityPodcastCategory.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityPodcast.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityListener.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityCustomField.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityAuditLog.php

* Jan 20, 2022 3:16 AM: Target file is patched with some local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/proxies/__CG__AppEntityAnalytics.php

* Jan 20, 2022 3:16 AM: Target file is not patched any local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/nowplaying/z_radio.json

* Jan 20, 2022 3:16 AM: Local source file not found: /var/lib/docker/volumes/azuracast_tmp_data/_data/nowplaying/z_radio.json

* Jan 20, 2022 3:16 AM: Target file is not patched any local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/nowplaying/trainwreckstv_scuffedpod.json

* Jan 20, 2022 3:16 AM: Local source file not found: /var/lib/docker/volumes/azuracast_tmp_data/_data/nowplaying/trainwreckstv_scuffedpod.json

* Jan 20, 2022 3:16 AM: Target file is not patched any local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/app-2022-01-06.log

* Jan 20, 2022 3:16 AM: Local source file not found: /var/lib/docker/volumes/azuracast_tmp_data/_data/app-2022-01-06.log

* Jan 20, 2022 3:16 AM: Target file is not patched any local data: /home/01062022/var/lib/docker/volumes/azuracast_tmp_data/_data/app-2022-01-05.log

* Jan 20, 2022 3:16 AM: Local source file not found: /var/lib/docker/volumes/azuracast_tmp_data/_data/app-2022-01-05.log

* Jan 20, 2022 3:16 AM: Target file is not patched any local data: /home/01062022/var/lib/docker/volumes/azuracast_station_data/_data/z_radio/config/nowplaying.txt

* Jan 20, 2022 3:16 AM: Local source file not found: /var/lib/docker/volumes/azuracast_station_data/_data/z_radio/config/nowplaying.txt

* Jan 20, 2022 3:16 AM: Target file is not patched any local data: /home/01062022/var/lib/docker/volumes/azuracast_station_data/_data/z_radio/config/liquidsoap.log

* Jan 20, 2022 3:16 AM: Local source file not found: /var/lib/docker/volumes/azuracast_station_data/_data/z_radio/config/liquidsoap.log

* Jan 20, 2022 3:16 AM: Target file is not patched any local data: /home/01062022/var/lib/docker/volumes/azuracast_station_data/_data/z_radio/config/icecast_access.log.20220104_234417

* Jan 20, 2022 3:16 AM: Local source file not found: /var/lib/docker/volumes/azuracast_station_data/_data/z_radio/config/icecast_access.log.20220104_234417
1 Like