"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestCopyFileCopyDest|TestMaxDeleteSize|TestRmdirsNoLeaveRoot)$'" - Starting (try 2/5)
2026/07/15 01:52:59 DEBUG : Creating backend with remote "TestDrime:rclone-test-nevitos7cocu"
2026/07/15 01:52:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/15 01:53:00 DEBUG : Creating backend with remote "/tmp/rclone1634257628"
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "drime root 'rclone-test-nevitos7cocu'", Local "Local file system at /tmp/rclone1634257628", Modify Window "876000h0m0s"
2026/07/15 01:53:00 DEBUG : Creating backend with remote "TestDrime:rclone-test-nevitos7cocu/dst"
2026/07/15 01:53:01 DEBUG : Creating backend with remote "TestDrime:rclone-test-nevitos7cocu/CopyDest"
2026/07/15 01:53:03 DEBUG : one: Need to transfer - File not found at Destination
2026/07/15 01:53:08 DEBUG : one: size = 3 OK
2026/07/15 01:53:08 INFO  : one: Copied (new)
2026/07/15 01:53:12 DEBUG : one: size = 5 (Local file system at /tmp/rclone1634257628)
2026/07/15 01:53:12 DEBUG : one: size = 3 (drime root 'rclone-test-nevitos7cocu/dst')
2026/07/15 01:53:12 DEBUG : one: Sizes differ
2026/07/15 01:53:14 DEBUG : one: Removing old object on successful upload
2026/07/15 01:53:15 DEBUG : one: Old object already deleted, safely ignoring
2026/07/15 01:53:15 DEBUG : one: size = 5 OK
2026/07/15 01:53:15 INFO  : one: Copied (replaced existing)
2026/07/15 01:53:18 DEBUG : dst/one: Removing old object on successful upload
2026/07/15 01:53:19 DEBUG : dst/one: Old object already deleted, safely ignoring
2026/07/15 01:53:24 DEBUG : Creating backend with remote "TestDrime:rclone-test-nevitos7cocu/BackupDir"
2026/07/15 01:53:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone1634257628)
2026/07/15 01:53:27 DEBUG : one: size = 3 (drime root 'rclone-test-nevitos7cocu/dst')
2026/07/15 01:53:27 DEBUG : one: Sizes differ
2026/07/15 01:53:28 DEBUG : one: size = 5 OK
2026/07/15 01:53:28 DEBUG : one: Sizes identical
2026/07/15 01:53:28 DEBUG : one: size = 5 (Local file system at /tmp/rclone1634257628)
2026/07/15 01:53:28 DEBUG : one: size = 3 (drime root 'rclone-test-nevitos7cocu/dst')
2026/07/15 01:53:28 DEBUG : one: Sizes differ
2026/07/15 01:53:31 INFO  : one: Moved (server-side)
2026/07/15 01:53:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 01:53:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/07/15 01:53:33 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 01:53:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/07/15 01:53:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 01:53:34 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/07/15 01:53:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 01:53:35 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/07/15 01:53:36 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 01:53:36 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/07/15 01:53:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 01:53:36 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/07/15 01:53:37 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 01:53:37 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/07/15 01:53:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 01:53:37 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/07/15 01:53:39 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 01:53:39 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/07/15 01:53:42 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 01:53:42 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/07/15 01:53:42 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 0/10
2026/07/15 01:53:47 DEBUG : pacer: Reducing sleep to 5.12s
2026/07/15 01:53:57 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 01:53:57 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/07/15 01:54:02 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 01:54:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/15 01:54:12 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 01:54:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 01:54:52 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 01:55:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 01:55:33 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 01:55:53 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 01:56:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 01:56:32 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 01:56:32 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 1/10
2026/07/15 01:56:52 DEBUG : pacer: Reducing sleep to 10s
2026/07/15 01:57:13 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 01:57:13 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/15 01:57:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 01:57:42 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 01:58:02 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 01:58:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 01:58:43 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 01:59:02 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 01:59:23 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 01:59:42 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 02:00:02 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 02:00:02 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 2/10
2026/07/15 02:00:23 DEBUG : pacer: Reducing sleep to 10s
2026/07/15 02:00:42 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 02:00:42 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/15 02:00:53 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 02:01:13 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 02:01:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 02:01:52 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 02:02:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 02:02:32 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 02:02:52 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 02:03:13 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 02:03:33 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 02:03:33 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 3/10
2026/07/15 02:03:52 DEBUG : pacer: Reducing sleep to 10s
2026/07/15 02:04:13 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 02:04:13 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/15 02:04:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 02:04:43 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 02:05:02 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 02:05:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 02:05:42 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 02:06:02 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 02:06:22 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 02:06:42 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 02:07:03 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 02:07:03 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 4/10
2026/07/15 02:07:23 DEBUG : pacer: Reducing sleep to 10s
2026/07/15 02:07:43 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 02:07:43 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/15 02:07:52 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 02:08:12 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 02:08:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 02:08:52 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 02:09:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 02:09:32 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 02:09:52 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 02:10:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 02:10:32 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 02:10:32 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 5/10
2026/07/15 02:10:52 DEBUG : pacer: Reducing sleep to 10s
2026/07/15 02:11:12 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 02:11:12 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/15 02:11:23 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 02:11:42 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 02:12:02 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 02:12:22 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 02:12:42 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 02:13:02 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 02:13:22 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 02:13:43 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 02:14:02 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 02:14:02 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 6/10
2026/07/15 02:14:22 DEBUG : pacer: Reducing sleep to 10s
2026/07/15 02:14:42 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 02:14:42 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/15 02:14:53 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 02:15:12 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 02:15:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 02:15:52 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 02:16:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 02:16:32 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 02:16:52 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 02:17:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 02:17:32 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 02:17:32 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 7/10
2026/07/15 02:17:52 DEBUG : pacer: Reducing sleep to 10s
2026/07/15 02:18:12 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 02:18:12 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/15 02:18:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 02:18:43 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 02:19:02 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 02:19:22 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 02:19:43 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 02:20:03 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 02:20:22 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 02:20:42 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 02:21:02 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 02:21:02 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 8/10
2026/07/15 02:21:22 DEBUG : pacer: Reducing sleep to 10s
2026/07/15 02:21:42 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/07/15 02:21:42 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/15 02:21:52 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/07/15 02:22:12 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/07/15 02:22:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/07/15 02:22:52 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/07/15 02:23:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/07/15 02:23:32 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/07/15 02:23:53 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/07/15 02:24:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/07/15 02:24:32 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/07/15 02:24:32 DEBUG : one: Received error: failed to copy item: Error "Server Error" - low level retry 9/10
2026/07/15 02:24:32 ERROR : one: Failed to copy: failed to copy item: Error "Server Error"
2026/07/15 02:24:32 ERROR : one: Destination found in --copy-dest, error copying
2026/07/15 02:24:33 INFO  : one: Deleted
2026/07/15 02:24: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 (1899.23s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "drime root 'rclone-test-nevitos7cocu'", Local "Local file system at /tmp/rclone1634257628", Modify Window "876000h0m0s"
2026/07/15 02:24:42 DEBUG : Waiting for deletions to finish
2026/07/15 02:24:43 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2026/07/15 02:24:43 INFO  : large: Deleted
2026/07/15 02:24:43 INFO  : medium: Deleted
    operations_test.go:492: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:492
        	Error:      	Not equal: 
        	            	expected: 1
        	            	actual  : 3
        	Test:       	TestMaxDeleteSize
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/5
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- FAIL: TestMaxDeleteSize (15.36s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "drime root 'rclone-test-nevitos7cocu'", Local "Local file system at /tmp/rclone1634257628", Modify Window "876000h0m0s"
2026/07/15 02:25:01 INFO  : A2: Making directory
2026/07/15 02:25:02 INFO  : A1/B2: Making directory
2026/07/15 02:25:04 INFO  : A1/B2/C2: Making directory
2026/07/15 02:25:06 INFO  : A1/B1/C3: Making directory
2026/07/15 02:25:06 INFO  : A3: Making directory
2026/07/15 02:25:07 INFO  : A3/B3: Making directory
2026/07/15 02:25:08 INFO  : A3/B3/C4: Making directory
2026/07/15 02:25:13 DEBUG : removing 1 level 3 directories
2026/07/15 02:25:13 INFO  : A3/B3/C4: Removing directory
2026/07/15 02:25:18 DEBUG : removing 2 level 3 directories
2026/07/15 02:25:18 INFO  : A1/B2/C2: Removing directory
2026/07/15 02:25:18 INFO  : A1/B1/C3: Removing directory
2026/07/15 02:25:20 DEBUG : removing 2 level 2 directories
2026/07/15 02:25:20 INFO  : A3/B3: Removing directory
2026/07/15 02:25:20 INFO  : A1/B2: Removing directory
2026/07/15 02:25:22 DEBUG : removing 2 level 1 directories
2026/07/15 02:25:22 INFO  : A3: Removing directory
2026/07/15 02:25:22 INFO  : A2: Removing directory
2026/07/15 02:25:32 DEBUG : removing 1 level 3 directories
2026/07/15 02:25:32 INFO  : A1/B1/C1: Removing directory
2026/07/15 02:25:32 DEBUG : removing 1 level 2 directories
2026/07/15 02:25:32 INFO  : A1/B1: Removing directory
2026/07/15 02:25:33 ERROR : A1/B1: Failed to rmdir: directory not empty
2026/07/15 02:25:33 DEBUG : removing 1 level 1 directories
2026/07/15 02:25:33 INFO  : A1: Removing directory
2026/07/15 02:25:33 ERROR : A1: Failed to rmdir: directory not empty
2026/07/15 02:25:33 DEBUG : removing 1 level 0 directories
2026/07/15 02:25:33 INFO  : drime root 'rclone-test-nevitos7cocu': Removing directory
2026/07/15 02:25:33 ERROR : Failed to rmdir: directory not empty
    operations_test.go:728: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:728
        	Error:      	Received unexpected error:
        	            	failed to remove directories: 3 errors: last error: directory not empty
        	Test:       	TestRmdirsNoLeaveRoot
--- FAIL: TestRmdirsNoLeaveRoot (44.12s)
FAIL
2026/07/15 02:25:39 DEBUG : drime root 'rclone-test-nevitos7cocu': Purge remote
"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestCopyFileCopyDest|TestMaxDeleteSize|TestRmdirsNoLeaveRoot)$'" - Finished ERROR in 32m40.268558952s (try 2/5): exit status 1: Failed [TestCopyFileCopyDest TestMaxDeleteSize TestRmdirsNoLeaveRoot]