"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCopyAfterDelete|TestCopyDeleteBefore|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyWithFilesFromAndNoTraverse|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterAddingAFile|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncDeleteDuring|TestSyncEmptyDirectories|TestSyncIgnoreErrors|TestSyncWithTrackRenamesStrategyLeaf)$'" - Starting (try 5/5)
2026/02/03 07:26:32 DEBUG : Creating backend with remote "TestDrime:rclone-test-cifinad8hofu"
2026/02/03 07:26:32 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 07:26:33 DEBUG : Creating backend with remote "/tmp/rclone2024420840"
=== RUN TestCopyNoTraverse
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:26:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/03 07:26:33 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:26:33 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:26:37 DEBUG : sub dir/hello world: size = 11 OK
2026/02/03 07:26:37 INFO : sub dir/hello world: Copied (new)
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestCopyNoTraverse
Messages: directories
--- FAIL: TestCopyNoTraverse (19.74s)
=== RUN TestCopyWithFilesFromAndNoTraverse
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:26:53 DEBUG : potato2: Need to transfer - File not found at Destination
2026/02/03 07:26:53 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:26:53 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:26:56 DEBUG : potato2: size = 11 OK
2026/02/03 07:26:56 INFO : potato2: Copied (new)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestCopyWithFilesFromAndNoTraverse (22.56s)
=== RUN TestCopyEmptyDirectories
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:27:15 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2026/02/03 07:27:15 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/02/03 07:27:15 INFO : sub dir2: Set directory modification time (using DirSetModTime)
2026/02/03 07:27:15 INFO : sub dir: Set directory modification time (using DirSetModTime)
2026/02/03 07:27:15 INFO : sub dir2: Making directory
2026/02/03 07:27:17 INFO : sub dir: Making directory
2026/02/03 07:27:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/03 07:27:17 INFO : sub dir2/sub sub dir2: Making directory
2026/02/03 07:27:18 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:27:18 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:27:21 DEBUG : sub dir/hello world: size = 11 OK
2026/02/03 07:27:21 INFO : sub dir/hello world: Copied (new)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 07:27:35 DEBUG : drime root 'rclone-test-cifinad8hofu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
run.go:130: removing dir "sub dir2" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir2" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir2" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir2" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir2"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=8) "sub dir2"
}
Test: TestCopyEmptyDirectories
Messages: directories
--- FAIL: TestCopyEmptyDirectories (37.70s)
=== RUN TestCopyNoEmptyDirectories
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:27:53 INFO : sub dir2: Making directory
2026/02/03 07:27:53 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2026/02/03 07:27:53 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/02/03 07:27:53 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/03 07:27:54 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:27:54 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:27:57 DEBUG : sub dir/hello world: size = 11 OK
2026/02/03 07:27:57 INFO : sub dir/hello world: Copied (new)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:388
Error: Not equal:
expected: []string{"sub dir"}
actual : []string{"sub dir", "sub dir2"}
Diff:
--- Expected
+++ Actual
@@ -1,3 +1,4 @@
-([]string) (len=1) {
- (string) (len=7) "sub dir"
+([]string) (len=2) {
+ (string) (len=7) "sub dir",
+ (string) (len=8) "sub dir2"
}
Test: TestCopyNoEmptyDirectories
Messages: directories
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
--- FAIL: TestCopyNoEmptyDirectories (18.46s)
=== RUN TestSyncEmptyDirectories
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:28:11 DEBUG : sub dir2: Making directory with metadata
2026/02/03 07:28:11 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/02/03 07:28:11 INFO : sub dir: Set directory modification time (using DirSetModTime)
2026/02/03 07:28:12 INFO : sub dir2: Making directory
2026/02/03 07:28:12 INFO : sub dir: Making directory
2026/02/03 07:28:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/03 07:28:13 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:28:13 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:28:16 DEBUG : sub dir/hello world: size = 11 OK
2026/02/03 07:28:16 INFO : sub dir/hello world: Copied (new)
2026/02/03 07:28:16 DEBUG : Waiting for deletions to finish
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:516
Error: Should be true
Test: TestSyncEmptyDirectories
Messages: listing wrong, want
sub dir/hello world (11) got
fstest.go:203: Not found "sub dir/hello world"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:516
Error: Not equal:
expected: 0
actual : 1
Test: TestSyncEmptyDirectories
Messages: 1 objects not found
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:516
Error: Not equal:
expected: []string{"sub dir", "sub dir2"}
actual : []string{}
Diff:
--- Expected
+++ Actual
@@ -1,4 +1,2 @@
-([]string) (len=2) {
- (string) (len=7) "sub dir",
- (string) (len=8) "sub dir2"
+([]string) {
}
Test: TestSyncEmptyDirectories
Messages: directories
2026/02/03 07:28:25 DEBUG : drime root 'rclone-test-cifinad8hofu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestSyncEmptyDirectories
Messages: directories
--- FAIL: TestSyncEmptyDirectories (31.67s)
=== RUN TestServerSideCopy
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:28:49 DEBUG : Creating backend with remote "TestDrime:rclone-test-kadavif7wopu"
sync_test.go:620: Server side copy (if possible) drime root 'rclone-test-cifinad8hofu' -> drime root 'rclone-test-kadavif7wopu'
2026/02/03 07:28:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/03 07:28:51 DEBUG : drime root 'rclone-test-kadavif7wopu': Waiting for checks to finish
2026/02/03 07:28:51 DEBUG : drime root 'rclone-test-kadavif7wopu': Waiting for transfers to finish
2026/02/03 07:28:55 DEBUG : sub dir/hello world: size = 11 OK
2026/02/03 07:28:55 INFO : sub dir/hello world: Copied (server-side copy)
2026/02/03 07:28:55 DEBUG : drime root 'rclone-test-kadavif7wopu': Purge remote
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
--- PASS: TestServerSideCopy (17.25s)
=== RUN TestCopyOverSelf
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:635
Error: Should be true
Test: TestCopyOverSelf
Messages: listing wrong, want
sub dir/hello world (11) got
fstest.go:203: Not found "sub dir/hello world"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:635
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestCopyOverSelf
Messages: 1 objects not found
2026/02/03 07:29:14 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:29:14 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone2024420840)
2026/02/03 07:29:14 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-cifinad8hofu')
2026/02/03 07:29:14 DEBUG : sub dir/hello world: Sizes differ
2026/02/03 07:29:14 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:29:16 DEBUG : sub dir/hello world: Removing old object on successful upload
2026/02/03 07:29:17 DEBUG : sub dir/hello world: size = 17 OK
2026/02/03 07:29:17 INFO : sub dir/hello world: Copied (replaced existing)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:643
Error: Should be true
Test: TestCopyOverSelf
Messages: listing wrong, want
sub dir/hello world (17) got
sub dir/hello world (11)
fstest.go:150:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:643
Error: Not equal:
expected: 17
actual : 11
Test: TestCopyOverSelf
Messages: sub dir/hello world: size incorrect file=17 vs obj=11
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:29:38 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:29:38 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:29:38 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:29:38 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestCopyOverSelf
Messages: directories
--- FAIL: TestCopyOverSelf (42.76s)
=== RUN TestServerSideCopyOverSelf
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
2026/02/03 07:29:56 DEBUG : Creating backend with remote "TestDrime:rclone-test-jajivib7dini"
sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-cifinad8hofu' -> drime root 'rclone-test-jajivib7dini'
2026/02/03 07:29:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/03 07:29:57 DEBUG : drime root 'rclone-test-jajivib7dini': Waiting for checks to finish
2026/02/03 07:29:57 DEBUG : drime root 'rclone-test-jajivib7dini': Waiting for transfers to finish
2026/02/03 07:30:00 DEBUG : sub dir/hello world: size = 11 OK
2026/02/03 07:30:00 INFO : sub dir/hello world: Copied (server-side copy)
2026/02/03 07:30:05 DEBUG : sub dir/hello world: Removing old object on successful upload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 07:30:20 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-cifinad8hofu')
2026/02/03 07:30:20 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-jajivib7dini')
2026/02/03 07:30:20 DEBUG : sub dir/hello world: Sizes differ
2026/02/03 07:30:20 DEBUG : drime root 'rclone-test-jajivib7dini': Waiting for checks to finish
2026/02/03 07:30:20 DEBUG : drime root 'rclone-test-jajivib7dini': Waiting for transfers to finish
2026/02/03 07:30:22 DEBUG : sub dir/hello world: Server side copy: removing existing object after successful copy
2026/02/03 07:30:22 DEBUG : sub dir/hello world: size = 17 OK
2026/02/03 07:30:22 INFO : sub dir/hello world: Copied (server-side copy)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:671
Error: Should be true
Test: TestServerSideCopyOverSelf
Messages: listing wrong, want
sub dir/hello world (17) got
sub dir/hello world (11)
fstest.go:150:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:671
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 17
actual : 11
Test: TestServerSideCopyOverSelf
Messages: sub dir/hello world: size incorrect file=17 vs obj=11
2026/02/03 07:30:33 DEBUG : drime root 'rclone-test-jajivib7dini': Purge remote
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestServerSideCopyOverSelf
Messages: directories
--- FAIL: TestServerSideCopyOverSelf (66.55s)
=== RUN TestServerSideMoveOverSelf
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
2026/02/03 07:31:03 DEBUG : Creating backend with remote "TestDrime:rclone-test-haxuneb6qeva"
sync_test.go:701: Server side copy (if possible) drime root 'rclone-test-cifinad8hofu' -> drime root 'rclone-test-haxuneb6qeva'
2026/02/03 07:31:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/03 07:31:04 DEBUG : drime root 'rclone-test-haxuneb6qeva': Waiting for checks to finish
2026/02/03 07:31:04 DEBUG : drime root 'rclone-test-haxuneb6qeva': Waiting for transfers to finish
2026/02/03 07:31:07 DEBUG : sub dir/hello world: size = 11 OK
2026/02/03 07:31:07 INFO : sub dir/hello world: Copied (server-side copy)
2026/02/03 07:31:12 DEBUG : sub dir/hello world: Removing old object on successful upload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 07:31:25 DEBUG : drime root 'rclone-test-haxuneb6qeva': Using server-side directory move
2026/02/03 07:31:25 INFO : drime root 'rclone-test-haxuneb6qeva': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2026/02/03 07:31:26 DEBUG : drime root 'rclone-test-haxuneb6qeva': Waiting for checks to finish
2026/02/03 07:31:26 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-cifinad8hofu')
2026/02/03 07:31:26 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-haxuneb6qeva')
2026/02/03 07:31:26 DEBUG : sub dir/hello world: Sizes differ
2026/02/03 07:31:26 DEBUG : drime root 'rclone-test-haxuneb6qeva': Waiting for transfers to finish
2026/02/03 07:31:26 INFO : sub dir/hello world: Deleted
2026/02/03 07:31:27 ERROR : sub dir/hello world: Couldn't move: object not found
sync_test.go:714:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:714
Error: Received unexpected error:
object not found
Test: TestServerSideMoveOverSelf
2026/02/03 07:31:27 DEBUG : drime root 'rclone-test-haxuneb6qeva': Purge remote
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
/usr/local/go/src/runtime/panic.go:636
/usr/local/go/src/testing/testing.go:1041
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:714
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestServerSideMoveOverSelf
Messages: directories
--- FAIL: TestServerSideMoveOverSelf (49.61s)
=== RUN TestCopyAfterDelete
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:31:45 ERROR : error listing: directory not found
2026/02/03 07:31:45 INFO : Local file system at /tmp/rclone2024420840: Making directory
2026/02/03 07:31:46 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:31:46 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestCopyAfterDelete
Messages: directories
--- FAIL: TestCopyAfterDelete (23.53s)
=== RUN TestSyncIgnoreErrors
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:32:12 INFO : d: Making directory
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:964
Error: Not equal:
expected: []string{"b", "c", "d"}
actual : []string{"b", "c", "d", "sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,5 +1,6 @@
-([]string) (len=3) {
+([]string) (len=4) {
(string) (len=1) "b",
(string) (len=1) "c",
- (string) (len=1) "d"
+ (string) (len=1) "d",
+ (string) (len=7) "sub dir"
}
Test: TestSyncIgnoreErrors
Messages: directories
2026/02/03 07:32:24 DEBUG : a/potato2: Need to transfer - File not found at Destination
2026/02/03 07:32:24 DEBUG : c/non empty space: size = 5 OK
2026/02/03 07:32:24 DEBUG : c/non empty space: Sizes identical
2026/02/03 07:32:24 DEBUG : c/non empty space: Unchanged skipping
2026/02/03 07:32:24 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:32:24 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:32:28 DEBUG : a/potato2: size = 60 OK
2026/02/03 07:32:28 INFO : a/potato2: Copied (new)
2026/02/03 07:32:28 DEBUG : Waiting for deletions to finish
2026/02/03 07:32:29 INFO : b/potato: Deleted
2026/02/03 07:32:29 INFO : sub dir: Removing directory
2026/02/03 07:32:30 INFO : d: Removing directory
2026/02/03 07:32:30 INFO : b: Removing directory
2026/02/03 07:32:31 DEBUG : b: Failed to Rmdir: directory not empty
2026/02/03 07:32:31 DEBUG : drime root 'rclone-test-cifinad8hofu': failed to delete 1 directories
2026/02/03 07:32:31 DEBUG : drime root 'rclone-test-cifinad8hofu': deleted 2 directories
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:994
Error: Not equal:
expected: []string{"a", "c"}
actual : []string{"a", "b", "c"}
Diff:
--- Expected
+++ Actual
@@ -1,3 +1,4 @@
-([]string) (len=2) {
+([]string) (len=3) {
(string) (len=1) "a",
+ (string) (len=1) "b",
(string) (len=1) "c"
Test: TestSyncIgnoreErrors
Messages: directories
run.go:130: removing dir "c" failed - try 1/3: directory not empty
run.go:130: removing dir "c" failed - try 2/3: directory not empty
run.go:130: removing dir "c" failed - try 3/3: directory not empty
run.go:133: removing dir "c" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"c"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=1) "c"
}
Test: TestSyncIgnoreErrors
Messages: directories
--- FAIL: TestSyncIgnoreErrors (55.37s)
=== RUN TestSyncAfterAddingAFile
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:33:07 DEBUG : empty space: size = 1 OK
2026/02/03 07:33:07 DEBUG : empty space: Sizes identical
2026/02/03 07:33:07 DEBUG : potato: Need to transfer - File not found at Destination
2026/02/03 07:33:07 DEBUG : empty space: Unchanged skipping
2026/02/03 07:33:07 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:33:07 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:33:10 DEBUG : potato: size = 60 OK
2026/02/03 07:33:10 INFO : potato: Copied (new)
2026/02/03 07:33:10 DEBUG : Waiting for deletions to finish
2026/02/03 07:33:10 INFO : c: Removing directory
2026/02/03 07:33:10 DEBUG : drime root 'rclone-test-cifinad8hofu': deleted 1 directories
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1109
Error: Should be true
Test: TestSyncAfterAddingAFile
Messages: listing wrong, want
empty space (1), potato (60) got
empty space (1)
fstest.go:203: Not found "potato"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1109
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestSyncAfterAddingAFile
Messages: 1 objects not found
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestSyncAfterAddingAFile
Messages: listing wrong, want
got
empty space (1), potato (60)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestSyncAfterAddingAFile
Messages: Unexpected file "empty space"
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestSyncAfterAddingAFile
Messages: Unexpected file "potato"
--- FAIL: TestSyncAfterAddingAFile (31.42s)
=== RUN TestSyncAfterRemovingAFileAndAddingAFile
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:33:33 DEBUG : potato: Removing old object on successful upload
2026/02/03 07:33:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:33:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:33:33 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:33:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:33:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:33:34 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 07:33:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:33:34 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:33:34 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:33:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 07:33:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:33:35 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 07:33:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:33:35 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 07:33:36 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:33:36 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 07:33:37 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:33:37 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 07:33:40 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 07:33:40 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:286: Retry Put of "potato" to drime root 'rclone-test-cifinad8hofu': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error")
2026/02/03 07:33:45 DEBUG : pacer: Reducing sleep to 5.12s
2026/02/03 07:33:57 DEBUG : pacer: Reducing sleep to 2.56s
2026/02/03 07:33:57 DEBUG : potato: Removing old object on successful upload
2026/02/03 07:34:00 DEBUG : pacer: Reducing sleep to 1.28s
2026/02/03 07:34:03 DEBUG : pacer: Reducing sleep to 640ms
2026/02/03 07:34:08 DEBUG : pacer: Reducing sleep to 320ms
2026/02/03 07:34:09 DEBUG : pacer: Reducing sleep to 160ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:34:10 DEBUG : pacer: Reducing sleep to 80ms
2026/02/03 07:34:10 DEBUG : pacer: Reducing sleep to 40ms
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:34:13 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 07:34:13 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1181
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestSyncAfterRemovingAFileAndAddingAFile
Messages: listing wrong, want
empty space (1), potato (21) got
potato (21)
fstest.go:203: Not found "empty space"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1181
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194
Error: Not equal:
expected: 0
actual : 1
Test: TestSyncAfterRemovingAFileAndAddingAFile
Messages: 1 objects not found
2026/02/03 07:34:17 DEBUG : empty space: size = 1 OK
2026/02/03 07:34:17 DEBUG : empty space: Sizes identical
2026/02/03 07:34:17 DEBUG : potato2: Need to transfer - File not found at Destination
2026/02/03 07:34:17 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:34:17 DEBUG : empty space: Unchanged skipping
2026/02/03 07:34:17 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:34:20 DEBUG : potato2: size = 60 OK
2026/02/03 07:34:20 INFO : potato2: Copied (new)
2026/02/03 07:34:20 DEBUG : Waiting for deletions to finish
2026/02/03 07:34:21 INFO : potato: Deleted
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1190
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestSyncAfterRemovingAFileAndAddingAFile
Messages: listing wrong, want
empty space (1), potato2 (60) got
empty space (1), potato (21)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1190
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194
Error: Should be true
Test: TestSyncAfterRemovingAFileAndAddingAFile
Messages: Unexpected file "potato"
fstest.go:203: Not found "potato2"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1190
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1194
Error: Not equal:
expected: 0
actual : 1
Test: TestSyncAfterRemovingAFileAndAddingAFile
Messages: 1 objects not found
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestSyncAfterRemovingAFileAndAddingAFile
Messages: listing wrong, want
got
empty space (1), potato2 (60)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestSyncAfterRemovingAFileAndAddingAFile
Messages: Unexpected file "empty space"
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestSyncAfterRemovingAFileAndAddingAFile
Messages: Unexpected file "potato2"
--- FAIL: TestSyncAfterRemovingAFileAndAddingAFile (69.73s)
=== RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:34:52 INFO : d: Making directory
2026/02/03 07:34:53 INFO : d/e: Making directory
2026/02/03 07:34:55 DEBUG : a/potato2: Need to transfer - File not found at Destination
2026/02/03 07:34:55 DEBUG : c/non empty space: size = 5 OK
2026/02/03 07:34:55 DEBUG : c/non empty space: Sizes identical
2026/02/03 07:34:55 DEBUG : c/non empty space: Unchanged skipping
2026/02/03 07:34:56 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:34:56 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:34:59 DEBUG : a/potato2: size = 60 OK
2026/02/03 07:34:59 INFO : a/potato2: Copied (new)
2026/02/03 07:34:59 DEBUG : Waiting for deletions to finish
2026/02/03 07:34:59 INFO : b/potato: Deleted
2026/02/03 07:34:59 INFO : d/e: Removing directory
2026/02/03 07:35:00 INFO : d: Removing directory
2026/02/03 07:35:00 DEBUG : d: Failed to Rmdir: directory not empty
2026/02/03 07:35:00 INFO : b: Removing directory
2026/02/03 07:35:00 DEBUG : b: Failed to Rmdir: directory not empty
2026/02/03 07:35:00 DEBUG : drime root 'rclone-test-cifinad8hofu': failed to delete 2 directories
2026/02/03 07:35:00 DEBUG : drime root 'rclone-test-cifinad8hofu': deleted 1 directories
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1248
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1262
Error: Not equal:
expected: []string{"a", "c"}
actual : []string{"a", "b", "c", "d"}
Diff:
--- Expected
+++ Actual
@@ -1,4 +1,6 @@
-([]string) (len=2) {
+([]string) (len=4) {
(string) (len=1) "a",
- (string) (len=1) "c"
+ (string) (len=1) "b",
+ (string) (len=1) "c",
+ (string) (len=1) "d"
}
Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir
Messages: directories
run.go:130: removing dir "c" failed - try 1/3: directory not empty
run.go:130: removing dir "c" failed - try 2/3: directory not empty
--- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDir (38.48s)
=== RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:35:30 INFO : d: Making directory
2026/02/03 07:35:32 DEBUG : a/potato2: Need to transfer - File not found at Destination
2026/02/03 07:35:33 DEBUG : c/non empty space: size = 5 OK
2026/02/03 07:35:33 DEBUG : c/non empty space: Sizes identical
2026/02/03 07:35:33 DEBUG : c/non empty space: Unchanged skipping
2026/02/03 07:35:33 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:35:33 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:35:37 DEBUG : a/potato2: size = 60 OK
2026/02/03 07:35:37 INFO : a/potato2: Copied (new)
2026/02/03 07:35:37 ERROR : drime root 'rclone-test-cifinad8hofu': not deleting files as there were IO errors
2026/02/03 07:35:37 ERROR : drime root 'rclone-test-cifinad8hofu': not deleting directories as there were IO errors
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
run.go:130: removing dir "c" failed - try 1/3: directory not empty
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (37.72s)
=== RUN TestSyncDeleteDuring
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 07:36:13 DEBUG : Waiting for deletions to finish
2026/02/03 07:36:14 DEBUG : empty space: size = 1 OK
2026/02/03 07:36:14 DEBUG : empty space: Sizes identical
2026/02/03 07:36:14 DEBUG : potato2: Need to transfer - File not found at Destination
2026/02/03 07:36:14 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:36:14 DEBUG : empty space: Unchanged skipping
2026/02/03 07:36:14 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:36:14 INFO : potato: Deleted
2026/02/03 07:36:16 DEBUG : potato2: size = 60 OK
2026/02/03 07:36:16 INFO : potato2: Copied (new)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestSyncDeleteDuring (38.42s)
=== RUN TestCopyDeleteBefore
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:36:38 DEBUG : potato2: Need to transfer - File not found at Destination
2026/02/03 07:36:38 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:36:38 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:36:43 DEBUG : potato2: size = 19 OK
2026/02/03 07:36:43 INFO : potato2: Copied (new)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1379
Error: Should be true
Test: TestCopyDeleteBefore
Messages: listing wrong, want
potato (21), potato2 (19) got
potato (21)
fstest.go:203: Not found "potato2"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1379
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestCopyDeleteBefore
Messages: 1 objects not found
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCopyDeleteBefore
Messages: listing wrong, want
got
potato (21), potato2 (19)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCopyDeleteBefore
Messages: Unexpected file "potato"
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCopyDeleteBefore
Messages: Unexpected file "potato2"
--- FAIL: TestCopyDeleteBefore (27.87s)
=== RUN TestSyncWithTrackRenamesStrategyLeaf
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
sync_test.go:1680: Can track renames: false
2026/02/03 07:37:02 DEBUG : potato: size = 14 (Local file system at /tmp/rclone2024420840)
2026/02/03 07:37:02 DEBUG : potato: size = 21 (drime root 'rclone-test-cifinad8hofu')
2026/02/03 07:37:02 DEBUG : potato: Sizes differ
2026/02/03 07:37:02 INFO : drime root 'rclone-test-cifinad8hofu': Making map for --track-renames
2026/02/03 07:37:02 INFO : drime root 'rclone-test-cifinad8hofu': Finished making map for --track-renames
2026/02/03 07:37:02 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:37:02 DEBUG : sub/yam: Need to transfer - No matching file found at Destination
2026/02/03 07:37:02 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for renames to finish
2026/02/03 07:37:02 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:37:06 DEBUG : potato: Removing old object on successful upload
2026/02/03 07:37:06 DEBUG : sub/yam: size = 11 OK
2026/02/03 07:37:06 INFO : sub/yam: Copied (new)
2026/02/03 07:37:06 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:37:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:37:07 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:37:07 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:37:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:37:07 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 07:37:07 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:37:07 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:37:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:37:07 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 07:37:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:37:07 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 07:37:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:37:08 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 07:37:09 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:37:09 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 07:37:10 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:37:10 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 07:37:13 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 07:37:13 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/02/03 07:37:13 DEBUG : potato: Received error: failed to delete existing object: failed to delete item: Error "Server Error" - low level retry 0/10
2026/02/03 07:37:19 DEBUG : pacer: Reducing sleep to 5.12s
2026/02/03 07:37:19 DEBUG : potato: Removing old object on successful upload
2026/02/03 07:37:28 DEBUG : pacer: Reducing sleep to 2.56s
2026/02/03 07:37:28 DEBUG : potato: size = 14 OK
2026/02/03 07:37:28 INFO : potato: Copied (replaced existing)
2026/02/03 07:37:28 DEBUG : Waiting for deletions to finish
2026/02/03 07:37:33 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:37:33 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:37:33 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 07:37:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:37:35 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:37:35 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/02/03 07:37:41 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:37:41 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 07:37:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:38:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:38:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:38:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:39:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:39:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:39:51 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 07:39:51 ERROR : potato2: Couldn't delete: failed to delete item: Error "Server Error"
2026/02/03 07:39:51 ERROR : drime root 'rclone-test-cifinad8hofu': not deleting directories as there were IO errors
sync_test.go:1687:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1687
Error: Received unexpected error:
failed to delete 1 files
Test: TestSyncWithTrackRenamesStrategyLeaf
2026/02/03 07:40:11 DEBUG : pacer: Reducing sleep to 10s
2026/02/03 07:40:31 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 07:40:41 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 07:40:46 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/03 07:40:49 DEBUG : pacer: Reducing sleep to 625ms
run.go:130: removing dir "sub" failed - try 1/3: directory not empty
2026/02/03 07:40:50 DEBUG : pacer: Reducing sleep to 312.5ms
run.go:130: removing dir "sub" failed - try 2/3: directory not empty
2026/02/03 07:40:51 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 07:40:52 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 07:40:52 DEBUG : pacer: Reducing sleep to 39.0625ms
--- FAIL: TestSyncWithTrackRenamesStrategyLeaf (230.44s)
=== RUN TestMoveWithDeleteEmptySrcDirs
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:40:53 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 07:40:53 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/03 07:40:53 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2026/02/03 07:40:53 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:40:53 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:40:53 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 07:40:58 DEBUG : nested/sub dir/file: size = 6 OK
2026/02/03 07:40:58 INFO : nested/sub dir/file: Copied (new)
2026/02/03 07:40:58 INFO : nested/sub dir/file: Deleted
2026/02/03 07:40:59 DEBUG : sub dir/hello world: size = 11 OK
2026/02/03 07:40:59 INFO : sub dir/hello world: Copied (new)
2026/02/03 07:40:59 INFO : sub dir/hello world: Deleted
2026/02/03 07:40:59 ERROR : Local file system at /tmp/rclone2024420840: not deleting directories as there were IO errors
sync_test.go:1819:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1819
Error: Received unexpected error:
not deleting directories as there were IO errors
Test: TestMoveWithDeleteEmptySrcDirs
--- FAIL: TestMoveWithDeleteEmptySrcDirs (7.07s)
=== RUN TestMoveWithoutDeleteEmptySrcDirs
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:40:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/03 07:40:59 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2026/02/03 07:40:59 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:40:59 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:41:03 DEBUG : nested/sub dir/file: Removing old object on successful upload
2026/02/03 07:41:03 DEBUG : nested/sub dir/file: size = 6 OK
2026/02/03 07:41:03 INFO : nested/sub dir/file: Copied (new)
2026/02/03 07:41:03 INFO : nested/sub dir/file: Deleted
2026/02/03 07:41:13 DEBUG : sub dir/hello world: Removing old object on successful upload
2026/02/03 07:41:14 DEBUG : sub dir/hello world: size = 11 OK
2026/02/03 07:41:14 INFO : sub dir/hello world: Copied (new)
2026/02/03 07:41:14 INFO : sub dir/hello world: Deleted
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
2026/02/03 07:41:26 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:26 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:41:27 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:41:33 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:33 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:41:34 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:41:37 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:37 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:41:37 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:37 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:37 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:41:38 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 07:41:38 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:38 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:38 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:41:39 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 07:41:39 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestMoveWithoutDeleteEmptySrcDirs
Messages: directories
--- FAIL: TestMoveWithoutDeleteEmptySrcDirs (44.16s)
=== RUN TestMoveWithIgnoreExisting
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:41:44 DEBUG : existing-b: Need to transfer - File not found at Destination
2026/02/03 07:41:44 DEBUG : existing: Need to transfer - File not found at Destination
2026/02/03 07:41:44 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:41:44 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:41:45 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:45 DEBUG : pacer: low level retry 1/1 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:41:45 DEBUG : existing-b: Received error: failed to upload file: Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n" - low level retry 0/10
2026/02/03 07:41:45 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:45 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:45 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:41:45 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:45 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:45 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 07:41:45 DEBUG : pacer: Reducing sleep to 40ms
2026/02/03 07:41:45 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:45 DEBUG : pacer: low level retry 1/1 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:45 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 07:41:45 DEBUG : existing-b: Received error: failed to upload file: Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n" - low level retry 1/10
2026/02/03 07:41:45 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:45 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:45 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:41:46 DEBUG : pacer: Reducing sleep to 80ms
2026/02/03 07:41:48 DEBUG : pacer: Reducing sleep to 40ms
2026/02/03 07:41:48 DEBUG : existing: size = 6 OK
2026/02/03 07:41:48 INFO : existing: Copied (new)
2026/02/03 07:41:48 INFO : existing: Deleted
2026/02/03 07:41:55 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 07:41:55 DEBUG : existing-b: size = 6 OK
2026/02/03 07:41:55 INFO : existing-b: Copied (new)
2026/02/03 07:41:55 INFO : existing-b: Deleted
2026/02/03 07:41:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:55 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:55 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:41:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:55 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:55 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 07:41:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:55 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:55 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:41:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:55 DEBUG : pacer: low level retry 4/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:55 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 07:41:56 DEBUG : pacer: Reducing sleep to 160ms
2026/02/03 07:41:56 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:56 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:56 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 07:41:56 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:56 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:56 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 07:41:57 DEBUG : pacer: Reducing sleep to 320ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:41:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:41:58 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:41:58 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 07:41:58 DEBUG : pacer: Reducing sleep to 320ms
2026/02/03 07:41:59 DEBUG : pacer: Reducing sleep to 160ms
2026/02/03 07:41:59 DEBUG : pacer: Reducing sleep to 80ms
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:42:02 DEBUG : pacer: Reducing sleep to 40ms
2026/02/03 07:42:02 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 07:42:02 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1873
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestMoveWithIgnoreExisting
Messages: directories
2026/02/03 07:42:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:06 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:42:06 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 07:42:07 DEBUG : existing: Destination exists, skipping
2026/02/03 07:42:07 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2026/02/03 07:42:07 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for checks to finish
2026/02/03 07:42:07 DEBUG : drime root 'rclone-test-cifinad8hofu': Waiting for transfers to finish
2026/02/03 07:42:07 INFO : There was nothing to transfer
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1898
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestMoveWithIgnoreExisting
Messages: directories
2026/02/03 07:42:16 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:16 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:42:17 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:42:24 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:24 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:42:24 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestMoveWithIgnoreExisting
Messages: listing wrong, want
got
existing (6), existing-b (6)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestMoveWithIgnoreExisting
Messages: Unexpected file "existing"
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestMoveWithIgnoreExisting
Messages: Unexpected file "existing-b"
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestMoveWithIgnoreExisting
Messages: directories
--- FAIL: TestMoveWithIgnoreExisting (45.24s)
=== RUN TestServerSideMove
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 07:42:29 DEBUG : Creating backend with remote "TestDrime:rclone-test-cujenoy6loku"
2026/02/03 07:42:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:30 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:30 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:42:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:30 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:30 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:42:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:30 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:30 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 07:42:30 DEBUG : pacer: Reducing sleep to 40ms
2026/02/03 07:42:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:30 DEBUG : pacer: low level retry 1/1 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:30 DEBUG : pacer: Rate limited, increasing sleep to 80ms
run.go:286: Retry Put of "potato2" to drime root 'rclone-test-cifinad8hofu': 1/10 (failed to upload file: Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:33 DEBUG : pacer: Reducing sleep to 40ms
2026/02/03 07:42:33 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:33 DEBUG : pacer: low level retry 1/1 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms
run.go:286: Retry Put of "potato2" to drime root 'rclone-test-cifinad8hofu': 2/10 (failed to upload file: Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:35 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:35 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:42:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:35 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:35 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 07:42:35 DEBUG : pacer: Reducing sleep to 160ms
2026/02/03 07:42:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:35 DEBUG : pacer: low level retry 1/1 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:35 DEBUG : pacer: Rate limited, increasing sleep to 320ms
run.go:286: Retry Put of "potato2" to drime root 'rclone-test-cifinad8hofu': 3/10 (failed to upload file: Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:37 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:37 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:37 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 07:42:38 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:38 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:38 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 07:42:38 DEBUG : pacer: Reducing sleep to 640ms
2026/02/03 07:42:52 DEBUG : pacer: Reducing sleep to 320ms
2026/02/03 07:42:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:52 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:52 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 07:42:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:52 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:52 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 07:42:53 DEBUG : pacer: Reducing sleep to 640ms
2026/02/03 07:42:56 DEBUG : pacer: Reducing sleep to 320ms
2026/02/03 07:42:57 DEBUG : pacer: Reducing sleep to 160ms
2026/02/03 07:42:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:42:57 DEBUG : pacer: low level retry 1/1 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:57 DEBUG : pacer: Rate limited, increasing sleep to 320ms
run.go:286: Retry Put of "potato3" to drime root 'rclone-test-cifinad8hofu': 1/10 (failed to upload file: Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:42:59 DEBUG : pacer: Reducing sleep to 160ms
2026/02/03 07:43:05 DEBUG : pacer: Reducing sleep to 80ms
2026/02/03 07:43:05 DEBUG : pacer: Reducing sleep to 40ms
sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-cifinad8hofu' -> drime root 'rclone-test-cujenoy6loku'
2026/02/03 07:43:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:08 DEBUG : pacer: low level retry 1/1 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms
run.go:286: Retry Put of "potato3" to drime root 'rclone-test-cujenoy6loku': 1/10 (failed to upload file: Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:10 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 07:43:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:12 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:43:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:12 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:12 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:43:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:12 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:12 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 07:43:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:13 DEBUG : pacer: low level retry 4/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:13 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:43:13 DEBUG : pacer: Reducing sleep to 80ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:43:14 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:14 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:14 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:43:14 DEBUG : pacer: Reducing sleep to 80ms
2026/02/03 07:43:14 ERROR : error listing: directory not found
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 07:43:17 DEBUG : pacer: Reducing sleep to 40ms
2026/02/03 07:43:17 DEBUG : pacer: Reducing sleep to 20ms
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 07:43:24 DEBUG : drime root 'rclone-test-cujenoy6loku': Using server-side directory move
2026/02/03 07:43:24 INFO : drime root 'rclone-test-cujenoy6loku': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2026/02/03 07:43:24 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:24 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:24 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 07:43:24 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 07:43:25 DEBUG : pacer: Reducing sleep to 40ms
2026/02/03 07:43:25 DEBUG : potato2: Need to transfer - File not found at Destination
2026/02/03 07:43:25 DEBUG : empty space: size = 1 OK
2026/02/03 07:43:25 DEBUG : empty space: Sizes identical
2026/02/03 07:43:25 DEBUG : empty space: Unchanged skipping
2026/02/03 07:43:25 DEBUG : drime root 'rclone-test-cujenoy6loku': Waiting for checks to finish
2026/02/03 07:43:25 DEBUG : potato3: size = 68 (drime root 'rclone-test-cifinad8hofu')
2026/02/03 07:43:25 DEBUG : potato3: size = 60 (drime root 'rclone-test-cujenoy6loku')
2026/02/03 07:43:25 DEBUG : potato3: Sizes differ
2026/02/03 07:43:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:25 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:25 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 07:43:25 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:43:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:25 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:25 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:43:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:25 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:25 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:43:25 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 07:43:25 INFO : potato3: Deleted
2026/02/03 07:43:25 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 07:43:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:25 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 07:43:25 DEBUG : pacer: Reducing sleep to 80ms
2026/02/03 07:43:25 INFO : empty space: Deleted
2026/02/03 07:43:25 DEBUG : drime root 'rclone-test-cujenoy6loku': Waiting for transfers to finish
2026/02/03 07:43:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:25 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:25 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 07:43:25 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 07:43:25 ERROR : potato2: Couldn't move: object not found
2026/02/03 07:43:25 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 07:43:26 ERROR : potato3: Couldn't move: object not found
sync_test.go:1747:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1747
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1912
Error: Received unexpected error:
object not found
Test: TestServerSideMove
2026/02/03 07:43:26 DEBUG : drime root 'rclone-test-cujenoy6loku': Purge remote
2026/02/03 07:43:26 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:26 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:43:26 DEBUG : pacer: Reducing sleep to 80ms
2026/02/03 07:43:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:43:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 07:43:27 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:43:27 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 07:43:27 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:43:27 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 07:43:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:43:27 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 07:43:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:43:28 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 07:43:29 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:43:29 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 07:43:32 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:32 DEBUG : pacer: low level retry 7/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:43:32 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/02/03 07:43:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:43:37 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 07:43:47 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:43:47 DEBUG : pacer: low level retry 9/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:44:07 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "empty space" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 07:44:28 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:44:48 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:45:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:45:28 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:45:48 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:46:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:46:28 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:46:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:47:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:47:27 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "empty space" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 07:47:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:48:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 07:48:07 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 07:48:27 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:48:47 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:49:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:49:28 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:49:48 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:50:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:50:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:50:47 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "empty space" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "empty space" failed: failed to delete item: Error "Server Error"
2026/02/03 07:51:07 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:51:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:51:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:52:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:52:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:52:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:53:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:53:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:53:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:54:08 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato2" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 07:54:28 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:54:48 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:55:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:55:28 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:55:48 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:56:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:56:28 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:56:48 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:57:08 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:57:28 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato2" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 07:57:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:58:08 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:58:28 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:58:48 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:59:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:59:28 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:59:48 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:00:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:00:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:00:48 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato2" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "potato2" failed: failed to delete item: Error "Server Error"
2026/02/03 08:01:08 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:01:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:01:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:02:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:02:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:02:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:03:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:03:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:03:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:04:08 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato3" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 08:04:29 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:04:48 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:05:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:05:28 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:05:48 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:06:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:06:29 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:06:48 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:07:09 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:07:29 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato3" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 08:07:49 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:08:09 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:08:29 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:08:49 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:09:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:09:28 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:09:48 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:10:09 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:10:29 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:10:48 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato3" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "potato3" failed: failed to delete item: Error "Server Error"
2026/02/03 08:11:08 DEBUG : pacer: Reducing sleep to 10s
--- FAIL: TestServerSideMove (1719.67s)
=== RUN TestServerSideMoveWithFilter
run.go:185: Remote "drime root 'rclone-test-cifinad8hofu'", Local "Local file system at /tmp/rclone2024420840", Modify Window "876000h0m0s"
2026/02/03 08:11:08 DEBUG : Creating backend with remote "TestDrime:rclone-test-wavibaz5hifu"
2026/02/03 08:11:29 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 08:11:44 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 08:11:45 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/03 08:11:51 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 08:11:51 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 08:11:59 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 08:11:59 DEBUG : pacer: Reducing sleep to 78.125ms
sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-cifinad8hofu' -> drime root 'rclone-test-wavibaz5hifu'
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 08:12:07 ERROR : error listing: directory not found
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 08:12:09 ERROR : error listing: directory not found
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1741
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1925
Error: Should be true
Test: TestServerSideMoveWithFilter
Messages: listing wrong, want
empty space (1), potato3 (60) got
fstest.go:203: Not found "potato3"
fstest.go:203: Not found "empty space"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1741
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1925
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 2
Test: TestServerSideMoveWithFilter
Messages: 2 objects not found
2026/02/03 08:12:14 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/03 08:12:14 DEBUG : empty space: Excluded (Size Filter)
2026/02/03 08:12:14 DEBUG : empty space: Excluded
2026/02/03 08:12:14 DEBUG : empty space: Excluded (Size Filter)
2026/02/03 08:12:14 DEBUG : empty space: Excluded
2026/02/03 08:12:14 DEBUG : potato2: Need to transfer - File not found at Destination
2026/02/03 08:12:14 DEBUG : drime root 'rclone-test-wavibaz5hifu': Waiting for checks to finish
2026/02/03 08:12:14 DEBUG : potato3: size = 68 (drime root 'rclone-test-cifinad8hofu')
2026/02/03 08:12:14 DEBUG : potato3: size = 60 (drime root 'rclone-test-wavibaz5hifu')
2026/02/03 08:12:14 DEBUG : potato3: Sizes differ
2026/02/03 08:12:14 DEBUG : drime root 'rclone-test-wavibaz5hifu': Waiting for transfers to finish
2026/02/03 08:12:14 INFO : potato3: Deleted
2026/02/03 08:12:14 ERROR : potato2: Couldn't move: object not found
2026/02/03 08:12:15 ERROR : potato3: Couldn't move: object not found
sync_test.go:1747:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1747
/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1925
Error: Received unexpected error:
object not found
Test: TestServerSideMoveWithFilter
2026/02/03 08:12:15 DEBUG : drime root 'rclone-test-wavibaz5hifu': Purge remote
2026/02/03 08:12:15 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 08:12:16 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 08:12:16 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:12:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 08:12:16 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:12:16 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 08:12:17 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:12:17 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 08:12:17 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:12:17 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 08:12:17 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:12:17 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 08:12:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:12:18 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 08:12:18 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:12:18 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 08:12:19 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:12:19 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 08:12:20 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:12:20 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 08:12:23 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 08:12:23 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:130: removing file "potato2" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 08:12:28 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:12:28 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 08:12:38 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:12:58 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:13:18 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:13:38 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:13:58 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:14:18 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:14:38 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:14:58 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:15:18 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato2" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 08:15:38 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:15:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:16:18 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:16:18 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:16:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:16:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:17:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:17:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:17:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:18:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:18:38 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato2" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "potato2" failed: failed to delete item: Error "Server Error"
2026/02/03 08:18:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:19:18 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:19:38 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:19:58 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:20:18 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:20:38 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:20:58 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:21:18 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:21:38 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:21:58 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato3" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 08:22:18 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:22:38 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:22:58 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:23:18 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:23:39 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:23:58 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:24:18 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:24:38 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:24:58 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:25:18 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato3" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 08:25:38 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:25:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:26:19 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:26:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:26:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:27:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:27:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:27:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:28:19 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:28:38 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "potato3" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "potato3" failed: failed to delete item: Error "Server Error"
2026/02/03 08:28:59 DEBUG : pacer: Reducing sleep to 10s
--- FAIL: TestServerSideMoveWithFilter (1070.30s)
FAIL
2026/02/03 08:28:59 DEBUG : drime root 'rclone-test-cifinad8hofu': Purge remote
2026/02/03 08:29:19 DEBUG : pacer: Reducing sleep to 5s
"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCopyAfterDelete|TestCopyDeleteBefore|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyWithFilesFromAndNoTraverse|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterAddingAFile|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncDeleteDuring|TestSyncEmptyDirectories|TestSyncIgnoreErrors|TestSyncWithTrackRenamesStrategyLeaf)$'" - Finished ERROR in 1h2m46.727530751s (try 5/5): exit status 1: Failed [TestCopyNoTraverse TestCopyEmptyDirectories TestCopyNoEmptyDirectories TestSyncEmptyDirectories TestCopyOverSelf TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestCopyAfterDelete TestSyncIgnoreErrors TestSyncAfterAddingAFile TestSyncAfterRemovingAFileAndAddingAFile TestSyncAfterRemovingAFileAndAddingAFileSubDir TestCopyDeleteBefore TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestMoveWithoutDeleteEmptySrcDirs TestMoveWithIgnoreExisting TestServerSideMove TestServerSideMoveWithFilter]