"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^TestCopyFileCopyDest$'" - Starting (try 3/5)
2026/06/25 06:10:02 DEBUG : Creating backend with remote "TestDrime:rclone-test-dihimoz1qopa"
2026/06/25 06:10:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/06/25 06:10:07 DEBUG : Creating backend with remote "/tmp/rclone3635255842"
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "drime root 'rclone-test-dihimoz1qopa'", Local "Local file system at /tmp/rclone3635255842", Modify Window "876000h0m0s"
2026/06/25 06:10:07 DEBUG : Creating backend with remote "TestDrime:rclone-test-dihimoz1qopa/dst"
2026/06/25 06:10:13 DEBUG : Creating backend with remote "TestDrime:rclone-test-dihimoz1qopa/CopyDest"
2026/06/25 06:10:17 DEBUG : one: Need to transfer - File not found at Destination
2026/06/25 06:10:32 DEBUG : one: size = 3 OK
2026/06/25 06:10:32 INFO  : one: Copied (new)
2026/06/25 06:10:44 DEBUG : one: size = 5 (Local file system at /tmp/rclone3635255842)
2026/06/25 06:10:44 DEBUG : one: size = 3 (drime root 'rclone-test-dihimoz1qopa/dst')
2026/06/25 06:10:44 DEBUG : one: Sizes differ
2026/06/25 06:10:51 DEBUG : one: Removing old object on successful upload
2026/06/25 06:10:53 DEBUG : one: Old object already deleted, safely ignoring
2026/06/25 06:10:53 DEBUG : one: size = 5 OK
2026/06/25 06:10:53 INFO  : one: Copied (replaced existing)
2026/06/25 06:11:02 DEBUG : dst/one: Removing old object on successful upload
2026/06/25 06:11:04 DEBUG : dst/one: Old object already deleted, safely ignoring
2026/06/25 06:11:19 DEBUG : Creating backend with remote "TestDrime:rclone-test-dihimoz1qopa/BackupDir"
2026/06/25 06:11:28 DEBUG : one: size = 5 (Local file system at /tmp/rclone3635255842)
2026/06/25 06:11:28 DEBUG : one: size = 3 (drime root 'rclone-test-dihimoz1qopa/dst')
2026/06/25 06:11:28 DEBUG : one: Sizes differ
2026/06/25 06:11:32 DEBUG : one: size = 5 OK
2026/06/25 06:11:32 DEBUG : one: Sizes identical
2026/06/25 06:11:32 DEBUG : one: size = 5 (Local file system at /tmp/rclone3635255842)
2026/06/25 06:11:32 DEBUG : one: size = 3 (drime root 'rclone-test-dihimoz1qopa/dst')
2026/06/25 06:11:32 DEBUG : one: Sizes differ
2026/06/25 06:12:01 INFO  : one: Moved (server-side)
2026/06/25 06:12:05 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:12:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/06/25 06:12:08 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:12:08 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/06/25 06:12:09 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:12:09 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/06/25 06:12:12 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:12:12 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/06/25 06:12:14 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:12:14 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/06/25 06:12:16 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:12:16 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/06/25 06:12:18 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:12:18 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/06/25 06:12:20 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:12:20 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/06/25 06:12:22 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/06/25 06:12:22 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/06/25 06:12:24 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:12:24 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/06/25 06:12:24 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 0/10
2026/06/25 06:12:29 DEBUG : pacer: Reducing sleep to 5.12s
2026/06/25 06:12:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:12:40 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/06/25 06:12:45 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:12:45 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/25 06:12:55 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:13:16 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:13:35 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:13:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:14:15 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:14:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:14:55 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/06/25 06:15:15 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:15:15 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 1/10
2026/06/25 06:15:35 DEBUG : pacer: Reducing sleep to 10s
2026/06/25 06:15:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:15:55 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/25 06:16:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:16:25 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:16:46 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:17:05 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:17:25 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:17:45 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:18:05 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:18:25 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/06/25 06:18:45 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:18:45 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 2/10
2026/06/25 06:19:05 DEBUG : pacer: Reducing sleep to 10s
2026/06/25 06:19:25 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:19:25 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/25 06:19:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:19:55 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:20:15 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:20:35 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:20:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:21:15 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:21:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:21:55 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/06/25 06:22:15 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:22:15 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 3/10
2026/06/25 06:22:35 DEBUG : pacer: Reducing sleep to 10s
2026/06/25 06:22:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:22:55 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/25 06:23:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:23:25 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:23:45 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:24:05 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:24:25 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:24:45 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:25:05 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:25:25 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/06/25 06:25:45 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:25:45 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 4/10
2026/06/25 06:26:05 DEBUG : pacer: Reducing sleep to 10s
2026/06/25 06:26:25 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:26:25 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/25 06:26:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:26:55 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:27:15 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:27:36 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:27:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:28:15 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:28:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:28:55 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/06/25 06:29:15 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:29:15 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 5/10
2026/06/25 06:29:35 DEBUG : pacer: Reducing sleep to 10s
2026/06/25 06:29:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:29:55 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/25 06:30:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:30:25 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:30:45 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:31:06 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:31:25 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:31:45 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:32:05 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:32:25 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/06/25 06:32:45 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:32:45 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 6/10
2026/06/25 06:33:05 DEBUG : pacer: Reducing sleep to 10s
2026/06/25 06:33:25 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:33:25 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/25 06:33:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:33:55 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:34:15 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:34:35 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:34:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:35:15 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:35:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:35:55 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/06/25 06:36:16 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:36:16 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 7/10
2026/06/25 06:36:36 DEBUG : pacer: Reducing sleep to 10s
2026/06/25 06:36:57 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:36:57 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/25 06:37:06 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:37:25 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:37:45 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:38:05 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:38:26 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:38:46 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:39:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:40:23 DEBUG : pacer: low level retry 9/10 (error Error "504 Gateway Timeout (504): {\"type\":\"https://developers.cloudflare.com/support/troubleshooting/http-status-codes/cloudflare-5xx-errors/error-504/\",\"title\":\"Error 504: Gateway time-out\",\"status\":504,\"detail\":\"The origin web server did not respond to Cloudflare within the allowed time. This typically means the origin is overloaded or a backend process is taking too long.\",\"instance\":\"a111f98c2887e8db\",\"error_code\":504,\"error_name\":\"origin_gateway_timeout\",\"error_category\":\"origin\",\"ray_id\":\"a111f98c2887e8db\",\"timestamp\":\"2026-06-25T06:40:23Z\",\"zone\":\"app.drime.cloud\",\"cloudflare_error\":true,\"retryable\":true,\"retry_after\":120,\"owner_action_required\":true,\"what_you_should_do\":\"**Wait and retry.** Back off for at least 120 seconds. If the error persists, the website operator should investigate long-running processes on the origin server.\",\"footer\":\"This error was generated by Cloudflare on behalf of the website owner.\"}\n")
2026/06/25 06:40:28 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:40:28 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 8/10
2026/06/25 06:40:48 DEBUG : pacer: Reducing sleep to 10s
2026/06/25 06:41:09 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/06/25 06:41:09 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/25 06:41:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/06/25 06:41:37 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/06/25 06:41:56 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/06/25 06:42:17 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/06/25 06:42:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/06/25 06:42:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/06/25 06:43:16 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/06/25 06:43:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/06/25 06:43:56 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/06/25 06:43:56 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 9/10
2026/06/25 06:43:56 ERROR : one: Failed to copy: failed to copy item: Error "Server Error"
2026/06/25 06:43:56 ERROR : one: Destination found in --copy-dest, error copying
2026/06/25 06:44:01 INFO  : one: Deleted
2026/06/25 06:44:04 ERROR : one: Couldn't move: failed to move item: Error "No valid entries to move"
    copy_test.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:321
        	Error:      	Received unexpected error:
        	            	moving to --backup-dir failed: failed to move item: Error "No valid entries to move"
        	Test:       	TestCopyFileCopyDest
--- FAIL: TestCopyFileCopyDest (2064.24s)
FAIL
2026/06/25 06:44:31 DEBUG : drime root 'rclone-test-dihimoz1qopa': Purge remote
"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^TestCopyFileCopyDest$'" - Finished ERROR in 34m31.81049986s (try 3/5): exit status 1: Failed [TestCopyFileCopyDest]