"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^TestCopyFileCopyDest$'" - Starting (try 4/5)
2026/08/25 06:43:26 DEBUG : Creating backend with remote "TestDrime:rclone-test-tinayoj5xute"
2026/08/25 06:43:26 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/25 06:43:31 DEBUG : Creating backend with remote "/tmp/rclone1519285472"
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "drime root 'rclone-test-tinayoj5xute'", Local "Local file system at /tmp/rclone1519285472", Modify Window "876000h0m0s"
2026/08/25 06:43:31 DEBUG : Creating backend with remote "TestDrime:rclone-test-tinayoj5xute/dst"
2026/08/25 06:43:41 DEBUG : Creating backend with remote "TestDrime:rclone-test-tinayoj5xute/CopyDest"
2026/08/25 06:43:43 DEBUG : one: Need to transfer - File not found at Destination
2026/08/25 06:44:01 DEBUG : one: size = 3 OK
2026/08/25 06:44:01 INFO  : one: Copied (new)
2026/08/25 06:44:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone1519285472)
2026/08/25 06:44:20 DEBUG : one: size = 3 (drime root 'rclone-test-tinayoj5xute/dst')
2026/08/25 06:44:20 DEBUG : one: Sizes differ
2026/08/25 06:44:26 DEBUG : one: Removing old object on successful upload
2026/08/25 06:44:28 DEBUG : one: Old object already deleted, safely ignoring
2026/08/25 06:44:28 DEBUG : one: size = 5 OK
2026/08/25 06:44:28 INFO  : one: Copied (replaced existing)
2026/08/25 06:44:38 DEBUG : dst/one: Removing old object on successful upload
2026/08/25 06:44:41 DEBUG : dst/one: Old object already deleted, safely ignoring
2026/08/25 06:45:01 DEBUG : Creating backend with remote "TestDrime:rclone-test-tinayoj5xute/BackupDir"
2026/08/25 06:45:03 DEBUG : one: size = 5 (Local file system at /tmp/rclone1519285472)
2026/08/25 06:45:03 DEBUG : one: size = 3 (drime root 'rclone-test-tinayoj5xute/dst')
2026/08/25 06:45:03 DEBUG : one: Sizes differ
2026/08/25 06:45:05 DEBUG : one: size = 5 OK
2026/08/25 06:45:05 DEBUG : one: Sizes identical
2026/08/25 06:45:05 DEBUG : one: size = 5 (Local file system at /tmp/rclone1519285472)
2026/08/25 06:45:05 DEBUG : one: size = 3 (drime root 'rclone-test-tinayoj5xute/dst')
2026/08/25 06:45:05 DEBUG : one: Sizes differ
2026/08/25 06:45:09 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): {\"type\":\"https://developers.cloudflare.com/support/troubleshooting/http-status-codes/cloudflare-5xx-errors/error-502/\",\"title\":\"Error 502: Bad gateway\",\"status\":502,\"detail\":\"The origin web server returned an invalid or incomplete response to Cloudflare. This typically indicates the origin is overloaded or misconfigured.\",\"instance\":\"a308a1de4db7c091\",\"error_code\":502,\"error_name\":\"origin_bad_gateway\",\"error_category\":\"origin\",\"ray_id\":\"a308a1de4db7c091\",\"timestamp\":\"2026-08-25T06:45:09Z\",\"zone\":\"app.drime.cloud\",\"cloudflare_error\":true,\"retryable\":true,\"retry_after\":60,\"owner_action_required\":true,\"what_you_should_do\":\"**Wait and retry.** Back off for at least 60 seconds. If the error persists, the website operator should check their origin server health and configuration.\",\"footer\":\"This error was generated by Cloudflare on behalf of the website owner.\"}\n")
2026/08/25 06:45:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/25 06:45:10 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): {\"type\":\"https://developers.cloudflare.com/support/troubleshooting/http-status-codes/cloudflare-5xx-errors/error-502/\",\"title\":\"Error 502: Bad gateway\",\"status\":502,\"detail\":\"The origin web server returned an invalid or incomplete response to Cloudflare. This typically indicates the origin is overloaded or misconfigured.\",\"instance\":\"a308a1e1592fc091\",\"error_code\":502,\"error_name\":\"origin_bad_gateway\",\"error_category\":\"origin\",\"ray_id\":\"a308a1e1592fc091\",\"timestamp\":\"2026-08-25T06:45:10Z\",\"zone\":\"app.drime.cloud\",\"cloudflare_error\":true,\"retryable\":true,\"retry_after\":60,\"owner_action_required\":true,\"what_you_should_do\":\"**Wait and retry.** Back off for at least 60 seconds. If the error persists, the website operator should check their origin server health and configuration.\",\"footer\":\"This error was generated by Cloudflare on behalf of the website owner.\"}\n")
2026/08/25 06:45:10 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/08/25 06:45:10 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): {\"type\":\"https://developers.cloudflare.com/support/troubleshooting/http-status-codes/cloudflare-5xx-errors/error-502/\",\"title\":\"Error 502: Bad gateway\",\"status\":502,\"detail\":\"The origin web server returned an invalid or incomplete response to Cloudflare. This typically indicates the origin is overloaded or misconfigured.\",\"instance\":\"a308a1e1a98ec091\",\"error_code\":502,\"error_name\":\"origin_bad_gateway\",\"error_category\":\"origin\",\"ray_id\":\"a308a1e1a98ec091\",\"timestamp\":\"2026-08-25T06:45:10Z\",\"zone\":\"app.drime.cloud\",\"cloudflare_error\":true,\"retryable\":true,\"retry_after\":60,\"owner_action_required\":true,\"what_you_should_do\":\"**Wait and retry.** Back off for at least 60 seconds. If the error persists, the website operator should check their origin server health and configuration.\",\"footer\":\"This error was generated by Cloudflare on behalf of the website owner.\"}\n")
2026/08/25 06:45:10 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/08/25 06:45:10 DEBUG : pacer: Reducing sleep to 40ms
2026/08/25 06:45:10 INFO  : one: Moved (server-side)
2026/08/25 06:45:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 06:45:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/25 06:45:17 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 06:45:17 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/08/25 06:45:19 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 06:45:19 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/08/25 06:45:23 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 06:45:23 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/08/25 06:45:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 06:45:23 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/08/25 06:45:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 06:45:24 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/08/25 06:45:25 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 06:45:25 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/08/25 06:45:26 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 06:45:26 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/08/25 06:45:27 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 06:45:27 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/08/25 06:45:31 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 06:45:31 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/08/25 06:45:31 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 0/10
2026/08/25 06:45:36 DEBUG : pacer: Reducing sleep to 5.12s
2026/08/25 06:45:49 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 06:45:49 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/08/25 06:45:52 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 06:45:52 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/08/25 06:46:03 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 06:46:23 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 06:46:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 06:47:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 06:47:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 06:47:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 06:48:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 06:48:22 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 06:48:22 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 1/10
2026/08/25 06:48:41 DEBUG : pacer: Reducing sleep to 10s
2026/08/25 06:49:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 06:49:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/08/25 06:49:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 06:49:31 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 06:49:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 06:50:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 06:50:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 06:50:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 06:51:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 06:51:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 06:51:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 06:51:52 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 2/10
2026/08/25 06:52:11 DEBUG : pacer: Reducing sleep to 10s
2026/08/25 06:52:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 06:52:31 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/08/25 06:52:42 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 06:53:03 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 06:53:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 06:53:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 06:54:03 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 06:54:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 06:54:43 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 06:55:03 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 06:55:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 06:55:21 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 3/10
2026/08/25 06:55:42 DEBUG : pacer: Reducing sleep to 10s
2026/08/25 06:56:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 06:56:04 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/08/25 06:56:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 06:56:31 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 06:56:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 06:57:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 06:57:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 06:57:54 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 06:58:13 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 06:58:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 06:58:53 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 06:58:53 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 4/10
2026/08/25 06:59:11 DEBUG : pacer: Reducing sleep to 10s
2026/08/25 06:59:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 06:59:31 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/08/25 06:59:43 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 07:00:03 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 07:00:22 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 07:00:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 07:01:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 07:01:24 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 07:01:43 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 07:02:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 07:02:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 07:02:21 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 5/10
2026/08/25 07:02:42 DEBUG : pacer: Reducing sleep to 10s
2026/08/25 07:03:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 07:03:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/08/25 07:03:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 07:03:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 07:03:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 07:04:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 07:04:33 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 07:04:52 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 07:05:12 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 07:05:34 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 07:05:53 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 07:05:53 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 6/10
2026/08/25 07:06:13 DEBUG : pacer: Reducing sleep to 10s
2026/08/25 07:06:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 07:06:32 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/08/25 07:06:43 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 07:07:02 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 07:07:22 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 07:07:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 07:08:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 07:08:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 07:08:43 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 07:09:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 07:09:24 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 07:09:24 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 7/10
2026/08/25 07:09:43 DEBUG : pacer: Reducing sleep to 10s
2026/08/25 07:10:01 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 07:10:01 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/08/25 07:10:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 07:10:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 07:10:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 07:11:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 07:11:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 07:11:53 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 07:12:14 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 07:12:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 07:12:54 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 07:12:54 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 8/10
2026/08/25 07:13:11 DEBUG : pacer: Reducing sleep to 10s
2026/08/25 07:13:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/25 07:13:32 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/08/25 07:13:43 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/25 07:14:03 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/08/25 07:14:22 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/08/25 07:14:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/08/25 07:15:03 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/08/25 07:15:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/08/25 07:15:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/08/25 07:16:03 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/08/25 07:16:22 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/08/25 07:16:22 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 9/10
2026/08/25 07:16:22 ERROR : one: Failed to copy: failed to copy item: Error "Server Error"
2026/08/25 07:16:22 ERROR : one: Destination found in --copy-dest, error copying
2026/08/25 07:16:25 DEBUG : pacer: Reducing sleep to 20ms
2026/08/25 07:16:29 DEBUG : pacer: Reducing sleep to 10ms
2026/08/25 07:16:29 INFO  : one: Deleted
2026/08/25 07:16:33 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 (2009.37s)
FAIL
2026/08/25 07:17:00 DEBUG : drime root 'rclone-test-tinayoj5xute': Purge remote
"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^TestCopyFileCopyDest$'" - Finished ERROR in 33m35.607962036s (try 4/5): exit status 1: Failed [TestCopyFileCopyDest]