"Failure has occurred while loading a type" after updating canary

Support
#1

After updating to canary 2.0.5.108 yesterday, I’m not able to complete backups anymore, and they always fail with the message “Failure has occurred while loading a type”. My connection test still works and the files appear to be fine on my destination drive. Another machine using an older duplicati version (2.0.5.1_beta_2020-01-18) continues to work fine. Any help would be appreciated! Logs are as follows:

{
“DeletedFiles”: 21,
“DeletedFolders”: 1,
“ModifiedFiles”: 127,
“ExaminedFiles”: 643635,
“OpenedFiles”: 1157,
“AddedFiles”: 1030,
“SizeOfModifiedFiles”: 2360913,
“SizeOfAddedFiles”: 19256888,
“SizeOfExaminedFiles”: 34471533583,
“SizeOfOpenedFiles”: 21617932,
“NotProcessedFiles”: 0,
“AddedFolders”: 198,
“TooLargeFiles”: 0,
“FilesWithError”: 0,
“ModifiedFolders”: 0,
“ModifiedSymlinks”: 0,
“AddedSymlinks”: 0,
“DeletedSymlinks”: 0,
“PartialBackup”: false,
“Dryrun”: false,
“MainOperation”: “Backup”,
“CompactResults”: {
“DeletedFileCount”: 0,
“DownloadedFileCount”: 0,
“UploadedFileCount”: 0,
“DeletedFileSize”: 0,
“DownloadedFileSize”: 0,
“UploadedFileSize”: 0,
“Dryrun”: false,
“VacuumResults”: null,
“MainOperation”: “Compact”,
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “2020-09-07T07:28:29.747606Z”,
“BeginTime”: “2020-09-07T07:28:05.349346Z”,
“Duration”: “00:00:24.3982600”,
“MessagesActualLength”: 0,
“WarningsActualLength”: 0,
“ErrorsActualLength”: 0,
“Messages”: null,
“Warnings”: null,
“Errors”: null,
“BackendStatistics”: {
“RemoteCalls”: 9,
“BytesUploaded”: 45750487,
“BytesDownloaded”: 84155911,
“FilesUploaded”: 3,
“FilesDownloaded”: 3,
“FilesDeleted”: 1,
“FoldersCreated”: 0,
“RetryAttempts”: 0,
“UnknownFileSize”: 0,
“UnknownFileCount”: 0,
“KnownFileCount”: 642,
“KnownFileSize”: 15636146810,
“LastBackupDate”: “2020-09-07T03:15:00-04:00”,
“BackupListCount”: 28,
“TotalQuotaSpace”: 0,
“FreeQuotaSpace”: 0,
“AssignedQuotaSpace”: -1,
“ReportedQuotaError”: false,
“ReportedQuotaWarning”: false,
“MainOperation”: “Backup”,
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “0001-01-01T00:00:00”,
“BeginTime”: “2020-09-07T07:15:00.098966Z”,
“Duration”: “00:00:00”,
“MessagesActualLength”: 0,
“WarningsActualLength”: 0,
“ErrorsActualLength”: 0,
“Messages”: null,
“Warnings”: null,
“Errors”: null
}
},
“VacuumResults”: null,
“DeleteResults”: {
“DeletedSetsActualLength”: 1,
“DeletedSets”: [
{
“Item1”: 28,
“Item2”: “2020-08-06T03:15:00-04:00”
}
],
“Dryrun”: false,
“MainOperation”: “Delete”,
“CompactResults”: {
“DeletedFileCount”: 0,
“DownloadedFileCount”: 0,
“UploadedFileCount”: 0,
“DeletedFileSize”: 0,
“DownloadedFileSize”: 0,
“UploadedFileSize”: 0,
“Dryrun”: false,
“VacuumResults”: null,
“MainOperation”: “Compact”,
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “2020-09-07T07:28:29.747606Z”,
“BeginTime”: “2020-09-07T07:28:05.349346Z”,
“Duration”: “00:00:24.3982600”,
“MessagesActualLength”: 0,
“WarningsActualLength”: 0,
“ErrorsActualLength”: 0,
“Messages”: null,
“Warnings”: null,
“Errors”: null,
“BackendStatistics”: {
“RemoteCalls”: 9,
“BytesUploaded”: 45750487,
“BytesDownloaded”: 84155911,
“FilesUploaded”: 3,
“FilesDownloaded”: 3,
“FilesDeleted”: 1,
“FoldersCreated”: 0,
“RetryAttempts”: 0,
“UnknownFileSize”: 0,
“UnknownFileCount”: 0,
“KnownFileCount”: 642,
“KnownFileSize”: 15636146810,
“LastBackupDate”: “2020-09-07T03:15:00-04:00”,
“BackupListCount”: 28,
“TotalQuotaSpace”: 0,
“FreeQuotaSpace”: 0,
“AssignedQuotaSpace”: -1,
“ReportedQuotaError”: false,
“ReportedQuotaWarning”: false,
“MainOperation”: “Backup”,
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “0001-01-01T00:00:00”,
“BeginTime”: “2020-09-07T07:15:00.098966Z”,
“Duration”: “00:00:00”,
“MessagesActualLength”: 0,
“WarningsActualLength”: 0,
“ErrorsActualLength”: 0,
“Messages”: null,
“Warnings”: null,
“Errors”: null
}
},
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “2020-09-07T07:28:29.747609Z”,
“BeginTime”: “2020-09-07T07:27:55.590539Z”,
“Duration”: “00:00:34.1570700”,
“MessagesActualLength”: 0,
“WarningsActualLength”: 0,
“ErrorsActualLength”: 0,
“Messages”: null,
“Warnings”: null,
“Errors”: null,
“BackendStatistics”: {
“RemoteCalls”: 9,
“BytesUploaded”: 45750487,
“BytesDownloaded”: 84155911,
“FilesUploaded”: 3,
“FilesDownloaded”: 3,
“FilesDeleted”: 1,
“FoldersCreated”: 0,
“RetryAttempts”: 0,
“UnknownFileSize”: 0,
“UnknownFileCount”: 0,
“KnownFileCount”: 642,
“KnownFileSize”: 15636146810,
“LastBackupDate”: “2020-09-07T03:15:00-04:00”,
“BackupListCount”: 28,
“TotalQuotaSpace”: 0,
“FreeQuotaSpace”: 0,
“AssignedQuotaSpace”: -1,
“ReportedQuotaError”: false,
“ReportedQuotaWarning”: false,
“MainOperation”: “Backup”,
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “0001-01-01T00:00:00”,
“BeginTime”: “2020-09-07T07:15:00.098966Z”,
“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-20200907T071500Z.dlist.zip.aes”,
“Value”:
},
{
“Key”: “duplicati-i7dd3f574d0064c7f86919e9eeec67026.dindex.zip.aes”,
“Value”:
},
{
“Key”: “duplicati-be830dc57426e4a97ae727e5f449327b7.dblock.zip.aes”,
“Value”:
}
],
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “2020-09-07T07:29:20.252478Z”,
“BeginTime”: “2020-09-07T07:28:34.855344Z”,
“Duration”: “00:00:45.3971340”,
“MessagesActualLength”: 0,
“WarningsActualLength”: 0,
“ErrorsActualLength”: 0,
“Messages”: null,
“Warnings”: null,
“Errors”: null,
“BackendStatistics”: {
“RemoteCalls”: 9,
“BytesUploaded”: 45750487,
“BytesDownloaded”: 84155911,
“FilesUploaded”: 3,
“FilesDownloaded”: 3,
“FilesDeleted”: 1,
“FoldersCreated”: 0,
“RetryAttempts”: 0,
“UnknownFileSize”: 0,
“UnknownFileCount”: 0,
“KnownFileCount”: 642,
“KnownFileSize”: 15636146810,
“LastBackupDate”: “2020-09-07T03:15:00-04:00”,
“BackupListCount”: 28,
“TotalQuotaSpace”: 0,
“FreeQuotaSpace”: 0,
“AssignedQuotaSpace”: -1,
“ReportedQuotaError”: false,
“ReportedQuotaWarning”: false,
“MainOperation”: “Backup”,
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “0001-01-01T00:00:00”,
“BeginTime”: “2020-09-07T07:15:00.098966Z”,
“Duration”: “00:00:00”,
“MessagesActualLength”: 0,
“WarningsActualLength”: 0,
“ErrorsActualLength”: 0,
“Messages”: null,
“Warnings”: null,
“Errors”: null
}
},
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “2020-09-07T07:29:20.270243Z”,
“BeginTime”: “2020-09-07T07:15:00.098961Z”,
“Duration”: “00:14:20.1712820”,
“MessagesActualLength”: 22,
“WarningsActualLength”: 0,
“ErrorsActualLength”: 0,
“Messages”: [
“2020-09-07 03:15:00 -04 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started”,
“2020-09-07 03:17:54 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: ()”,
“2020-09-07 03:18:10 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (640 bytes)”,
“2020-09-07 03:26:50 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-bf8c772cdbbe647e5aa87f29e519a7470.dblock.zip.aes (13.24 MB)”,
“2020-09-07 03:27:11 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-bf8c772cdbbe647e5aa87f29e519a7470.dblock.zip.aes (13.24 MB)”,
“2020-09-07 03:27:25 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-i45c372d6e47548c0827ee4d7497b5637.dindex.zip.aes (57.09 KB)”,
“2020-09-07 03:27:25 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-i45c372d6e47548c0827ee4d7497b5637.dindex.zip.aes (57.09 KB)”,
“2020-09-07 03:27:43 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-20200907T071500Z.dlist.zip.aes (30.33 MB)”,
“2020-09-07 03:27:55 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-20200907T071500Z.dlist.zip.aes (30.33 MB)”,
“2020-09-07 03:27:55 -04 - [Information-Duplicati.Library.Main.Operation.DeleteHandler-DeleteRemoteFileset]: Deleting 1 remote fileset(s) …”,
“2020-09-07 03:28:04 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Delete - Started: duplicati-20200806T071500Z.dlist.zip.aes (30.10 MB)”,
“2020-09-07 03:28:04 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Delete - Completed: duplicati-20200806T071500Z.dlist.zip.aes (30.10 MB)”,
“2020-09-07 03:28:05 -04 - [Information-Duplicati.Library.Main.Operation.DeleteHandler-DeleteResults]: Deleted 1 remote fileset(s)”,
“2020-09-07 03:28:29 -04 - [Information-Duplicati.Library.Main.Database.LocalDeleteDatabase-CompactReason]: Compacting not required”,
“2020-09-07 03:28:29 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: ()”,
“2020-09-07 03:28:34 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (642 bytes)”,
“2020-09-07 03:28:34 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-20200907T071500Z.dlist.zip.aes (30.33 MB)”,
“2020-09-07 03:28:53 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Completed: duplicati-20200907T071500Z.dlist.zip.aes (30.33 MB)”,
“2020-09-07 03:28:54 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-i7dd3f574d0064c7f86919e9eeec67026.dindex.zip.aes (1.75 KB)”,
“2020-09-07 03:28:54 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Completed: duplicati-i7dd3f574d0064c7f86919e9eeec67026.dindex.zip.aes (1.75 KB)”
],
“Warnings”: ,
“Errors”: ,
“BackendStatistics”: {
“RemoteCalls”: 9,
“BytesUploaded”: 45750487,
“BytesDownloaded”: 84155911,
“FilesUploaded”: 3,
“FilesDownloaded”: 3,
“FilesDeleted”: 1,
“FoldersCreated”: 0,
“RetryAttempts”: 0,
“UnknownFileSize”: 0,
“UnknownFileCount”: 0,
“KnownFileCount”: 642,
“KnownFileSize”: 15636146810,
“LastBackupDate”: “2020-09-07T03:15:00-04:00”,
“BackupListCount”: 28,
“TotalQuotaSpace”: 0,
“FreeQuotaSpace”: 0,
“AssignedQuotaSpace”: -1,
“ReportedQuotaError”: false,
“ReportedQuotaWarning”: false,
“MainOperation”: “Backup”,
“ParsedResult”: “Success”,
“Version”: “2.0.5.108 (2.0.5.108_canary_2020-07-09)”,
“EndTime”: “0001-01-01T00:00:00”,
“BeginTime”: “2020-09-07T07:15:00.098966Z”,
“Duration”: “00:00:00”,
“MessagesActualLength”: 0,
“WarningsActualLength”: 0,
“ErrorsActualLength”: 0,
“Messages”: null,
“Warnings”: null,
“Errors”: null
}
}

