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