There seems to be a performance issue when running my jobs after migration from 2.0.8.1 to 2.1.0.2 - my two backup jobs appear to take up to 4x more time than before:
The mean duration with 2.0.8.1 was 30-35 min (5-10 min if run a second time directly afterwards), with 2.1.0.2 the duration is about 120 min (~30-90 min the second time).
The backup destination path for each job is on a separate, physically connected USB drive. Both jobs have about 10 backup versions on the remote. The behavior of increased job time applies to both jobs.
When noticing the increased processing time directly after upgrading I ignored it first because I assumed that the job database (and maybe also the remote data) may have to undergo a migration procedure once that takes some additional time. Now that the jobs have run some more times with comparable processing times, I conclude that the increase in processing time must come from somewhere else.
Example for job run on old duplicati (last job run on version 2.0.8.1):
Begin 2024-12-16 11:49:54
End 2024-12-16 12:23:24
Duration 00:33:30
{
"DeletedFiles": 11904,
"DeletedFolders": 366,
"ModifiedFiles": 3524,
"ExaminedFiles": 2249913,
"OpenedFiles": 20690,
"AddedFiles": 17166,
"SizeOfModifiedFiles": 32269351944,
"SizeOfAddedFiles": 14798875303,
"SizeOfExaminedFiles": 305694366848,
"SizeOfOpenedFiles": 47083894936,
"NotProcessedFiles": 0,
"AddedFolders": 629,
"TooLargeFiles": 0,
"FilesWithError": 0,
"ModifiedFolders": 0,
"ModifiedSymlinks": 0,
"AddedSymlinks": 0,
"DeletedSymlinks": 0,
"PartialBackup": false,
"Dryrun": false,
"MainOperation": "Backup",
"CompactResults": {
"DeletedFileCount": 4,
"DownloadedFileCount": 0,
"UploadedFileCount": 0,
"DeletedFileSize": 209350164,
"DownloadedFileSize": 0,
"UploadedFileSize": 0,
"Dryrun": false,
"VacuumResults": null,
"MainOperation": "Compact",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "2024-12-16T11:23:20.4952309Z",
"BeginTime": "2024-12-16T11:22:11.9930518Z",
"Duration": "00:01:08.5021791",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 51,
"BytesUploaded": 2231243765,
"BytesDownloaded": 279669335,
"FilesUploaded": 41,
"FilesDownloaded": 3,
"FilesDeleted": 5,
"FoldersCreated": 0,
"RetryAttempts": 0,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 3505,
"KnownFileSize": 183833651725,
"LastBackupDate": "2024-12-16T11:49:54+01:00",
"BackupListCount": 10,
"TotalQuotaSpace": 536870645760,
"FreeQuotaSpace": 262983827456,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2024-12-16T10:49:54.0957413Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
},
"VacuumResults": null,
"DeleteResults": {
"DeletedSetsActualLength": 1,
"DeletedSets": [
{
"Item1": 3,
"Item2": "2024-11-05T16:32:37+01:00"
}
],
"Dryrun": false,
"MainOperation": "Delete",
"CompactResults": {
"DeletedFileCount": 4,
"DownloadedFileCount": 0,
"UploadedFileCount": 0,
"DeletedFileSize": 209350164,
"DownloadedFileSize": 0,
"UploadedFileSize": 0,
"Dryrun": false,
"VacuumResults": null,
"MainOperation": "Compact",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "2024-12-16T11:23:20.4952309Z",
"BeginTime": "2024-12-16T11:22:11.9930518Z",
"Duration": "00:01:08.5021791",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 51,
"BytesUploaded": 2231243765,
"BytesDownloaded": 279669335,
"FilesUploaded": 41,
"FilesDownloaded": 3,
"FilesDeleted": 5,
"FoldersCreated": 0,
"RetryAttempts": 0,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 3505,
"KnownFileSize": 183833651725,
"LastBackupDate": "2024-12-16T11:49:54+01:00",
"BackupListCount": 10,
"TotalQuotaSpace": 536870645760,
"FreeQuotaSpace": 262983827456,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2024-12-16T10:49:54.0957413Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
},
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "2024-12-16T11:23:20.4952309Z",
"BeginTime": "2024-12-16T11:21:24.9352481Z",
"Duration": "00:01:55.5599828",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 51,
"BytesUploaded": 2231243765,
"BytesDownloaded": 279669335,
"FilesUploaded": 41,
"FilesDownloaded": 3,
"FilesDeleted": 5,
"FoldersCreated": 0,
"RetryAttempts": 0,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 3505,
"KnownFileSize": 183833651725,
"LastBackupDate": "2024-12-16T11:49:54+01:00",
"BackupListCount": 10,
"TotalQuotaSpace": 536870645760,
"FreeQuotaSpace": 262983827456,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2024-12-16T10:49:54.0957413Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
},
"RepairResults": null,
"TestResults": {
"MainOperation": "Test",
"VerificationsActualLength": 3,
"Verifications": [
{
"Key": "duplicati-20241216T104954Z.dlist.zip.aes",
"Value": []
},
{
"Key": "duplicati-i47ed0a26b763488bb49284d22b527f35.dindex.zip.aes",
"Value": []
},
{
"Key": "duplicati-b9bd605a30eae4dbeb2d508fb2508bc70.dblock.zip.aes",
"Value": []
}
],
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "2024-12-16T11:23:24.559538Z",
"BeginTime": "2024-12-16T11:23:23.1908276Z",
"Duration": "00:00:01.3687104",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 51,
"BytesUploaded": 2231243765,
"BytesDownloaded": 279669335,
"FilesUploaded": 41,
"FilesDownloaded": 3,
"FilesDeleted": 5,
"FoldersCreated": 0,
"RetryAttempts": 0,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 3505,
"KnownFileSize": 183833651725,
"LastBackupDate": "2024-12-16T11:49:54+01:00",
"BackupListCount": 10,
"TotalQuotaSpace": 536870645760,
"FreeQuotaSpace": 262983827456,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2024-12-16T10:49:54.0957413Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
},
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "2024-12-16T11:23:24.5785707Z",
"BeginTime": "2024-12-16T10:49:54.0957413Z",
"Duration": "00:33:30.4828294",
"MessagesActualLength": 116,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": [
"2024-12-16 11:49:54 +01 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started",
"2024-12-16 11:53:02 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: ()",
"2024-12-16 11:53:06 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (3.39 KB)",
"2024-12-16 11:53:06 +01 - [Information-Duplicati.Library.Main.Operation.BackupHandler-SkipUsnForVolume]: Performing full scan for volume \"C:\\\": USN journal entries were purged since last scan",
"2024-12-16 12:15:48 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b065cec82db3a47d384a7418306adbd72.dblock.zip.aes (99.78 MB)",
"2024-12-16 12:15:48 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-bfd37d43d11c241eca04d2830e7e4e292.dblock.zip.aes (99.79 MB)",
"2024-12-16 12:15:48 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-bec570a7ddea248338a14bb800aa8e097.dblock.zip.aes (99.79 MB)",
"2024-12-16 12:15:48 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-bdf3105f353d346878b743c2438b7f44d.dblock.zip.aes (99.79 MB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-bdf3105f353d346878b743c2438b7f44d.dblock.zip.aes (99.79 MB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b065cec82db3a47d384a7418306adbd72.dblock.zip.aes (99.78 MB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-bec570a7ddea248338a14bb800aa8e097.dblock.zip.aes (99.79 MB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-bfd37d43d11c241eca04d2830e7e4e292.dblock.zip.aes (99.79 MB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-if543d17afea14a53abcca27977e81269.dindex.zip.aes (205.76 KB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-i9a87da2802c345fbafdc3072df2c06bc.dindex.zip.aes (202.22 KB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-i4f95b37dba184cae96283778d38db341.dindex.zip.aes (208.22 KB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-i6d345473f0a74320b2e8cd192fa8ab31.dindex.zip.aes (194.00 KB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-if543d17afea14a53abcca27977e81269.dindex.zip.aes (205.76 KB)",
"2024-12-16 12:15:56 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-bf3a046a25ef349c2a718e8a9b1aa8e42.dblock.zip.aes (99.82 MB)",
"2024-12-16 12:15:57 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-i4f95b37dba184cae96283778d38db341.dindex.zip.aes (208.22 KB)",
"2024-12-16 12:15:57 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b64a1261f418b4dfaa3e72f5147aa8264.dblock.zip.aes (99.77 MB)"
],
"Warnings": [],
"Errors": [],
"BackendStatistics": {
"RemoteCalls": 51,
"BytesUploaded": 2231243765,
"BytesDownloaded": 279669335,
"FilesUploaded": 41,
"FilesDownloaded": 3,
"FilesDeleted": 5,
"FoldersCreated": 0,
"RetryAttempts": 0,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 3505,
"KnownFileSize": 183833651725,
"LastBackupDate": "2024-12-16T11:49:54+01:00",
"BackupListCount": 10,
"TotalQuotaSpace": 536870645760,
"FreeQuotaSpace": 262983827456,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.0.8.1 (2.0.8.1_beta_2024-05-07)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2024-12-16T10:49:54.0957413Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
}
Example for job run on duplicati 2.1.0.2 (second job run on that release):
Begin 2025-01-14 17:32:32
End 2025-01-14 19:03:26
Duration 01:30:55
{
"DeletedFiles": 3933,
"DeletedFolders": 51,
"ModifiedFiles": 22,
"ExaminedFiles": 2271711,
"OpenedFiles": 45685,
"AddedFiles": 45663,
"SizeOfModifiedFiles": 2606344031,
"SizeOfAddedFiles": 2968747713,
"SizeOfExaminedFiles": 323371769812,
"SizeOfOpenedFiles": 5575100124,
"NotProcessedFiles": 0,
"AddedFolders": 3395,
"TooLargeFiles": 0,
"FilesWithError": 0,
"ModifiedFolders": 0,
"ModifiedSymlinks": 0,
"AddedSymlinks": 0,
"DeletedSymlinks": 0,
"PartialBackup": false,
"Dryrun": false,
"MainOperation": "Backup",
"CompactResults": null,
"VacuumResults": null,
"DeleteResults": {
"DeletedSetsActualLength": 0,
"DeletedSets": [],
"Dryrun": false,
"MainOperation": "Delete",
"CompactResults": null,
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.1.0.2 (2.1.0.2_beta_2024-11-29)",
"EndTime": "2025-01-14T18:03:17.2616319Z",
"BeginTime": "2025-01-14T18:01:46.1459921Z",
"Duration": "00:01:31.1156398",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 21,
"BytesUploaded": 477998811,
"BytesDownloaded": 1002956204,
"FilesUploaded": 7,
"FilesDownloaded": 12,
"FilesDeleted": 0,
"FoldersCreated": 0,
"RetryAttempts": 0,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 3613,
"KnownFileSize": 189478867337,
"LastBackupDate": "2025-01-14T17:32:32+01:00",
"BackupListCount": 10,
"TotalQuotaSpace": 536870645760,
"FreeQuotaSpace": 257338368000,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.1.0.2 (2.1.0.2_beta_2024-11-29)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2025-01-14T16:32:32.1808584Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
},
"RepairResults": null,
"TestResults": {
"MainOperation": "Test",
"VerificationsActualLength": 12,
"Verifications": [
{
"Key": "duplicati-20250114T163232Z.dlist.zip.aes",
"Value": []
},
{
"Key": "duplicati-20241007T141920Z.dlist.zip.aes",
"Value": []
},
{
"Key": "duplicati-20240425T103610Z.dlist.zip.aes",
"Value": []
},
{
"Key": "duplicati-20240603T100831Z.dlist.zip.aes",
"Value": []
},
{
"Key": "duplicati-i3f0213ca535d4291bd4e03ecee3f4bc1.dindex.zip.aes",
"Value": []
},
{
"Key": "duplicati-i414d196f36fd43be8154bf8b09809135.dindex.zip.aes",
"Value": []
},
{
"Key": "duplicati-ib973be69caa147e2b63d6022c865bc07.dindex.zip.aes",
"Value": []
},
{
"Key": "duplicati-i8d0310505ef5425f85a7bcc8d2b5d7cf.dindex.zip.aes",
"Value": []
},
{
"Key": "duplicati-bb8a709606bc042d9abb081b4f75fce57.dblock.zip.aes",
"Value": []
},
{
"Key": "duplicati-bbfe54441a1d34fca999398b110d55229.dblock.zip.aes",
"Value": []
},
{
"Key": "duplicati-b05031f627afa4c129873066dfc87b920.dblock.zip.aes",
"Value": []
},
{
"Key": "duplicati-bc99e907c69f84df68f12c810f1dadefb.dblock.zip.aes",
"Value": []
}
],
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.1.0.2 (2.1.0.2_beta_2024-11-29)",
"EndTime": "2025-01-14T18:03:26.6901016Z",
"BeginTime": "2025-01-14T18:03:17.4759097Z",
"Duration": "00:00:09.2141919",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 21,
"BytesUploaded": 477998811,
"BytesDownloaded": 1002956204,
"FilesUploaded": 7,
"FilesDownloaded": 12,
"FilesDeleted": 0,
"FoldersCreated": 0,
"RetryAttempts": 0,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 3613,
"KnownFileSize": 189478867337,
"LastBackupDate": "2025-01-14T17:32:32+01:00",
"BackupListCount": 10,
"TotalQuotaSpace": 536870645760,
"FreeQuotaSpace": 257338368000,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.1.0.2 (2.1.0.2_beta_2024-11-29)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2025-01-14T16:32:32.1808584Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
},
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.1.0.2 (2.1.0.2_beta_2024-11-29)",
"EndTime": "2025-01-14T18:03:26.7467824Z",
"BeginTime": "2025-01-14T16:32:32.1808403Z",
"Duration": "01:30:54.5659421",
"MessagesActualLength": 51,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": [
"2025-01-14 17:32:32 +01 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started",
"2025-01-14 17:39:11 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: ()",
"2025-01-14 17:39:11 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (3.521 KB)",
"2025-01-14 17:39:12 +01 - [Information-Duplicati.Library.Main.Operation.BackupHandler-SkipUsnForVolume]: Performing full scan for volume \"C:\\\": USN journal entries were purged since last scan",
"2025-01-14 18:52:42 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-bc60c7f2bc8304e95a68024576103397d.dblock.zip.aes (100.240 MB)",
"2025-01-14 18:52:43 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-bc60c7f2bc8304e95a68024576103397d.dblock.zip.aes (100.240 MB)",
"2025-01-14 18:52:43 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-i8f917ad352cb4789915b6de9e0588ed6.dindex.zip.aes (205.872 KB)",
"2025-01-14 18:52:43 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-i8f917ad352cb4789915b6de9e0588ed6.dindex.zip.aes (205.872 KB)",
"2025-01-14 18:53:17 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b92c9a69056694435ae2e1a60b965d4ec.dblock.zip.aes (100.147 MB)",
"2025-01-14 18:53:27 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b92c9a69056694435ae2e1a60b965d4ec.dblock.zip.aes (100.147 MB)",
"2025-01-14 18:53:27 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-i1e218601a2784240878c72c8f64f419c.dindex.zip.aes (153.216 KB)",
"2025-01-14 18:53:27 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-i1e218601a2784240878c72c8f64f419c.dindex.zip.aes (153.216 KB)",
"2025-01-14 18:54:33 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b76f8fe448e51425f908686efaaf3298a.dblock.zip.aes (86.376 MB)",
"2025-01-14 18:55:17 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b76f8fe448e51425f908686efaaf3298a.dblock.zip.aes (86.376 MB)",
"2025-01-14 19:00:32 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-i88cfe82eeda94b9daefc4b9b48ddb27f.dindex.zip.aes (332.372 KB)",
"2025-01-14 19:00:32 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-i88cfe82eeda94b9daefc4b9b48ddb27f.dindex.zip.aes (332.372 KB)",
"2025-01-14 19:01:45 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-20250114T163232Z.dlist.zip.aes (168.417 MB)",
"2025-01-14 19:01:46 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-20250114T163232Z.dlist.zip.aes (168.417 MB)",
"2025-01-14 19:01:46 +01 - [Information-Duplicati.Library.Main.Operation.DeleteHandler:RetentionPolicy-StartCheck]: Start checking if backups can be removed",
"2025-01-14 19:01:46 +01 - [Information-Duplicati.Library.Main.Operation.DeleteHandler:RetentionPolicy-FramesAndIntervals]: Time frames and intervals pairs: 7.00:00:00 / 1.00:00:00, 28.00:00:00 / 7.00:00:00, 365.00:00:00 / 31.00:00:00"
],
"Warnings": [],
"Errors": [],
"BackendStatistics": {
"RemoteCalls": 21,
"BytesUploaded": 477998811,
"BytesDownloaded": 1002956204,
"FilesUploaded": 7,
"FilesDownloaded": 12,
"FilesDeleted": 0,
"FoldersCreated": 0,
"RetryAttempts": 0,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 3613,
"KnownFileSize": 189478867337,
"LastBackupDate": "2025-01-14T17:32:32+01:00",
"BackupListCount": 10,
"TotalQuotaSpace": 536870645760,
"FreeQuotaSpace": 257338368000,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Interrupted": false,
"Version": "2.1.0.2 (2.1.0.2_beta_2024-11-29)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2025-01-14T16:32:32.1808584Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
}
Note that the second example is from the second job run of that day. The first job run took considerably longer but may be non-representative because I moved some folders during the run, leading to about 200 warnings blowing the log up.
I do also have verbose logs available if useful but due to the log level each job run results in millions of entries and I would need guidance on analyzing them myself or on how to reliably remove sensitive data from them before sharing.
My questions regarding the 2.1.0.2 beta release are:
- Are there any changes in the backup procedure known to potentially increase the processing time for my configuration? (I know this question is quite generic)
- Am I the only one experiencing the increase in processing time after updating?
- Is there a chance that the performance will go back to nomal after migrating to beta 2.1.0.3?
If you are confident the issue may disappear when switching to the most recent beta, I don’t want anybody to spend their precious time in investigating legacy issues.
If relevant information are missing in my post, feel free to request them.
Thanks for your help!