"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestCopyFileCopyDest|TestMaxDeleteSize|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter)$'" - Starting (try 2/5)
2026/07/16 02:58:08 DEBUG : Creating backend with remote "TestDrime:rclone-test-hulasod4vugo"
2026/07/16 02:58:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/16 02:58:09 DEBUG : Creating backend with remote "/tmp/rclone2160191422"
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "drime root 'rclone-test-hulasod4vugo'", Local "Local file system at /tmp/rclone2160191422", Modify Window "876000h0m0s"
2026/07/16 02:58:09 DEBUG : Creating backend with remote "TestDrime:rclone-test-hulasod4vugo/dst"
2026/07/16 02:58:10 DEBUG : Creating backend with remote "TestDrime:rclone-test-hulasod4vugo/CopyDest"
2026/07/16 02:58:12 DEBUG : one: Need to transfer - File not found at Destination
2026/07/16 02:58:15 DEBUG : one: size = 3 OK
2026/07/16 02:58:15 INFO  : one: Copied (new)
2026/07/16 02:58:18 DEBUG : one: size = 5 (Local file system at /tmp/rclone2160191422)
2026/07/16 02:58:18 DEBUG : one: size = 3 (drime root 'rclone-test-hulasod4vugo/dst')
2026/07/16 02:58:18 DEBUG : one: Sizes differ
2026/07/16 02:58:19 DEBUG : one: Removing old object on successful upload
2026/07/16 02:58:19 DEBUG : one: Old object already deleted, safely ignoring
2026/07/16 02:58:19 DEBUG : one: size = 5 OK
2026/07/16 02:58:19 INFO  : one: Copied (replaced existing)
2026/07/16 02:58:20 DEBUG : dst/one: Removing old object on successful upload
2026/07/16 02:58:20 DEBUG : dst/one: Old object already deleted, safely ignoring
2026/07/16 02:58:21 DEBUG : Creating backend with remote "TestDrime:rclone-test-hulasod4vugo/BackupDir"
2026/07/16 02:58:22 DEBUG : one: size = 5 (Local file system at /tmp/rclone2160191422)
2026/07/16 02:58:22 DEBUG : one: size = 3 (drime root 'rclone-test-hulasod4vugo/dst')
2026/07/16 02:58:22 DEBUG : one: Sizes differ
2026/07/16 02:58:22 DEBUG : one: size = 5 OK
2026/07/16 02:58:22 DEBUG : one: Sizes identical
2026/07/16 02:58:22 DEBUG : one: size = 5 (Local file system at /tmp/rclone2160191422)
2026/07/16 02:58:22 DEBUG : one: size = 3 (drime root 'rclone-test-hulasod4vugo/dst')
2026/07/16 02:58:22 DEBUG : one: Sizes differ
2026/07/16 02:58:23 INFO  : one: Moved (server-side)
2026/07/16 02:58:24 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 02:58:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/07/16 02:58:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 02:58:24 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/07/16 02:58:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 02:58:24 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/07/16 02:58:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 02:58:24 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/07/16 02:58:25 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 02:58:25 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/07/16 02:58:25 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 02:58:25 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/07/16 02:58:26 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 02:58:26 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/07/16 02:58:27 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 02:58:27 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/07/16 02:58:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 02:58:28 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/07/16 02:58:30 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 02:58:30 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/07/16 02:58:30 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 0/10
2026/07/16 02:58:36 DEBUG : pacer: Reducing sleep to 5.12s
2026/07/16 02:58:45 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 02:58:45 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/07/16 02:58:51 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 02:58:51 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/16 02:59:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 02:59:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 02:59:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 03:00:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 03:00:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 03:00:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 03:01:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 03:01:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 03:01:21 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 1/10
2026/07/16 03:01:41 DEBUG : pacer: Reducing sleep to 10s
2026/07/16 03:02:01 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 03:02:01 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/16 03:02:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 03:02:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 03:02:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 03:03:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 03:03:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 03:03:52 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 03:04:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 03:04:31 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 03:04:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 03:04:52 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 2/10
2026/07/16 03:05:12 DEBUG : pacer: Reducing sleep to 10s
2026/07/16 03:05:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 03:05:32 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/16 03:05:42 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 03:06:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 03:06:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 03:06:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 03:07:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 03:07:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 03:07:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 03:08:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 03:08:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 03:08:21 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 3/10
2026/07/16 03:08:41 DEBUG : pacer: Reducing sleep to 10s
2026/07/16 03:09:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 03:09:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/16 03:09:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 03:09:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 03:09:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 03:10:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 03:10:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 03:10:52 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 03:11:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 03:11:31 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 03:11:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 03:11:52 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 4/10
2026/07/16 03:12:11 DEBUG : pacer: Reducing sleep to 10s
2026/07/16 03:12:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 03:12:31 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/16 03:12:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 03:13:02 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 03:13:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 03:13:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 03:14:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 03:14:22 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 03:14:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 03:15:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 03:15:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 03:15:21 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 5/10
2026/07/16 03:15:42 DEBUG : pacer: Reducing sleep to 10s
2026/07/16 03:16:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 03:16:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/16 03:16:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 03:16:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 03:16:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 03:17:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 03:17:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 03:17:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 03:18:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 03:18:33 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 03:18:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 03:18:52 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 6/10
2026/07/16 03:19:11 DEBUG : pacer: Reducing sleep to 10s
2026/07/16 03:19:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 03:19:31 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/16 03:19:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 03:20:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 03:20:22 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 03:20:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 03:21:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 03:21:22 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 03:21:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 03:22:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 03:22:22 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 03:22:22 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 7/10
2026/07/16 03:22:41 DEBUG : pacer: Reducing sleep to 10s
2026/07/16 03:23:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 03:23:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/16 03:23:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 03:23:31 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 03:23:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 03:24:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 03:24:32 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 03:24:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 03:25:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 03:25:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 03:25:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 03:25:52 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 8/10
2026/07/16 03:26:11 DEBUG : pacer: Reducing sleep to 10s
2026/07/16 03:26:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/16 03:26:31 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/16 03:26:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/16 03:27:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/16 03:27:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/16 03:27:42 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/16 03:28:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/16 03:28:22 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/16 03:28:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/16 03:29:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/16 03:29:22 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/16 03:29:22 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 9/10
2026/07/16 03:29:22 ERROR : one: Failed to copy: failed to copy item: Error "Server Error"
2026/07/16 03:29:22 ERROR : one: Destination found in --copy-dest, error copying
2026/07/16 03:29:24 INFO  : one: Deleted
2026/07/16 03:29:24 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 (1889.72s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "drime root 'rclone-test-hulasod4vugo'", Local "Local file system at /tmp/rclone2160191422", Modify Window "876000h0m0s"
2026/07/16 03:29:47 DEBUG : Waiting for deletions to finish
2026/07/16 03:29:48 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/07/16 03:29:48 INFO  : small: Deleted
2026/07/16 03:29:48 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSize (12.70s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "drime root 'rclone-test-hulasod4vugo'", Local "Local file system at /tmp/rclone2160191422", Modify Window "876000h0m0s"
2026/07/16 03:29:55 INFO  : A2: Making directory
2026/07/16 03:29:57 INFO  : A1/B2: Making directory
2026/07/16 03:29:58 INFO  : A1/B2/C2: Making directory
2026/07/16 03:29:58 INFO  : A1/B1/C3: Making directory
2026/07/16 03:29:59 INFO  : A3: Making directory
2026/07/16 03:29:59 INFO  : A3/B3: Making directory
2026/07/16 03:29:59 INFO  : A3/B3/C4: Making directory
2026/07/16 03:30:02 DEBUG : removing 1 level 3 directories
2026/07/16 03:30:02 INFO  : A3/B3/C4: Removing directory
2026/07/16 03:30:15 DEBUG : removing 2 level 3 directories
2026/07/16 03:30:15 INFO  : A1/B2/C2: Removing directory
2026/07/16 03:30:15 INFO  : A1/B1/C3: Removing directory
2026/07/16 03:30:17 DEBUG : removing 2 level 2 directories
2026/07/16 03:30:17 INFO  : A3/B3: Removing directory
2026/07/16 03:30:17 INFO  : A1/B2: Removing directory
2026/07/16 03:30:19 DEBUG : removing 2 level 1 directories
2026/07/16 03:30:19 INFO  : A3: Removing directory
2026/07/16 03:30:19 INFO  : A2: Removing directory
2026/07/16 03:30:26 DEBUG : removing 1 level 3 directories
2026/07/16 03:30:26 INFO  : A1/B1/C1: Removing directory
2026/07/16 03:30:28 DEBUG : removing 1 level 2 directories
2026/07/16 03:30:28 INFO  : A1/B1: Removing directory
2026/07/16 03:30:30 DEBUG : removing 1 level 1 directories
2026/07/16 03:30:30 INFO  : A1: Removing directory
2026/07/16 03:30:33 DEBUG : removing 1 level 0 directories
2026/07/16 03:30:33 INFO  : drime root 'rclone-test-hulasod4vugo': Removing directory
2026/07/16 03:30:36 ERROR : error listing: directory not found
2026/07/16 03:30:37 ERROR : error listing: directory not found
--- PASS: TestRmdirsNoLeaveRoot (45.49s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "drime root 'rclone-test-hulasod4vugo'", Local "Local file system at /tmp/rclone2160191422", Modify Window "876000h0m0s"
2026/07/16 03:30:38 INFO  : A1: Making directory
2026/07/16 03:30:41 INFO  : A1/B1: Making directory
2026/07/16 03:30:44 INFO  : A1/B1/C1: Making directory
2026/07/16 03:30:54 DEBUG : removing 1 level 3 directories
2026/07/16 03:30:54 INFO  : A1/B1/C1: Removing directory
2026/07/16 03:30:56 DEBUG : removing 1 level 2 directories
2026/07/16 03:30:56 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (28.28s)
FAIL
2026/07/16 03:31:05 DEBUG : drime root 'rclone-test-hulasod4vugo': Purge remote
"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestCopyFileCopyDest|TestMaxDeleteSize|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter)$'" - Finished ERROR in 32m57.921592285s (try 2/5): exit status 1: Failed [TestCopyFileCopyDest]