Canary .11 still reporting unfindable errors

Support
1

What is the error here? For any beackup set it reports at least one error.

DeletedFiles: 5

DeletedFolders: 0
ModifiedFiles: 32
ExaminedFiles: 2285
OpenedFiles: 45
AddedFiles: 13
SizeOfModifiedFiles: 31896633
SizeOfAddedFiles: 2126134
SizeOfExaminedFiles: 14807467900
SizeOfOpenedFiles: 34042323
NotProcessedFiles: 0
AddedFolders: 0
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
MainOperation: Compact
ParsedResult: Success
Version: 2.0.3.11 (2.0.3.11_canary_2018-09-05)
EndTime: 08.09.2018 13:57:55 (1536407875)
BeginTime: 08.09.2018 13:57:55 (1536407875)
Duration: 00:00:00.3120178
Messages: [
2018-09-08 13:57:46 +02 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: Die Operation Backup wurde gestartet,
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: (),
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (134 Bytes),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
…
]
Warnings:
Errors:
BackendStatistics:
RemoteCalls: 9
BytesUploaded: 5108087
BytesDownloaded: 262515175
FilesUploaded: 3
FilesDownloaded: 3
FilesDeleted: 1
FoldersCreated: 0
RetryAttempts: 0
UnknownFileSize: 0
UnknownFileCount: 0
KnownFileCount: 136
KnownFileSize: 12375763608
LastBackupDate: 08.09.2018 13:57:46 (1536407866)
BackupListCount: 4
TotalQuotaSpace: 169738235904
FreeQuotaSpace: 26486845440
AssignedQuotaSpace: -1
ReportedQuotaError: False
ReportedQuotaWarning: False
ParsedResult: Success
Version: 2.0.3.11 (2.0.3.11_canary_2018-09-05)
Messages: [
2018-09-08 13:57:46 +02 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: Die Operation Backup wurde gestartet,
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: (),
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (134 Bytes),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
…
]
Warnings:
Errors:
DeleteResults:
DeletedSets: [
Item1: 1
Item2: 07.09.2018 17:33:26 (1536334406)
]
Dryrun: False
MainOperation: Delete
ParsedResult: Success
Version: 2.0.3.11 (2.0.3.11_canary_2018-09-05)
EndTime: 08.09.2018 13:57:55 (1536407875)
BeginTime: 08.09.2018 13:57:54 (1536407874)
Duration: 00:00:01.0740614
Messages: [
2018-09-08 13:57:46 +02 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: Die Operation Backup wurde gestartet,
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: (),
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (134 Bytes),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
…
]
Warnings:
Errors:
RepairResults: null
TestResults:
MainOperation: Test
Verifications: [
Key: duplicati-20180908T115746Z.dlist.zip.aes
Value: ,
Key: duplicati-ibc3951555cae402baa4871f09359af53.dindex.zip.aes
Value: ,
Key: duplicati-b939c58082a5a43379e34075af7f9afcc.dblock.zip.aes
Value:
]
ParsedResult: Success
Version: 2.0.3.11 (2.0.3.11_canary_2018-09-05)
EndTime: 08.09.2018 13:57:57 (1536407877)
BeginTime: 08.09.2018 13:57:55 (1536407875)
Duration: 00:00:02.1231215
Messages: [
2018-09-08 13:57:46 +02 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: Die Operation Backup wurde gestartet,
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: (),
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (134 Bytes),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
…
]
Warnings:
Errors:
ParsedResult: Success
Version: 2.0.3.11 (2.0.3.11_canary_2018-09-05)
EndTime: 08.09.2018 13:57:57 (1536407877)
BeginTime: 08.09.2018 13:57:46 (1536407866)
Duration: 00:00:11.3536494
Messages: [
2018-09-08 13:57:46 +02 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: Die Operation Backup wurde gestartet,
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: (),
2018-09-08 13:57:49 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (134 Bytes),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
2018-09-08 13:57:54 +02 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b786c4b9b9e8546dab31d37e7e5ef906c.dblock.zip.aes (4,60 MB),
…
]
Warnings:
Errors:

2

I’m guessing that this is the “errors” follow-on to [2.0.3.5] Always warnings, but what is the warning? topic, so similar suggestions apply about checking the other logs. Please post any findings and any popup messages.

2 Backup warnings - what is the problem? gives some background links, possible futures, and above request.

Errors and Warnings not showing in job logs shows where looking at the global log did in fact show the cause.

“Data Error” Show button goes to wrong log is the less-than-ideal way things currently stand for multiple logs.

Duplicati announcing warnings when there are none ends with a note on the multiple logs issue, however the focus since the big 2.0.3.6 rewrite has likely been fixes of Critical issues with the current canary which seems to be coming along quite well. 2.0.3.11 users can enjoy fewer problems, but probably other fixes got delayed.

I’d expect any major redesign of the logs area would be announced at:

Releases

Latest Releases topics

1 Like
3

Yup, for me, it reports at least one error.
With no recent logs in “About -> Show log” and just normal logs in my backup entries - i.e., entries have no errors and logs - I don’t know what it wants.

EDIT: I use “run-script-after” and I just realized, this script crashes (invisibly), because it makes a db backup, but reports a wrong oauth id. I guess it causes the error message. OAUTH ID must ave changed for some reason. Corrected it - Will check now with working script.

Edit: duplicati command line crashes after successful backup. Remote backup verification completed Downloading file (54,83 KB) ... Downl - Pastebin.com - I think this triggers the error in some way.

4

Plugging some of the pastebin output found Duplicati.CommandLine.exe crash and other issues pointing to it. Unfortunately it’s still a mystery. The lack of findable errors in the web UI per-job log seems easy to reproduce using a .bat file doing, say, “exit 3”, causing a popup with nothing in the web UI job or server log. --log-file has:

2018-09-14 17:33:24 -04 - [Warning-Duplicati.Library.Modules.Builtin.RunScript-InvalidExitCode]: The script "C:\Users\Ted\Documents\exit.bat" returned with exit code 3

I played with other options to see if I could log text said by the bat file. No luck, but I might misunderstand this:

C:\Program Files\Duplicati 2>Duplicati.CommandLine.exe help runscript
Run script (runscript):
 Executes a script before starting an operation, and again on completion
 Module is loaded automatically, use --disable-module to prevent this
 Supported options:
  --run-script-before (Path): Run a script on startup
    Executes a script before performing an operation. The operation will block
    until the script has completed or timed out.
  --run-script-after (Path): Run a script on exit
    Executes a script after performing an operation. The script will receive
    the operation results written to stdout.
  --run-script-before-required (Path): Run a required script on startup
    Executes a script before performing an operation. The operation will block
    until the script has completed or timed out. If the script returns a
    non-zero error code or times out, the operation will be aborted.
  --run-script-result-output-format (Enumeration): Selects the output format
    for results
    Selects the output format for results. Available formats: Duplicati, Json
    * values: Duplicati, Json
    * default value: Duplicati
  --run-script-timeout (Timespan): Sets the script timeout
    Sets the maximum time a script is allowed to execute. If the script has
    not completed within this time, it will continue to execute but the
    operation will continue too, and no script output will be processed.
    * default value: 60s
  --run-script-log-level (Enumeration): Defines a log level for messages
    Use this option to set the log level for messages to include in the report
    * values: ExplicitOnly, Profiling, Verbose, Retry, Information, DryRun,
    Warning, Error
    * default value: Warning
  --run-script-log-filter (String): Log message filter
    Use this option to set a filter expression that defines what options are
    included in the report


C:\Program Files\Duplicati 2>

I chose the “exit 3” because 2.0.3.10 made some Improvements for –run-script-before/after options, but the probable code at Added support for multiple exit codes, and added a unittest to verify… looks like the older behavior would have also put something in the --log-file log. Unfortunately, not everyone runs those, so they can’t find the cause. I’m glad that you were able to find yours, and there’s probably a separate issue for that.