Second run of the backup that worked once failed, seemingly on a 60 second timeout.
This version has a default 30 second read-write-timeout (compared to 10m in 2.1.0.5), but maybe it’s still considered good due to other changes? Regardless, I’m at default.
What I’m not at default on is my number-of-retries=0 in order to invite some problems.
Summary of profiling log by showing lines containing Backend:
2025-03-27 10:15:35 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationList]: Starting - RemoteOperationList
2025-03-27 10:15:35 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: ()
2025-03-27 10:15:37 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (530 bytes)
2025-03-27 10:15:37 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationList]: RemoteOperationList took 0:00:00:01.865
2025-03-27 10:15:37 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationQuotaInfo]: Starting - RemoteOperationQuotaInfo
2025-03-27 10:15:37 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationQuotaInfo]: RemoteOperationQuotaInfo took 0:00:00:00.000
2025-03-27 10:15:37 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationWaitForEmpty]: Starting - RemoteOperationWaitForEmpty
2025-03-27 10:15:37 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationWaitForEmpty]: RemoteOperationWaitForEmpty took 0:00:00:00.000
2025-03-27 10:19:33 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 10:19:36 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 10:19:45 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b13e8a7dd6fa9480bbb2e526b3fb564d1.dblock.zip.aes (50.00 MiB)
2025-03-27 10:19:47 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-bcbc16412d6a04d7f992ce495fbf79854.dblock.zip.aes (49.96 MiB)
2025-03-27 10:19:59 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 49.96 MiB in 00:00:12.2063091, 4.09 MiB/s
2025-03-27 10:19:59 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-bcbc16412d6a04d7f992ce495fbf79854.dblock.zip.aes (49.96 MiB)
2025-03-27 10:19:59 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-ifd44d9a54cff452fb25cfa9cb633b69f.dindex.zip.aes (22.68 KiB)
2025-03-27 10:19:59 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 22.68 KiB in 00:00:00.1120708, 202.41 KiB/s
2025-03-27 10:19:59 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-ifd44d9a54cff452fb25cfa9cb633b69f.dindex.zip.aes (22.68 KiB)
2025-03-27 10:19:59 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:00:26.250
2025-03-27 10:20:05 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 10:20:06 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-bece0e7d87b8044d68a5566888024ab3e.dblock.zip.aes (10.67 MiB)
2025-03-27 10:20:07 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 10.67 MiB in 00:00:01.8287084, 5.84 MiB/s
2025-03-27 10:20:07 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-bece0e7d87b8044d68a5566888024ab3e.dblock.zip.aes (10.67 MiB)
2025-03-27 10:20:08 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-id76a89bc466144119f95b88874d233c1.dindex.zip.aes (63.36 KiB)
2025-03-27 10:20:08 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 63.36 KiB in 00:00:00.1126734, 562.30 KiB/s
2025-03-27 10:20:08 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-id76a89bc466144119f95b88874d233c1.dindex.zip.aes (63.36 KiB)
2025-03-27 10:20:08 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:00:02.761
2025-03-27 10:20:14 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 10:20:14 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-20250327T141533Z.dlist.zip.aes (958.18 KiB)
2025-03-27 10:20:15 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 958.18 KiB in 00:00:00.8435476, 1.11 MiB/s
2025-03-27 10:20:15 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-20250327T141533Z.dlist.zip.aes (958.18 KiB)
2025-03-27 10:20:15 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:00:01.047
2025-03-27 10:20:46 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:01:10.493
2025-03-27 10:20:46 -04 - [Retry-Duplicati.Library.Main.Backend.Handler-RetryPut]: Operation Put with file duplicati-b13e8a7dd6fa9480bbb2e526b3fb564d1.dblock.zip.aes attempt 1 of 0 failed with message: The operation has timed out.
2025-03-27 10:20:47 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Failed: duplicati-b13e8a7dd6fa9480bbb2e526b3fb564d1.dblock.zip.aes ()
2025-03-27 10:20:47 -04 - [Warning-Duplicati.Library.Main.Backend.Handler-BackendManagerHandlerFailure]: Error in handler: The operation has timed out.
2025-03-27 10:20:47 -04 - [Warning-Duplicati.Library.Main.Backend.DatabaseCollector-FlushingMessagesToLog]: Update: duplicati-20250327T141533Z.dlist.zip.aes State: Uploading Size: 981181 Hash: ivaEIdbYmYnSVLEB3P3/H2WQXTQmFWK9OgWdymAPLWo=
Job log:
Warnings 2
2025-03-27 10:20:47 -04 - [Warning-Duplicati.Library.Main.Backend.Handler-BackendManagerHandlerFailure]: Error in handler: The operation has timed out.
TimeoutException: The operation has timed out.
2025-03-27 10:20:47 -04 - [Warning-Duplicati.Library.Main.Backend.DatabaseCollector-FlushingMessagesToLog]: Update: duplicati-20250327T141533Z.dlist.zip.aes State: Uploading Size: 981181 Hash: ivaEIdbYmYnSVLEB3P3/H2WQXTQmFWK9OgWdymAPLWo=
Operation: put File: duplicati-20250327T141533Z.dlist.zip.aes Result: {"Size":981181,"Hash":"ivaEIdbYmYnSVLEB3P3/H2WQXTQmFWK9OgWdymAPLWo="}
Update: duplicati-20250327T141533Z.dlist.zip.aes State: Uploaded Size: 981181 Hash: ivaEIdbYmYnSVLEB3P3/H2WQXTQmFWK9OgWdymAPLWo=
Errors 2
2025-03-27 10:20:47 -04 - [Error-Duplicati.Library.Main.Operation.BackupHandler-FatalError]: Fatal error
TimeoutException: The operation has timed out.
2025-03-27 10:20:47 -04 - [Error-Duplicati.Library.Main.Controller-FailedOperation]: The operation Backup has failed with error: The operation has timed out.
TimeoutException: The operation has timed out.
Backblaze web UI for the time of the backup has:
duplicati-20250327T141533Z.dlist.zip.aes (Warning but made it)
duplicati-b13e8a7dd6fa9480bbb2e526b3fb564d1.dblock.zip.aes (timed out but made it)
duplicati-bcbc16412d6a04d7f992ce495fbf79854.dblock.zip.aes
duplicati-bece0e7d87b8044d68a5566888024ab3e.dblock.zip.aes
duplicati-id76a89bc466144119f95b88874d233c1.dindex.zip.aes
duplicati-ifd44d9a54cff452fb25cfa9cb633b69f.dindex.zip.aes
RemoteOperation table has:
put duplicati-b13e8a7dd6fa9480bbb2e526b3fb564d1.dblock.zip.aes
put duplicati-bcbc16412d6a04d7f992ce495fbf79854.dblock.zip.aes
put duplicati-ifd44d9a54cff452fb25cfa9cb633b69f.dindex.zip.aes
put duplicati-bece0e7d87b8044d68a5566888024ab3e.dblock.zip.aes
put duplicati-id76a89bc466144119f95b88874d233c1.dindex.zip.aes
OK, now try a second backup and see if it can clean up things and succeed…
EDIT 1:
Still fails on timeout, but in less time. Activity summary from the log file:
2025-03-27 11:27:00 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationList]: Starting - RemoteOperationList
2025-03-27 11:27:00 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started: ()
2025-03-27 11:27:01 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed: (536 bytes)
2025-03-27 11:27:01 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationList]: RemoteOperationList took 0:00:00:01.295
2025-03-27 11:27:01 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationQuotaInfo]: Starting - RemoteOperationQuotaInfo
2025-03-27 11:27:01 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationQuotaInfo]: RemoteOperationQuotaInfo took 0:00:00:00.000
2025-03-27 11:27:02 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationWaitForEmpty]: Starting - RemoteOperationWaitForEmpty
2025-03-27 11:27:02 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationWaitForEmpty]: RemoteOperationWaitForEmpty took 0:00:00:00.000
2025-03-27 11:28:09 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 11:28:09 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 11:28:09 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-20250327T141534Z.dlist.zip.aes (958.18 KiB)
2025-03-27 11:28:09 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-if2316671cd3d42798b1ae7d48186b530.dindex.zip.aes (22.81 KiB)
2025-03-27 11:28:10 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 958.18 KiB in 00:00:01.2938010, 740.60 KiB/s
2025-03-27 11:28:10 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-20250327T141534Z.dlist.zip.aes (958.18 KiB)
2025-03-27 11:28:10 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:00:01.799
2025-03-27 11:28:11 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 22.81 KiB in 00:00:01.6136102, 14.13 KiB/s
2025-03-27 11:28:11 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-if2316671cd3d42798b1ae7d48186b530.dindex.zip.aes (22.81 KiB)
2025-03-27 11:28:11 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:00:01.649
2025-03-27 11:29:09 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 11:29:09 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 11:29:10 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b5f9bb79bf67d44d993cd7657811dcc79.dblock.zip.aes (50.00 MiB)
2025-03-27 11:29:10 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-bef3586058687402a8f556cb38b7213cf.dblock.zip.aes (49.97 MiB)
2025-03-27 11:29:20 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 11:29:20 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b65ec1c6e478c4dc3b18422a75fbe0632.dblock.zip.aes (8.43 MiB)
2025-03-27 11:29:25 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 8.43 MiB in 00:00:05.2113622, 1.62 MiB/s
2025-03-27 11:29:25 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b65ec1c6e478c4dc3b18422a75fbe0632.dblock.zip.aes (8.43 MiB)
2025-03-27 11:29:26 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-i22b7cff2eb19460a9144cf918e2a62c0.dindex.zip.aes (53.89 KiB)
2025-03-27 11:29:26 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 53.89 KiB in 00:00:00.6330919, 85.12 KiB/s
2025-03-27 11:29:26 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-i22b7cff2eb19460a9144cf918e2a62c0.dindex.zip.aes (53.89 KiB)
2025-03-27 11:29:26 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:00:06.392
2025-03-27 11:29:33 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: Starting - RemoteOperationPut
2025-03-27 11:29:33 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-20250327T152657Z.dlist.zip.aes (958.22 KiB)
2025-03-27 11:29:34 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 958.22 KiB in 00:00:00.8538193, 1.10 MiB/s
2025-03-27 11:29:34 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-20250327T152657Z.dlist.zip.aes (958.22 KiB)
2025-03-27 11:29:34 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:00:01.028
2025-03-27 11:29:38 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 49.97 MiB in 00:00:27.4970785, 1.82 MiB/s
2025-03-27 11:29:38 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-bef3586058687402a8f556cb38b7213cf.dblock.zip.aes (49.97 MiB)
2025-03-27 11:29:38 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-ied44d06a4ad342908644ce2c3bedc6dd.dindex.zip.aes (22.73 KiB)
2025-03-27 11:29:38 -04 - [Profiling-Duplicati.Library.Main.Backend.PutOperation-UploadSpeed]: Uploaded 22.73 KiB in 00:00:00.1280924, 177.46 KiB/s
2025-03-27 11:29:38 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-ied44d06a4ad342908644ce2c3bedc6dd.dindex.zip.aes (22.73 KiB)
2025-03-27 11:29:38 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:00:28.921
2025-03-27 11:29:55 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Backend.Handler-RemoteOperationPut]: RemoteOperationPut took 0:00:00:45.804
2025-03-27 11:29:55 -04 - [Retry-Duplicati.Library.Main.Backend.Handler-RetryPut]: Operation Put with file duplicati-b5f9bb79bf67d44d993cd7657811dcc79.dblock.zip.aes attempt 1 of 0 failed with message: The operation has timed out.
2025-03-27 11:29:55 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Failed: duplicati-b5f9bb79bf67d44d993cd7657811dcc79.dblock.zip.aes ()
2025-03-27 11:29:59 -04 - [Warning-Duplicati.Library.Main.Backend.Handler-BackendManagerHandlerFailure]: Error in handler: The operation has timed out.
2025-03-27 11:29:59 -04 - [Warning-Duplicati.Library.Main.Backend.Handler-BackendManagerDisposeWhileActive]: Terminating 1 active uploads
2025-03-27 11:29:59 -04 - [Warning-Duplicati.Library.Main.Backend.Handler-BackendManagerDisposeWhileActive]: Terminating 1 active uploads
2025-03-27 11:29:59 -04 - [Warning-Duplicati.Library.Main.Backend.Handler-BackendManagerDisposeError]: upload was active during termination, but completed successfully
2025-03-27 11:29:59 -04 - [Warning-Duplicati.Library.Main.Backend.DatabaseCollector-FlushingMessagesToLog]: Update: duplicati-20250327T152657Z.dlist.zip.aes State: Uploading Size: 981213 Hash: Zc+xSN+gA6I1htJwdpMzG7JG4TK6R+uwMQi1Dd/4nh4=
EDIT 2:
I thought I’d try BackendTester before tweaking read-write-timeout, but it did its own. Is this really a good representation of what an actual backup would use for timeout?
3/27/2025 12:19:21 PM
[12:19:21 616] Starting run no 4
[12:19:22 530] Read Write Timeout set to infinite (because implements ITimeoutExemptBackend)
[12:19:22 531] Generating file 0 (19.00 MiB)
[12:19:22 758] Generating file 1 (37.92 MiB)
[12:19:23 182] Generating file 2 (13.39 MiB)
[12:19:23 333] Generating file 3 (47.08 MiB)
[12:19:23 855] Generating file 4 (10.00 MiB)
[12:19:23 974] Generating file 5 (8.70 MiB)
[12:19:24 110] Generating file 6 (2.50 MiB)
[12:19:24 151] Generating file 7 (28.57 MiB)
[12:19:24 496] Generating file 8 (37.16 MiB)
[12:19:24 993] Generating file 9 (24.78 MiB)
[12:19:25 308] Uploading wrong files ...
[12:19:25 308] Generating file 10 (1.56 KiB)
[12:19:25 319] Uploading file 0, 1.56 KiB ... done! in 558 ms
[12:19:25 878] Uploading file 0, 1.56 KiB ... done! in 290 ms
[12:19:26 169] Uploading file 9, 1.56 KiB ... done! in 247 ms
[12:19:26 418] Uploading files ...
[12:19:26 418] Uploading file 0, 19.00 MiB ... done! in 7530 ms
[12:19:33 949] Uploading file 1, 37.92 MiB ... done! in 28094 ms
[12:20:02 043] Uploading file 2, 13.39 MiB ... done! in 11229 ms
[12:20:13 273] Uploading file 3, 47.08 MiB ... [12:20:44 763] Failed to upload file 3, error message: System.TimeoutException: The operation has timed out.
at Duplicati.StreamUtil.TimeoutObservingStream.ReadImplAsync(Byte[] buffer, Int32 offset, Int32 count, CancellationToken cancellationToken)
at System.IO.Stream.<>c.<BeginReadInternal>b__38_0(Object <p0>)
at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
--- End of stack trace from previous location ---
at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
at System.Threading.Tasks.Task.ExecuteWithThreadLocal(Task& currentTaskSlot, Thread threadPoolThread)
--- End of stack trace from previous location ---
at System.IO.Stream.<CopyToAsync>g__Core|27_0(Stream source, Stream destination, Int32 bufferSize, CancellationToken cancellationToken)
at System.Net.Http.StreamToStreamCopy.<CopyAsync>g__DisposeSourceAsync|1_0(Task copyTask, Stream source)
at System.Net.Http.HttpContent.<CopyToAsync>g__WaitAsync|56_0(ValueTask copyTask)
at System.Net.Http.HttpConnection.SendRequestContentAsync(HttpRequestMessage request, HttpContentWriteStream stream, Boolean async, CancellationToken cancellationToken)
at System.Net.Http.HttpConnection.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
at System.Net.Http.HttpConnection.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
at System.Net.Http.HttpConnectionPool.SendWithVersionDetectionAndRetryAsync(HttpRequestMessage request, Boolean async, Boolean doRequestAuth, CancellationToken cancellationToken)
at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
at System.Net.Http.HttpClient.<SendAsync>g__Core|83_0(HttpRequestMessage request, HttpCompletionOption completionOption, CancellationTokenSource cts, Boolean disposeCts, CancellationTokenSource pendingRequestsCts, CancellationToken originalCancellationToken)
at Duplicati.Library.Backend.Backblaze.B2.PutAsync(String remotename, Stream stream, CancellationToken cancelToken)
at Duplicati.Library.Utility.Utility.Await(Task task)
at Duplicati.CommandLine.BackendTester.Program.Uploadfile(String localfilename, Int32 i, String remotefilename, IBackend backend, Boolean disableStreaming, Int64 throttle, Int32 readWriteTimeout), remote name: yyBNPiiTQNNBCPSikwlAfR after 31563 ms
[12:20:44 840] Uploading file 4, 10.00 MiB ... done! in 2868 ms
[12:20:47 709] Uploading file 5, 8.70 MiB ... done! in 3508 ms
[12:20:51 218] Uploading file 6, 2.50 MiB ... done! in 2436 ms
[12:20:53 655] Uploading file 7, 28.57 MiB ... done! in 23314 ms
[12:21:16 970] Uploading file 8, 37.16 MiB ... done! in 24095 ms
[12:21:41 066] Uploading file 9, 24.78 MiB ... done! in 15139 ms
EDIT 3:
Going to an infinite read-write-timeout (0s) seems to have solved the upload fails.
Recovery code seems to have managed to deal with the mess, so good job there.
But why does Canary continue to suffer? This was asked before. Response was:
If there’s no need, then why does it fail by default with small destination (561 files)?