#2

Are you running on osx or linux? I had the same error message and solved it by updating mono. See "Failure has occurred while loading a type. " error during backup on osx for details.

1 Like
#3

I’m running Linux (Ubuntu), but you may be right anyway. I’m using a fairly old version of mono:

Mono JIT compiler version 4.6.2 (Debian 4.6.2.7+dfsg-1ubuntu1)
Copyright (C) 2002-2014 Novell, Inc, Xamarin Inc and Contributors. www.mono-project.com
        TLS:           __thread
        SIGSEGV:       altstack
        Notifications: epoll
        Architecture:  amd64
        Disabled:      none
        Misc:          softdebug 
        LLVM:          supported, not enabled.
        GC:            sgen

Unfortunately it seems like updating it is going to be a chore, but I’ll try that next.

#4

Well, my saga continues. After finally getting mono to update to 6.12.0.90 (using the official mono repo), and updating duplicati to 2.0.5.110_canary_2020-08-10, one of my two backups did complete normally, and so from that perspective the old mono version was clearly the culprit. Unfortunately, with the other backup I now get a new error:

Unexpected difference in fileset version 26: 8/8/2020 3:15:00 AM (database id: 176), found 673647 entries, but expected 702205

(the error persists after trying to repair the database). I have 28 backup versions, so my question now is, what should I try? I can delete/recreate the database, or maybe I can somehow cut out the last few backup versions and let it diff against only versions 25 and before (if that’s even possible – I have no idea).

Any suggestions?

#5

Is this the 2.0.5.1 backup, or some other version? For older ones that did this more the way to fix was to delete the cited version. Sometimes the error would move to a different version. Only one is announced.

The database self-check that discovers this error is done before the backup, so the issue was left from previous backup. Do you still have logs for the job or server (About --> Show log --> Stored) with clues?

If you just want to try a version delete, Commandline Command can be set to delete and you can change Commandline arguments to --version=26 (or whatever the latest complaint is about) and run delete…

EDIT:

If you have space and time, you can make a copy of the Database and destination files before trying this.
This doesn’t seem to be done much, but it would permit more recovery tries with less sacrifice of data…

#6

Yup, shortly after posting that I found the documentation about the commandline commands and looked into running a delete. I did a --dry-run first trying to delete version 26 and got a whole bunch of other errors, which made me suspect the database again. So instead of just running a repair, I did a delete and rebuild on the database, and lo and behold, the new error disappeared. Since then I’ve backed up to both databases a few times, and all appears to be well once again.

Thanks for all the help!

2 Likes