Since my last post, backups seem to have being going fine. Today I tried using the GUI under the job to restore a file (an MS Access database, *.accdb) to replace my version which seemed to have a problem. The file size is approx 6MB. Apart from using an awful lot of bandwidth it appeared to be working fine. It spent quite a few minutes saying “Downloading files”, considering that the B2 bucket has only 37 files with a total of 147MB and surely it doesn’t need them all!
The first attempt threw an error, I think it seemed to indicate a problem with the location. Log info is below (I trust I’ve pasted the right log):
* Mar 14, 2019 2:43 PM: Failed to restore 1 files, additionally the following files failed to download, which may be the cause: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes
* Mar 14, 2019 2:43 PM: Failed to restore file: "C:\Users\Jay\Desktop\Alex Database - Current as at Sept. '11.accdb". File hash is HfIJ0Hig96lTUKzV42w59PV9BEIugitLAJv+IiADbzo=, expected hash is XNIpUN5e3e9fyRrXFJ6wWtMfFo9sPVLmpu5elQRRoR8=
* Mar 14, 2019 2:43 PM: Failed to patch with remote file: "duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes", message: Failed to decrypt data (invalid passphrase?): Invalid password or corrupted data
* Mar 14, 2019 2:43 PM: Backend event: Get - Failed: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:43 PM: Backend event: Get - Started: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:43 PM: Backend event: Get - Retrying: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:42 PM: Backend event: Get - Started: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:42 PM: Backend event: Get - Retrying: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:41 PM: Backend event: Get - Started: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:41 PM: Backend event: Get - Retrying: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:41 PM: Backend event: Get - Started: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:40 PM: Backend event: Get - Retrying: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:40 PM: Backend event: Get - Started: duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes (31.23 MB)
* Mar 14, 2019 2:40 PM: 1 remote files are required to restore
So I tried it again restoring the file to my Desktop folder. But the resulting file was smaller than expected and worked no better than the current faulty version. The next attempt did not even have a resulting file to show for it.
Here is another saved log FWIW:
"RestoredFiles": 0,
"SizeOfRestoredFiles": 0,
"RestoredFolders": 0,
"RestoredSymlinks": 0,
"PatchedFiles": 0,
"DeletedFiles": 0,
"DeletedFolders": 0,
"DeletedSymlinks": 0,
"MainOperation": "Restore",
"RecreateDatabaseResults": null,
"ParsedResult": "Error",
"Version": "2.0.4.12 (2.0.4.12_canary_2019-01-16)",
"EndTime": "2019-03-14T02:27:42.2366434Z",
"BeginTime": "2019-03-14T02:16:42.7887451Z",
"Duration": "00:10:59.4478983",
"MessagesActualLength": 36,
"WarningsActualLength": 0,
"ErrorsActualLength": 4,
"Messages": [
"2019-03-14 15:16:42 +13 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Restore has started",
"2019-03-14 15:16:42 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: ()",
"2019-03-14 15:16:47 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (37 bytes)",
"2019-03-14 15:16:47 +13 - [Information-Duplicati.Library.Main.Database.LocalRestoreDatabase-SearchingBackup]: Searching backup 16 (6/02/2019 8:00:00 p.m.) ...",
"2019-03-14 15:16:47 +13 - [Information-Duplicati.Library.Main.Operation.RestoreHandler-RemoteFileCount]: 3 remote files are required to restore",
"2019-03-14 15:16:47 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:17:16 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Retrying: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:17:35 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:18:04 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Retrying: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:18:22 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:18:57 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Retrying: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:19:15 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:19:48 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Retrying: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:20:06 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:20:41 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Failed: duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes (49.91 MB)",
"2019-03-14 15:20:41 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b708a3567db7a4ee99e5bc7d0f309031f.dblock.zip.aes (45.55 MB)",
"2019-03-14 15:21:12 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Retrying: duplicati-b708a3567db7a4ee99e5bc7d0f309031f.dblock.zip.aes (45.55 MB)",
"2019-03-14 15:21:31 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b708a3567db7a4ee99e5bc7d0f309031f.dblock.zip.aes (45.55 MB)",
"2019-03-14 15:22:05 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Retrying: duplicati-b708a3567db7a4ee99e5bc7d0f309031f.dblock.zip.aes (45.55 MB)",
"2019-03-14 15:22:23 +13 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b708a3567db7a4ee99e5bc7d0f309031f.dblock.zip.aes (45.55 MB)"
],
"Warnings": [],
"Errors": [
"2019-03-14 15:20:41 +13 - [Error-Duplicati.Library.Main.Operation.RestoreHandler-PatchingFailed]: Failed to patch with remote file: \"duplicati-b9522954b15c74218bb6c899315c3b53e.dblock.zip.aes\", message: Failed to decrypt data (invalid passphrase?): Invalid password or corrupted data",
"2019-03-14 15:24:30 +13 - [Error-Duplicati.Library.Main.Operation.RestoreHandler-PatchingFailed]: Failed to patch with remote file: \"duplicati-b708a3567db7a4ee99e5bc7d0f309031f.dblock.zip.aes\", message: Failed to decrypt data (invalid passphrase?): Invalid password or corrupted data",
"2019-03-14 15:27:42 +13 - [Error-Duplicati.Library.Main.Operation.RestoreHandler-PatchingFailed]: Failed to patch with remote file: \"duplicati-b2309aa2fcab04deebe6d51e37571832c.dblock.zip.aes\", message: Failed to decrypt data (invalid passphrase?): Invalid password or corrupted data",
"2019-03-14 15:27:42 +13 - [Error-Duplicati.Library.Main.Operation.RestoreHandler-RestoreFileFailed]: Failed to restore file: \"C:\\Users\\Jay\\Desktop\\Alex Database - Current as at Sept. '11.accdb\". File hash is pu9BSetwlMmcQH5bdJfHlqx5Ut9CKZiLYn+RmliR374=, expected hash is BkeOxjeU6KtjWosC6gSIcyMyYqn0yDYMI4pnpwnWUGQ="
],
"BackendStatistics": {
"RemoteCalls": 16,
"BytesUploaded": 0,
"BytesDownloaded": 0,
"FilesUploaded": 0,
"FilesDownloaded": 0,
"FilesDeleted": 0,
"FoldersCreated": 0,
"RetryAttempts": 12,
"UnknownFileSize": 0,
"UnknownFileCount": 0,
"KnownFileCount": 37,
"KnownFileSize": 147168305,
"LastBackupDate": "2019-03-14T15:00:00+13:00",
"BackupListCount": 17,
"TotalQuotaSpace": 0,
"FreeQuotaSpace": 0,
"AssignedQuotaSpace": -1,
"ReportedQuotaError": false,
"ReportedQuotaWarning": false,
"MainOperation": "Restore",
"ParsedResult": "Success",
"Version": "2.0.4.12 (2.0.4.12_canary_2019-01-16)",
"EndTime": "0001-01-01T00:00:00",
"BeginTime": "2019-03-14T02:16:42.7887451Z",
"Duration": "00:00:00",
"MessagesActualLength": 0,
"WarningsActualLength": 0,
"ErrorsActualLength": 0,
"Messages": null,
"Warnings": null,
"Errors": null
}
}
I would love to know what it’s problem is!