I got one without compact
, doing downloads with test ... all
. First one was a false alarm though.
2023-08-18 21:28:45 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.BackendManager-RemoteOperationGet]: Starting - RemoteOperationGet
2023-08-18 21:28:45 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b96dec70ea42a41029aea26991b47f29c.dblock.zip (50.00 MB)
2023-08-18 21:28:45 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "Blocklist-6E8BBA148806FC49A768376774405E79" ("Hash" TEXT NOT NULL, "Size" INTEGER NOT NULL)
2023-08-18 21:28:45 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "Blocklist-6E8BBA148806FC49A768376774405E79" ("Hash" TEXT NOT NULL, "Size" INTEGER NOT NULL) took 0:00:00:00.000
2023-08-18 21:28:45 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF" AS SELECT DISTINCT "Hash" AS "Hash", "Size" AS "Size" FROM (SELECT "Block"."Hash" AS "Hash", "Block"."Size" AS "Size" FROM "Remotevolume", "Block" WHERE "Remotevolume"."Name" = "duplicati-b2d698c48c7394ea8b5d8bfadb78b444b.dblock.zip" AND "Remotevolume"."ID" = "Block"."VolumeID" UNION SELECT "DeletedBlock"."Hash" AS "Hash", "DeletedBlock"."Size" AS "Size" FROM "DeletedBlock", "RemoteVolume" WHERE "RemoteVolume"."Name" = "duplicati-b2d698c48c7394ea8b5d8bfadb78b444b.dblock.zip" AND "RemoteVolume"."ID" = "DeletedBlock"."VolumeID")
2023-08-18 21:28:45 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF" AS SELECT DISTINCT "Hash" AS "Hash", "Size" AS "Size" FROM (SELECT "Block"."Hash" AS "Hash", "Block"."Size" AS "Size" FROM "Remotevolume", "Block" WHERE "Remotevolume"."Name" = "duplicati-b2d698c48c7394ea8b5d8bfadb78b444b.dblock.zip" AND "Remotevolume"."ID" = "Block"."VolumeID" UNION SELECT "DeletedBlock"."Hash" AS "Hash", "DeletedBlock"."Size" AS "Size" FROM "DeletedBlock", "RemoteVolume" WHERE "RemoteVolume"."Name" = "duplicati-b2d698c48c7394ea8b5d8bfadb78b444b.dblock.zip" AND "RemoteVolume"."ID" = "DeletedBlock"."VolumeID") took 0:00:00:00.002
2023-08-18 21:28:45 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT 1 AS "Type", "Blocklist-6E8BBA148806FC49A768376774405E79"."Hash" AS "Hash" FROM "Blocklist-6E8BBA148806FC49A768376774405E79" WHERE "Blocklist-6E8BBA148806FC49A768376774405E79"."Hash" NOT IN ( SELECT "Hash" FROM "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF" ) UNION SELECT 0 AS "Type", "Hash" AS "Hash" FROM "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF" WHERE "Hash" NOT IN (SELECT "Hash" FROM "Blocklist-6E8BBA148806FC49A768376774405E79") UNION SELECT 2 AS "Type", "E"."Hash" AS "Hash" FROM "Blocklist-6E8BBA148806FC49A768376774405E79" E, "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF" D WHERE "D"."Hash" = "E"."Hash" AND "D"."Size" != "E"."Size"
2023-08-18 21:28:45 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT 1 AS "Type", "Blocklist-6E8BBA148806FC49A768376774405E79"."Hash" AS "Hash" FROM "Blocklist-6E8BBA148806FC49A768376774405E79" WHERE "Blocklist-6E8BBA148806FC49A768376774405E79"."Hash" NOT IN ( SELECT "Hash" FROM "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF" ) UNION SELECT 0 AS "Type", "Hash" AS "Hash" FROM "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF" WHERE "Hash" NOT IN (SELECT "Hash" FROM "Blocklist-6E8BBA148806FC49A768376774405E79") UNION SELECT 2 AS "Type", "E"."Hash" AS "Hash" FROM "Blocklist-6E8BBA148806FC49A768376774405E79" E, "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF" D WHERE "D"."Hash" = "E"."Hash" AND "D"."Size" != "E"."Size" took 0:00:00:00.002
2023-08-18 21:28:45 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF"
2023-08-18 21:28:45 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "CmpTable-14B4948DCCF0BF42B01FB1ECDC2157AF" took 0:00:00:00.000
2023-08-18 21:28:45 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "Blocklist-6E8BBA148806FC49A768376774405E79"
2023-08-18 21:28:45 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "Blocklist-6E8BBA148806FC49A768376774405E79" took 0:00:00:00.000
2023-08-18 21:28:45 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: UPDATE "RemoteVolume" SET "VerificationCount" = MAX(1, CASE WHEN "VerificationCount" <= 0 THEN (SELECT MAX("VerificationCount") FROM "RemoteVolume") ELSE "VerificationCount" + 1 END) WHERE "Name" = "duplicati-b2d698c48c7394ea8b5d8bfadb78b444b.dblock.zip"
2023-08-18 21:28:45 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: UPDATE "RemoteVolume" SET "VerificationCount" = MAX(1, CASE WHEN "VerificationCount" <= 0 THEN (SELECT MAX("VerificationCount") FROM "RemoteVolume") ELSE "VerificationCount" + 1 END) WHERE "Name" = "duplicati-b2d698c48c7394ea8b5d8bfadb78b444b.dblock.zip" took 0:00:00:00.310
2023-08-18 21:31:34 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.BackendManager-RemoteOperationGet]: RemoteOperationGet took 0:00:02:48.866
2023-08-18 21:31:34 -04 - [Retry-Duplicati.Library.Main.BackendManager-RetryGet]: Operation Get with file duplicati-b96dec70ea42a41029aea26991b47f29c.dblock.zip attempt 1 of 5 failed with message: Error while copying content to a stream.
System.Net.Http.HttpRequestException: Error while copying content to a stream. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host
at System.Net.Sockets.Socket.EndReceive(IAsyncResult asyncResult)
at System.Net.Sockets.NetworkStream.EndRead(IAsyncResult asyncResult)
--- End of inner exception stack trace ---
at System.Net.ConnectStream.EndRead(IAsyncResult asyncResult)
at System.Net.Http.HttpClientHandler.WebExceptionWrapperStream.EndRead(IAsyncResult asyncResult)
at System.Net.Http.StreamToStreamCopy.BufferReadCallback(IAsyncResult ar)
--- End of inner exception stack trace ---
at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
at Duplicati.Library.OAuthHttpClient.<GetAsync>d__5.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
at Duplicati.Library.Backend.MicrosoftGraphBackend.Get(String remotename, Stream stream)
at Duplicati.Library.Main.BackendManager.coreDoGetPiping(FileEntryItem item, IEncryption useDecrypter, Int64& retDownloadSize, String& retHashcode)
at Duplicati.Library.Main.BackendManager.DoGet(FileEntryItem item)
at Duplicati.Library.Main.BackendManager.ThreadRun()
2023-08-18 21:31:34 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Retrying: duplicati-b96dec70ea42a41029aea26991b47f29c.dblock.zip (50.00 MB)
2023-08-18 21:31:44 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.BackendManager-RemoteOperationGet]: Starting - RemoteOperationGet
2023-08-18 21:31:44 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b96dec70ea42a41029aea26991b47f29c.dblock.zip (50.00 MB)
2023-08-18 21:32:52 -04 - [Profiling-Duplicati.Library.Main.BackendManager-DownloadSpeed]: Downloaded 50.00 MB in 00:01:07.7951080, 755.21 KB/s
2023-08-18 21:32:52 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Completed: duplicati-b96dec70ea42a41029aea26991b47f29c.dblock.zip (50.00 MB)
2023-08-18 21:32:52 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.BackendManager-RemoteOperationGet]: RemoteOperationGet took 0:00:01:07.796
Second one looks like it’s more stuck. It’s been sitting for around 10 hours with a 1drv.ms connection.
2023-08-18 22:17:45 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.BackendManager-RemoteOperationGet]: Starting - RemoteOperationGet
2023-08-18 22:17:45 -04 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Get - Started: duplicati-b911d41fae406498eaac7d1d8a053e0e8.dblock.zip (50.00 MB)
2023-08-18 22:17:45 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "Blocklist-8F1C4DC173E13B43A3436188928F2D84" ("Hash" TEXT NOT NULL, "Size" INTEGER NOT NULL)
2023-08-18 22:17:45 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "Blocklist-8F1C4DC173E13B43A3436188928F2D84" ("Hash" TEXT NOT NULL, "Size" INTEGER NOT NULL) took 0:00:00:00.000
2023-08-18 22:17:46 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: CREATE TEMPORARY TABLE "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0" AS SELECT DISTINCT "Hash" AS "Hash", "Size" AS "Size" FROM (SELECT "Block"."Hash" AS "Hash", "Block"."Size" AS "Size" FROM "Remotevolume", "Block" WHERE "Remotevolume"."Name" = "duplicati-bb8e417fdea554f3bbb920eaadc98a79b.dblock.zip" AND "Remotevolume"."ID" = "Block"."VolumeID" UNION SELECT "DeletedBlock"."Hash" AS "Hash", "DeletedBlock"."Size" AS "Size" FROM "DeletedBlock", "RemoteVolume" WHERE "RemoteVolume"."Name" = "duplicati-bb8e417fdea554f3bbb920eaadc98a79b.dblock.zip" AND "RemoteVolume"."ID" = "DeletedBlock"."VolumeID")
2023-08-18 22:17:46 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: CREATE TEMPORARY TABLE "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0" AS SELECT DISTINCT "Hash" AS "Hash", "Size" AS "Size" FROM (SELECT "Block"."Hash" AS "Hash", "Block"."Size" AS "Size" FROM "Remotevolume", "Block" WHERE "Remotevolume"."Name" = "duplicati-bb8e417fdea554f3bbb920eaadc98a79b.dblock.zip" AND "Remotevolume"."ID" = "Block"."VolumeID" UNION SELECT "DeletedBlock"."Hash" AS "Hash", "DeletedBlock"."Size" AS "Size" FROM "DeletedBlock", "RemoteVolume" WHERE "RemoteVolume"."Name" = "duplicati-bb8e417fdea554f3bbb920eaadc98a79b.dblock.zip" AND "RemoteVolume"."ID" = "DeletedBlock"."VolumeID") took 0:00:00:00.000
2023-08-18 22:17:46 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: Starting - ExecuteReader: SELECT 1 AS "Type", "Blocklist-8F1C4DC173E13B43A3436188928F2D84"."Hash" AS "Hash" FROM "Blocklist-8F1C4DC173E13B43A3436188928F2D84" WHERE "Blocklist-8F1C4DC173E13B43A3436188928F2D84"."Hash" NOT IN ( SELECT "Hash" FROM "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0" ) UNION SELECT 0 AS "Type", "Hash" AS "Hash" FROM "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0" WHERE "Hash" NOT IN (SELECT "Hash" FROM "Blocklist-8F1C4DC173E13B43A3436188928F2D84") UNION SELECT 2 AS "Type", "E"."Hash" AS "Hash" FROM "Blocklist-8F1C4DC173E13B43A3436188928F2D84" E, "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0" D WHERE "D"."Hash" = "E"."Hash" AND "D"."Size" != "E"."Size"
2023-08-18 22:17:46 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteReader]: ExecuteReader: SELECT 1 AS "Type", "Blocklist-8F1C4DC173E13B43A3436188928F2D84"."Hash" AS "Hash" FROM "Blocklist-8F1C4DC173E13B43A3436188928F2D84" WHERE "Blocklist-8F1C4DC173E13B43A3436188928F2D84"."Hash" NOT IN ( SELECT "Hash" FROM "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0" ) UNION SELECT 0 AS "Type", "Hash" AS "Hash" FROM "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0" WHERE "Hash" NOT IN (SELECT "Hash" FROM "Blocklist-8F1C4DC173E13B43A3436188928F2D84") UNION SELECT 2 AS "Type", "E"."Hash" AS "Hash" FROM "Blocklist-8F1C4DC173E13B43A3436188928F2D84" E, "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0" D WHERE "D"."Hash" = "E"."Hash" AND "D"."Size" != "E"."Size" took 0:00:00:00.001
2023-08-18 22:17:46 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0"
2023-08-18 22:17:46 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "CmpTable-5B6A84CBBB6CCE47988836BE6E0C05B0" took 0:00:00:00.000
2023-08-18 22:17:46 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: DROP TABLE IF EXISTS "Blocklist-8F1C4DC173E13B43A3436188928F2D84"
2023-08-18 22:17:46 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: DROP TABLE IF EXISTS "Blocklist-8F1C4DC173E13B43A3436188928F2D84" took 0:00:00:00.000
2023-08-18 22:17:46 -04 - [Profiling-Timer.Begin-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: Starting - ExecuteNonQuery: UPDATE "RemoteVolume" SET "VerificationCount" = MAX(1, CASE WHEN "VerificationCount" <= 0 THEN (SELECT MAX("VerificationCount") FROM "RemoteVolume") ELSE "VerificationCount" + 1 END) WHERE "Name" = "duplicati-bb8e417fdea554f3bbb920eaadc98a79b.dblock.zip"
2023-08-18 22:17:46 -04 - [Profiling-Timer.Finished-Duplicati.Library.Main.Database.ExtensionMethods-ExecuteNonQuery]: ExecuteNonQuery: UPDATE "RemoteVolume" SET "VerificationCount" = MAX(1, CASE WHEN "VerificationCount" <= 0 THEN (SELECT MAX("VerificationCount") FROM "RemoteVolume") ELSE "VerificationCount" + 1 END) WHERE "Name" = "duplicati-bb8e417fdea554f3bbb920eaadc98a79b.dblock.zip" took 0:00:00:00.138
Profiling log continues to not help hugely, but I suppose a next step is to try to set up network tracing.