Help understanding "Failed to process file"


#1

Hello, I created a new backup job a few days ago, (Windows 10 PC to OneDrive V2) using Duplicati 2.0.4.5_beta_2018-11-28.

My backup finished today. After uploading, it verified some of the files as expected and when finished with that, Duplicati gave me an error. After reading the error, I googled about it and searched these forums and still don’t understand the problem. It seems to be something with the verification. I have ran the verification twice more, and get the same errors. I did a test restore of a file and that worked and the file checksum checks out.

Below is the error I got. I am wondering about the errors listed as: “Failed to process file …dblock.zip.aes” and “Value: A task was canceled.” I haven’t canceled the task as far as I know. Did OneDrive cancel the download during verification?

Any help would be greatly appreciated. I just want to know if my backup is safe. Thank you.

DeletedFiles: 0

DeletedFolders: 0

ModifiedFiles: 0

ExaminedFiles: 266

OpenedFiles: 0

AddedFiles: 0

SizeOfModifiedFiles: 0

SizeOfAddedFiles: 0

SizeOfExaminedFiles: 152364334413

SizeOfOpenedFiles: 0

NotProcessedFiles: 0

AddedFolders: 0

TooLargeFiles: 0

FilesWithError: 0

ModifiedFolders: 0

ModifiedSymlinks: 0

AddedSymlinks: 0

DeletedSymlinks: 0

PartialBackup: False

Dryrun: False

MainOperation: Backup

CompactResults:

DeletedFileCount: 2

DownloadedFileCount: 0

UploadedFileCount: 0

DeletedFileSize: 2202

DownloadedFileSize: 0

UploadedFileSize: 0

Dryrun: False

MainOperation: Compact

ParsedResult: Error

Version: 2.0.4.5 (2.0.4.5_beta_2018-11-28)

EndTime: 12/3/2018 20:42:46 (1543891366)

BeginTime: 12/3/2018 20:42:46 (1543891366)

Duration: 00:00:00.3906378

Messages: [

    2018-12-03 20:42:34 -06 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started,

    2018-12-03 20:42:40 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started:  (),

    2018-12-03 20:42:43 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed:  (145 bytes),

    2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.Operation.BackupHandler-SkipUsnForVolume]: Performing full scan for volume "S:\",

    2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: むかしむかし-be4d2be10db854663b2ebbb5858365b25.dblock.zip.aes (1.06 KB),

]

Warnings: [\*]

Errors: [

    2018-12-03 20:51:51 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-bbb4897a42bf24292b9138e9e73901774.dblock.zip.aes,

    2018-12-03 21:00:52 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-b8d3fe233e28947a18a4e01e264eada96.dblock.zip.aes

]

BackendStatistics:

    RemoteCalls: 19

    BytesUploaded: 2202

    BytesDownloaded: 93095

    FilesUploaded: 2

    FilesDownloaded: 3

    FilesDeleted: 2

    FoldersCreated: 0

    RetryAttempts: 8

    UnknownFileSize: 0

    UnknownFileCount: 0

    KnownFileCount: 145

    KnownFileSize: 152390436957

    LastBackupDate: 12/1/2018 19:40:11 (1543714811)

    BackupListCount: 1

    TotalQuotaSpace: 1131723882496

    FreeQuotaSpace: 547411159292

    AssignedQuotaSpace: -1

    ReportedQuotaError: False

    ReportedQuotaWarning: False

    ParsedResult: Error

    Version: 2.0.4.5 (2.0.4.5_beta_2018-11-28)

    Messages: [

        2018-12-03 20:42:34 -06 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started,

        2018-12-03 20:42:40 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started:  (),

        2018-12-03 20:42:43 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed:  (145 bytes),

        2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.Operation.BackupHandler-SkipUsnForVolume]: Performing full scan for volume "S:\",

        2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: むかしむかし-be4d2be10db854663b2ebbb5858365b25.dblock.zip.aes (1.06 KB),

    ]

    Warnings: []

    Errors: [

        2018-12-03 20:51:51 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-bbb4897a42bf24292b9138e9e73901774.dblock.zip.aes,

        2018-12-03 21:00:52 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-b8d3fe233e28947a18a4e01e264eada96.dblock.zip.aes

    ]

