Hello,
I noticed that my backup which ran for quite some time failed (silently) for some time. I use backblaze for remote. In the beginning, it said I have to repair the database. That didn’t help, so I recreated the database which took several days. This also didn’t help. So I updated from v2.0.4.5 to 2.0.4.15. Now it looks like I get different erros, but still it fails.
I really don’t understand the errors and have no clue where to start. Somebody has some insight and can identify the problem?
Thanks in advance.
This is the log:
{
"DeletedFiles": 78702,
"DeletedFolders": 9100,
"ModifiedFiles": 36786,
"ExaminedFiles": 1555297,
"OpenedFiles": 125573,
"AddedFiles": 88786,
"SizeOfModifiedFiles": 17452218326,
"SizeOfAddedFiles": 21314302149,
"SizeOfExaminedFiles": 481151964725,
"SizeOfOpenedFiles": 41672455927,
"NotProcessedFiles": 0,
"AddedFolders": 9447,
"TooLargeFiles": 0,
"FilesWithError": 0,
"ModifiedFolders": 0,
"ModifiedSymlinks": 0,
"AddedSymlinks": 88,
"DeletedSymlinks": 76,
"PartialBackup": false,
"Dryrun": false,
"MainOperation": "Backup",
"CompactResults": {
"DeletedFileCount": 286,
"DownloadedFileCount": 0,
"UploadedFileCount": 0,
"DeletedFileSize": 7499883414,
"DownloadedFileSize": 0,
"UploadedFileSize": 0,
"Dryrun": false,
"MainOperation": "Compact",
"ParsedResult": "Success",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "2019-02-19T11:14:42.053785Z",
"BeginTime": "2019-02-19T11:08:21.507454Z",
"Duration": "00:06:20.5463310",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 421,
"BytesUploaded": 3310346745,
"BytesDownloaded": 123079047,
"FilesUploaded": 125,
"FilesDownloaded": 3,
"FilesDeleted": 290,
"FoldersCreated": 0,
"RetryAttempts": 1,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 16915,
"KnownFileSize": 442980411015,
"LastBackupDate": "2019-02-19T09:43:23+01:00",
"BackupListCount": 9,
"TotalQuotaSpace": 0,
"FreeQuotaSpace": 0,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2019-02-19T08:40:45.354427Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
},
"DeleteResults": {
"DeletedSetsActualLength": 3,
"DeletedSets": [
{
"Item1": 1,
"Item2": "2019-01-16T15:00:00+01:00"
},
{
"Item1": 2,
"Item2": "2019-01-14T10:14:49+01:00"
},
{
"Item1": 3,
"Item2": "2019-01-09T17:58:35+01:00"
}
],
"Dryrun": false,
"MainOperation": "Delete",
"CompactResults": {
"DeletedFileCount": 286,
"DownloadedFileCount": 0,
"UploadedFileCount": 0,
"DeletedFileSize": 7499883414,
"DownloadedFileSize": 0,
"UploadedFileSize": 0,
"Dryrun": false,
"MainOperation": "Compact",
"ParsedResult": "Success",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "2019-02-19T11:14:42.053785Z",
"BeginTime": "2019-02-19T11:08:21.507454Z",
"Duration": "00:06:20.5463310",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 421,
"BytesUploaded": 3310346745,
"BytesDownloaded": 123079047,
"FilesUploaded": 125,
"FilesDownloaded": 3,
"FilesDeleted": 290,
"FoldersCreated": 0,
"RetryAttempts": 1,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 16915,
"KnownFileSize": 442980411015,
"LastBackupDate": "2019-02-19T09:43:23+01:00",
"BackupListCount": 9,
"TotalQuotaSpace": 0,
"FreeQuotaSpace": 0,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2019-02-19T08:40:45.354427Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
},
"ParsedResult": "Success",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "2019-02-19T11:14:42.055126Z",
"BeginTime": "2019-02-19T10:57:21.877966Z",
"Duration": "00:17:20.1771600",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 421,
"BytesUploaded": 3310346745,
"BytesDownloaded": 123079047,
"FilesUploaded": 125,
"FilesDownloaded": 3,
"FilesDeleted": 290,
"FoldersCreated": 0,
"RetryAttempts": 1,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 16915,
"KnownFileSize": 442980411015,
"LastBackupDate": "2019-02-19T09:43:23+01:00",
"BackupListCount": 9,
"TotalQuotaSpace": 0,
"FreeQuotaSpace": 0,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2019-02-19T08:40:45.354427Z",
"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-20181015T140000Z.dlist.zip.aes",
"Value": []
},
{
"Key": "duplicati-ib6719f3c73614fa8a9a5b37c6b186305.dindex.zip.aes",
"Value": []
},
{
"Key": "duplicati-bcd91696fd3014fbaaa9744159cd8acd5.dblock.zip.aes",
"Value": []
}
],
"ParsedResult": "Success",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "2019-02-19T11:16:29.129547Z",
"BeginTime": "2019-02-19T11:15:28.703919Z",
"Duration": "00:01:00.4256280",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null,
"BackendStatistics": {
"RemoteCalls": 421,
"BytesUploaded": 3310346745,
"BytesDownloaded": 123079047,
"FilesUploaded": 125,
"FilesDownloaded": 3,
"FilesDeleted": 290,
"FoldersCreated": 0,
"RetryAttempts": 1,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 16915,
"KnownFileSize": 442980411015,
"LastBackupDate": "2019-02-19T09:43:23+01:00",
"BackupListCount": 9,
"TotalQuotaSpace": 0,
"FreeQuotaSpace": 0,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2019-02-19T08:40:45.354427Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
},
"ParsedResult": "Error",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "2019-02-19T11:16:29.148196Z",
"BeginTime": "2019-02-19T08:40:45.353884Z",
"Duration": "02:35:43.7943120",
"MessagesActualLength": 873,
"WarningsActualLength": 1590,
"ErrorsActualLength": 8,
"Messages": [
"2019-02-19 09:40:45 +01 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started",
"2019-02-19 09:53:18 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: ()",
"2019-02-19 09:54:05 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (16.68 KB)",
"2019-02-19 09:54:05 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-RemoteUnwantedMissingFile]: removing file listed as Temporary: duplicati-20190218T140000Z.dlist.zip.aes",
"2019-02-19 09:54:05 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-RemoteUnwantedMissingFile]: removing file listed as Deleting: duplicati-b51a6e09a185d4e1bb4fe5ce22fa92a2c.dblock.zip.aes",
"2019-02-19 09:54:05 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-RemoteUnwantedMissingFile]: removing file listed as Deleting: duplicati-b0f8570b6d07942d99f7a73104dca1a40.dblock.zip.aes",
"2019-02-19 09:54:05 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-RemoteUnwantedMissingFile]: removing file listed as Deleting: duplicati-i58322b36b83b44969975fc726c33242c.dindex.zip.aes",
"2019-02-19 09:54:05 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-RemoteUnwantedMissingFile]: removing file listed as Deleting: duplicati-bfe2bef84b0ff47f282d5e9a1b5d34f7e.dblock.zip.aes",
"2019-02-19 09:54:05 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-SchedulingMissingFileForDelete]: scheduling missing file for deletion, currently listed as Uploading: duplicati-bf2bfcb7489cb4c11bac8f3b89e25bc9b.dblock.zip.aes",
"2019-02-19 09:54:05 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-Remove incomplete file]: removing incomplete remote file listed as Uploading: duplicati-bd3e83bfbd98041b1acb11bb2522fd386.dblock.zip.aes",
"2019-02-19 09:54:05 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Delete - Started: duplicati-bd3e83bfbd98041b1acb11bb2522fd386.dblock.zip.aes ()",
"2019-02-19 09:54:06 +01 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Delete - Completed: duplicati-bd3e83bfbd98041b1acb11bb2522fd386.dblock.zip.aes ()",
"2019-02-19 09:54:12 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-SchedulingMissingFileForDelete]: scheduling missing file for deletion, currently listed as Uploading: duplicati-b3d03bb94707647efad701dacdccd08e7.dblock.zip.aes",
"2019-02-19 09:54:12 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-SchedulingMissingFileForDelete]: scheduling missing file for deletion, currently listed as Uploading: duplicati-b9e3bcec8993c41f7aea362ad149dd514.dblock.zip.aes",
"2019-02-19 09:54:12 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-SchedulingMissingFileForDelete]: scheduling missing file for deletion, currently listed as Uploading: duplicati-b915126a46a6d44e3bcdf4361130ea3a6.dblock.zip.aes",
"2019-02-19 09:54:12 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-SchedulingMissingFileForDelete]: scheduling missing file for deletion, currently listed as Uploading: duplicati-b519d7f77fb424101a923de7eb256e9d8.dblock.zip.aes",
"2019-02-19 09:54:12 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-RemoteUnwantedMissingFile]: removing file listed as Deleting: duplicati-b4b95769b606447389dfa69822bd2db6e.dblock.zip.aes",
"2019-02-19 09:54:12 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-RemoteUnwantedMissingFile]: removing file listed as Deleting: duplicati-bfdc23b02e388431eb817d4fc2698e86d.dblock.zip.aes",
"2019-02-19 09:54:12 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-RemoteUnwantedMissingFile]: removing file listed as Deleting: duplicati-bdd9d8ecd04974d16b4561102bddb685d.dblock.zip.aes",
"2019-02-19 09:54:12 +01 - [Information-Duplicati.Library.Main.Operation.FilelistProcessor-RemoteUnwantedMissingFile]: removing file listed as Deleting: duplicati-b3ab8854ee783487988640feedf32e4b3.dblock.zip.aes"
],
"Warnings": [
"2019-02-19 09:40:45 +01 - [Warning-Duplicati.Library.Main.Controller-UnsupportedOption]: The supplied option --default-filters is not supported and will be ignored",
"2019-02-19 09:43:33 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /etc/cups/certs/",
"2019-02-19 09:43:33 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /etc/cups/certs/",
"2019-02-19 09:43:34 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/SystemMigration/History/Migration-A3D9168E-CA71-41E5-83DC-DB002956F82D/QuarantineRoot/private/var/db/datadetectors/",
"2019-02-19 09:43:34 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/SystemMigration/History/Migration-A3D9168E-CA71-41E5-83DC-DB002956F82D/QuarantineRoot/private/var/db/datadetectors/",
"2019-02-19 09:45:15 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Caches/com.apple.iconservices.store/",
"2019-02-19 09:45:15 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Caches/com.apple.iconservices.store/",
"2019-02-19 09:45:16 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Application Support/com.apple.TCC/",
"2019-02-19 09:45:16 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Application Support/com.apple.TCC/",
"2019-02-19 09:45:17 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Application Support/ApplePushService/",
"2019-02-19 09:45:17 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Application Support/ApplePushService/",
"2019-02-19 09:45:19 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Application Support/Apple/ParentalControls/Users/",
"2019-02-19 09:45:19 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Application Support/Apple/ParentalControls/Users/",
"2019-02-19 09:45:19 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Application Support/Apple/AssetCache/Data/",
"2019-02-19 09:45:19 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /Library/Application Support/Apple/AssetCache/Data/",
"2019-02-19 09:45:28 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /private/var/spool/postfix/trace/",
"2019-02-19 09:45:28 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /private/var/spool/postfix/trace/",
"2019-02-19 09:45:28 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /private/var/spool/postfix/saved/",
"2019-02-19 09:45:28 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /private/var/spool/postfix/saved/",
"2019-02-19 09:45:28 +01 - [Warning-Duplicati.Library.Main.Operation.Backup.FileEnumerationProcess-FileAccessError]: Error reported while accessing file: /private/var/spool/postfix/public/"
],
"Errors": [
"2019-02-19 11:05:58 +01 - [Error-Duplicati.Library.Main.Database.LocalBackupDatabase-CheckingErrorsForIssue1400]: Checking errors, related to #1400. Unexpected result count: 0, expected 1, hash: SSgi4imh4zc4KF8e698ustpklEtC5aiEgbvvrIjMKRU=, size: 102400, blocksetid: 1933913, ix: 16, fullhash: WOkU7TAMgBsaAIperbrnKCHKUUinwU18h6vEjPtY2/k=, fullsize: 2159272",
"2019-02-19 11:05:58 +01 - [Error-Duplicati.Library.Main.Database.LocalBackupDatabase-FoundIssue1400Error]: Found block with ID 8096892 and hash SSgi4imh4zc4KF8e698ustpklEtC5aiEgbvvrIjMKRU= and size 97752",
"2019-02-19 11:38:51 +01 - [Error-Duplicati.Library.Main.Database.LocalBackupDatabase-CheckingErrorsForIssue1400]: Checking errors, related to #1400. Unexpected result count: 0, expected 1, hash: cKoQYt9FGmUY6NG/t5q1HOimd9xVAtSXgdcg9qYisH4=, size: 102400, blocksetid: 1934753, ix: 85, fullhash: AqhfoBmzWh1IdAwtk/ZQffif+O8yAFKTfzNbgD5NxHM=, fullsize: 8864966",
"2019-02-19 11:38:51 +01 - [Error-Duplicati.Library.Main.Database.LocalBackupDatabase-FoundIssue1400Error]: Found block with ID 8115662 and hash cKoQYt9FGmUY6NG/t5q1HOimd9xVAtSXgdcg9qYisH4= and size 70176",
"2019-02-19 11:38:52 +01 - [Error-Duplicati.Library.Main.Database.LocalBackupDatabase-CheckingErrorsForIssue1400]: Checking errors, related to #1400. Unexpected result count: 0, expected 1, hash: /41VyRjdiPY/oaBv0mok5NEmXud3Ggzn7/m0H9AOQHg=, size: 102400, blocksetid: 1934754, ix: 137, fullhash: 0kjQqcz6NLcfjZiyIMzcfkkV9nsGYa+FWJaaJ1+wZm8=, fullsize: 14174358",
"2019-02-19 11:38:52 +01 - [Error-Duplicati.Library.Main.Database.LocalBackupDatabase-FoundIssue1400Error]: Found block with ID 8115804 and hash /41VyRjdiPY/oaBv0mok5NEmXud3Ggzn7/m0H9AOQHg= and size 92275",
"2019-02-19 11:38:53 +01 - [Error-Duplicati.Library.Main.Database.LocalBackupDatabase-CheckingErrorsForIssue1400]: Checking errors, related to #1400. Unexpected result count: 0, expected 1, hash: 5bSPb5hHNw5ZlP/OyVAV7lVH/7MaGFEjz0NMqsS/j8s=, size: 102400, blocksetid: 1934755, ix: 386, fullhash: lT+bKzhtczNdFiZyat2VKaQ/TRAMzZROTnmr4RcF5hM=, fullsize: 39662648",
"2019-02-19 11:38:53 +01 - [Error-Duplicati.Library.Main.Database.LocalBackupDatabase-FoundIssue1400Error]: Found block with ID 8116195 and hash 5bSPb5hHNw5ZlP/OyVAV7lVH/7MaGFEjz0NMqsS/j8s= and size 66009"
],
"BackendStatistics": {
"RemoteCalls": 421,
"BytesUploaded": 3310346745,
"BytesDownloaded": 123079047,
"FilesUploaded": 125,
"FilesDownloaded": 3,
"FilesDeleted": 290,
"FoldersCreated": 0,
"RetryAttempts": 1,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 16915,
"KnownFileSize": 442980411015,
"LastBackupDate": "2019-02-19T09:43:23+01:00",
"BackupListCount": 9,
"TotalQuotaSpace": 0,
"FreeQuotaSpace": 0,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Backup",
"ParsedResult": "Success",
"Version": "2.0.4.15 (2.0.4.15_canary_2019-02-06)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2019-02-19T08:40:45.354427Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
}