DeleteResults:

DeletedSets: []

Dryrun: False

MainOperation: Delete

ParsedResult: Error

Version: 2.0.4.5 (2.0.4.5_beta_2018-11-28)

EndTime: 12/3/2018 20:42:46 (1543891366)

BeginTime: 12/3/2018 20:42:46 (1543891366)

Duration: 00:00:00.4074074

Messages: [

    2018-12-03 20:42:34 -06 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started,

    2018-12-03 20:42:40 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started:  (),

    2018-12-03 20:42:43 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed:  (145 bytes),

    2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.Operation.BackupHandler-SkipUsnForVolume]: Performing full scan for volume "S:\",

    2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: むかしむかし-be4d2be10db854663b2ebbb5858365b25.dblock.zip.aes (1.06 KB),

]

Warnings: []

Errors: [

    2018-12-03 20:51:51 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-bbb4897a42bf24292b9138e9e73901774.dblock.zip.aes,

    2018-12-03 21:00:52 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-b8d3fe233e28947a18a4e01e264eada96.dblock.zip.aes

]

RepairResults: null

TestResults:

MainOperation: Test

Verifications: [

    Key: むかしむかし-20181202T014011Z.dlist.zip.aes

    Value: [],

    Key: むかしむかし-ide3694b75d9a44db9ddf4fa13a20aad2.dindex.zip.aes

    Value: [],

    Key: むかしむかし-i17749172140a47248667ec7cce79f5bd.dindex.zip.aes

    Value: [],

    Key: むかしむかし-bbb4897a42bf24292b9138e9e73901774.dblock.zip.aes

    Value: [

        Key: Error

        Value: A task was canceled.

    ],

    Key: むかしむかし-b8d3fe233e28947a18a4e01e264eada96.dblock.zip.aes

    Value: [

        Key: Error

        Value: A task was canceled.

    ]

]

ParsedResult: Error

Version: 2.0.4.5 (2.0.4.5_beta_2018-11-28)

EndTime: 12/3/2018 21:00:52 (1543892452)

BeginTime: 12/3/2018 20:42:48 (1543891368)

Duration: 00:18:04.5883219

Messages: [

    2018-12-03 20:42:34 -06 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started,

    2018-12-03 20:42:40 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started:  (),

    2018-12-03 20:42:43 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed:  (145 bytes),

    2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.Operation.BackupHandler-SkipUsnForVolume]: Performing full scan for volume "S:\",

    2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: むかしむかし-be4d2be10db854663b2ebbb5858365b25.dblock.zip.aes (1.06 KB),

]

Warnings: []

Errors: [

    2018-12-03 20:51:51 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-bbb4897a42bf24292b9138e9e73901774.dblock.zip.aes,

    2018-12-03 21:00:52 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-b8d3fe233e28947a18a4e01e264eada96.dblock.zip.aes

]

ParsedResult: Error

Version: 2.0.4.5 (2.0.4.5_beta_2018-11-28)

EndTime: 12/3/2018 21:00:52 (1543892452)

BeginTime: 12/3/2018 20:42:34 (1543891354)

Duration: 00:18:18.4655877

Messages: [

2018-12-03 20:42:34 -06 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started,

2018-12-03 20:42:40 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started:  (),

2018-12-03 20:42:43 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed:  (145 bytes),

2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.Operation.BackupHandler-SkipUsnForVolume]: Performing full scan for volume "S:\",

2018-12-03 20:42:44 -06 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: むかしむかし-be4d2be10db854663b2ebbb5858365b25.dblock.zip.aes (1.06 KB),

]

Warnings:

Errors: [

2018-12-03 20:51:51 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-bbb4897a42bf24292b9138e9e73901774.dblock.zip.aes,

2018-12-03 21:00:52 -06 - [Error-Duplicati.Library.Main.Operation.TestHandler-RemoteFileProcessingFailed]: Failed to process file むかしむかし-b8d3fe233e28947a18a4e01e264eada96.dblock.zip.aes