"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCopy|TestCopyAfterDelete|TestCopyCheckFirst|TestCopyDeleteBefore|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyWithFilesFromAndNoTraverse|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterAddingAFile|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncBasedOnCheckSum|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncEmptyDirectories|TestSyncIgnoreErrors|TestSyncIgnoreExisting|TestSyncIgnoreTimes|TestSyncNoEmptyDirectories|TestSyncSetDelayedModTimes|TestSyncSizeOnly|TestSyncWithExclude|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 2/5) 2026/02/03 03:53:10 DEBUG : Creating backend with remote "TestDrime:rclone-test-tavenol5qebu" 2026/02/03 03:53:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 03:53:11 DEBUG : Creating backend with remote "/tmp/rclone2346354057" === RUN TestCopy run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 03:53:11 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/03 03:53:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:53:12 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:53:12 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:53:16 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:53:16 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 2026/02/03 03:53:17 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 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:83 Error: Should be true Test: TestCopy 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:83 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopy Messages: 1 objects not found 2026/02/03 03:53:24 DEBUG : drime root 'rclone-test-tavenol5qebu': 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: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: TestCopy Messages: listing wrong, want got sub dir/hello world (11) 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: TestCopy Messages: Unexpected file "sub dir/hello world" 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: TestCopy Messages: directories --- FAIL: TestCopy (27.41s) === RUN TestCopyNoTraverse run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 03:53:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:53:39 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:53:39 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:53:41 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:53:41 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: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:216 Error: Should be true Test: TestCopyNoTraverse 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:216 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyNoTraverse Messages: 1 objects not found 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 (27.35s) === RUN TestCopyCheckFirst run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 03:54:06 INFO : drime root 'rclone-test-tavenol5qebu': Running all checks before starting transfers 2026/02/03 03:54:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:54:07 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:54:07 INFO : drime root 'rclone-test-tavenol5qebu': Checks finished, now starting transfers 2026/02/03 03:54:07 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:54:12 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:54:12 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: TestCopyCheckFirst Messages: directories --- FAIL: TestCopyCheckFirst (21.43s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 03:54:28 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 03:54:28 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:54:28 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:54:32 DEBUG : potato2: size = 11 OK 2026/02/03 03:54:32 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:332 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:335 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCopyWithFilesFromAndNoTraverse Messages: listing wrong, want potato2 (11) got 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:332 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:335 Error: Not equal: expected: 0 actual : 1 Test: TestCopyWithFilesFromAndNoTraverse 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: TestCopyWithFilesFromAndNoTraverse Messages: listing wrong, want got potato2 (11) 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: TestCopyWithFilesFromAndNoTraverse Messages: Unexpected file "potato2" 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: TestCopyWithFilesFromAndNoTraverse Messages: directories --- FAIL: TestCopyWithFilesFromAndNoTraverse (27.99s) === RUN TestCopyEmptyDirectories run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 03:54:55 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/03 03:54:55 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/03 03:54:55 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/02/03 03:54:55 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/03 03:54:56 INFO : sub dir2: Making directory 2026/02/03 03:54:57 INFO : sub dir: Making directory 2026/02/03 03:54:58 INFO : sub dir2/sub sub dir2: Making directory 2026/02/03 03:54:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:54:58 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:54:58 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:55:01 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:55:01 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: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:358 Error: Should be true Test: TestCopyEmptyDirectories 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:358 Error: Not equal: expected: 0 actual : 1 Test: TestCopyEmptyDirectories 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:358 Error: Not equal: expected: []string{"sub dir", "sub dir2", "sub dir2/sub sub dir2"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) (len=7) "sub dir", - (string) (len=8) "sub dir2", - (string) (len=21) "sub dir2/sub sub dir2" +([]string) { } Test: TestCopyEmptyDirectories Messages: directories 2026/02/03 03:55:09 DEBUG : drime root 'rclone-test-tavenol5qebu': 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 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", "sub dir2"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=7) "sub dir", + (string) (len=8) "sub dir2" } Test: TestCopyEmptyDirectories Messages: directories --- FAIL: TestCopyEmptyDirectories (34.45s) === RUN TestCopyNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 03:55:30 INFO : sub dir2: Making directory 2026/02/03 03:55:30 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/02/03 03:55:30 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/03 03:55:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:55:31 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:55:31 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:55:33 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:55:33 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 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: TestCopyNoEmptyDirectories Messages: directories --- FAIL: TestCopyNoEmptyDirectories (28.44s) === RUN TestSyncEmptyDirectories run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 03:55:58 DEBUG : sub dir2: Making directory with metadata 2026/02/03 03:55:58 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/02/03 03:55:58 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/03 03:55:59 INFO : sub dir2: Making directory 2026/02/03 03:56:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:56:00 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:56:00 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:56:02 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:56:02 INFO : sub dir/hello world: Copied (new) 2026/02/03 03:56:02 ERROR : drime root 'rclone-test-tavenol5qebu': not deleting files as there were IO errors 2026/02/03 03:56:02 ERROR : drime root 'rclone-test-tavenol5qebu': not deleting directories as there were IO errors sync_test.go:513: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:513 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncEmptyDirectories 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:513 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: TestSyncEmptyDirectories Messages: directories --- FAIL: TestSyncEmptyDirectories (14.80s) === RUN TestSyncSetDelayedModTimes run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (1.31s) === RUN TestSyncNoEmptyDirectories run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 03:56:14 INFO : sub dir2: Making directory 2026/02/03 03:56:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:56:15 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:56:15 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:56:18 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:56:18 INFO : sub dir/hello world: Copied (new) 2026/02/03 03:56:18 ERROR : drime root 'rclone-test-tavenol5qebu': not deleting files as there were IO errors 2026/02/03 03:56:18 ERROR : drime root 'rclone-test-tavenol5qebu': not deleting directories as there were IO errors sync_test.go:597: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:597 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncNoEmptyDirectories --- FAIL: TestSyncNoEmptyDirectories (4.90s) === RUN TestServerSideCopy run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 03:56:22 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:292: Sleeping for 6s just to make sure 2026/02/03 03:56:31 DEBUG : Creating backend with remote "TestDrime:rclone-test-gukicel6meve" sync_test.go:620: Server side copy (if possible) drime root 'rclone-test-tavenol5qebu' -> drime root 'rclone-test-gukicel6meve' 2026/02/03 03:56:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:56:33 DEBUG : drime root 'rclone-test-gukicel6meve': Waiting for checks to finish 2026/02/03 03:56:33 DEBUG : drime root 'rclone-test-gukicel6meve': Waiting for transfers to finish 2026/02/03 03:56:35 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:56:35 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/03 03:56:36 DEBUG : drime root 'rclone-test-gukicel6meve': 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: TestServerSideCopy Messages: directories --- FAIL: TestServerSideCopy (31.52s) === RUN TestCopyOverSelf run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", 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 03:57:07 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:57:07 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone2346354057) 2026/02/03 03:57:07 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-tavenol5qebu') 2026/02/03 03:57:07 DEBUG : sub dir/hello world: Sizes differ 2026/02/03 03:57:07 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:57:09 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/03 03:57:09 DEBUG : sub dir/hello world: size = 17 OK 2026/02/03 03:57:09 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 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: TestCopyOverSelf Messages: listing wrong, want got sub dir/hello world (17) 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: TestCopyOverSelf Messages: Unexpected file "sub dir/hello world" 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 (41.46s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", 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 03:57:53 DEBUG : Creating backend with remote "TestDrime:rclone-test-fuwecax9jimu" sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-tavenol5qebu' -> drime root 'rclone-test-fuwecax9jimu' 2026/02/03 03:57:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:57:54 DEBUG : drime root 'rclone-test-fuwecax9jimu': Waiting for checks to finish 2026/02/03 03:57:54 DEBUG : drime root 'rclone-test-fuwecax9jimu': Waiting for transfers to finish 2026/02/03 03:57:57 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:57:57 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/03 03:58:04 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 03:58:16 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-tavenol5qebu') 2026/02/03 03:58:16 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-fuwecax9jimu') 2026/02/03 03:58:16 DEBUG : sub dir/hello world: Sizes differ 2026/02/03 03:58:16 DEBUG : drime root 'rclone-test-fuwecax9jimu': Waiting for checks to finish 2026/02/03 03:58:16 DEBUG : drime root 'rclone-test-fuwecax9jimu': Waiting for transfers to finish 2026/02/03 03:58:17 DEBUG : sub dir/hello world: Server side copy: removing existing object after successful copy 2026/02/03 03:58:17 DEBUG : sub dir/hello world: size = 17 OK 2026/02/03 03:58:17 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 03:58:27 DEBUG : drime root 'rclone-test-fuwecax9jimu': 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: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: TestServerSideCopyOverSelf Messages: listing wrong, want got sub dir/hello world (17) 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: TestServerSideCopyOverSelf Messages: Unexpected file "sub dir/hello world" 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 (69.58s) === RUN TestMoveOverSelf run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", 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:679 Error: Should be true Test: TestMoveOverSelf 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:679 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMoveOverSelf Messages: 1 objects not found 2026/02/03 03:58:56 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 03:58:56 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone2346354057) 2026/02/03 03:58:56 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-tavenol5qebu') 2026/02/03 03:58:56 DEBUG : sub dir/hello world: Sizes differ 2026/02/03 03:58:56 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 03:58:57 DEBUG : sub dir/hello world: Removing old object on successful upload 2026/02/03 03:58:58 DEBUG : sub dir/hello world: size = 17 OK 2026/02/03 03:58:58 INFO : sub dir/hello world: Copied (replaced existing) 2026/02/03 03:58:58 INFO : sub dir/hello world: 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:688 Error: Should be true Test: TestMoveOverSelf 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:688 Error: Not equal: expected: 17 actual : 11 Test: TestMoveOverSelf 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 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: TestMoveOverSelf Messages: listing wrong, want got sub dir/hello world (17) 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: TestMoveOverSelf Messages: Unexpected file "sub dir/hello world" 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: TestMoveOverSelf Messages: directories --- FAIL: TestMoveOverSelf (39.17s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", 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:696 Error: Should be true Test: TestServerSideMoveOverSelf 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:696 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMoveOverSelf Messages: 1 objects not found 2026/02/03 03:59:35 DEBUG : Creating backend with remote "TestDrime:rclone-test-puwosej9cake" sync_test.go:701: Server side copy (if possible) drime root 'rclone-test-tavenol5qebu' -> drime root 'rclone-test-puwosej9cake' 2026/02/03 03:59:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 03:59:36 DEBUG : drime root 'rclone-test-puwosej9cake': Waiting for checks to finish 2026/02/03 03:59:36 DEBUG : drime root 'rclone-test-puwosej9cake': Waiting for transfers to finish 2026/02/03 03:59:39 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 03:59:39 INFO : sub dir/hello world: Copied (server-side copy) 2026/02/03 03:59:42 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 03:59:55 DEBUG : drime root 'rclone-test-puwosej9cake': Using server-side directory move 2026/02/03 03:59:55 INFO : drime root 'rclone-test-puwosej9cake': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/03 03:59:55 DEBUG : drime root 'rclone-test-puwosej9cake': Waiting for checks to finish 2026/02/03 03:59:55 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-tavenol5qebu') 2026/02/03 03:59:55 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-puwosej9cake') 2026/02/03 03:59:55 DEBUG : sub dir/hello world: Sizes differ 2026/02/03 03:59:55 DEBUG : drime root 'rclone-test-puwosej9cake': Waiting for transfers to finish 2026/02/03 03:59:56 INFO : sub dir/hello world: Deleted 2026/02/03 03:59:56 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 03:59:56 DEBUG : drime root 'rclone-test-puwosej9cake': Purge remote 2026/02/03 03:59:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:59:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 03:59:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:59:58 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 03:59:58 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:59:58 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 03:59:58 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:59:58 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 03:59:59 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:59:59 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 03:59:59 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:59:59 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 03:59:59 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:59:59 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 04:00:00 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:00:00 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 04:00:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:00:01 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 04:00:04 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 04:00:04 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "sub dir/hello world" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 04:00:09 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:00:09 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 04:00:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:00:39 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:00:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:01:19 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:01:39 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:01:59 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:02:19 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:02:39 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:03:00 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "sub dir/hello world" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 04:03:25 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:03:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:04:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:04:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:04:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:05:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:05:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:05:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:06:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:06:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "sub dir/hello world" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "sub dir/hello world" failed: failed to delete item: Error "Server Error" 2026/02/03 04:06:41 DEBUG : pacer: Reducing sleep to 10s 2026/02/03 04:07:01 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 04:07:11 DEBUG : pacer: Reducing sleep to 2.5s --- FAIL: TestServerSideMoveOverSelf (469.74s) === RUN TestCopyAfterDelete run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:07:16 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 04:07:18 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 04:07:20 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 04:07:23 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 04:07:23 ERROR : error listing: directory not found 2026/02/03 04:07:23 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 04:07:23 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 04:07:23 INFO : Local file system at /tmp/rclone2346354057: Making directory 2026/02/03 04:07:23 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 04:07:24 DEBUG : pacer: Reducing sleep to 10ms 2026/02/03 04:07:24 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:07:24 DEBUG : drime root 'rclone-test-tavenol5qebu': 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 (27.18s) === RUN TestSyncBasedOnCheckSum run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:07:38 DEBUG : check sum: Need to transfer - File not found at Destination 2026/02/03 04:07:38 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:07:38 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:07:40 DEBUG : check sum: size = 1 OK 2026/02/03 04:07:40 INFO : check sum: Copied (new) 2026/02/03 04:07:40 DEBUG : Waiting for deletions to finish 2026/02/03 04:07:40 INFO : sub dir: Removing directory 2026/02/03 04:07:41 DEBUG : drime root 'rclone-test-tavenol5qebu': deleted 1 directories 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 04:07:50 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:07:50 DEBUG : check sum: size = 1 OK 2026/02/03 04:07:50 NOTICE: drime root 'rclone-test-tavenol5qebu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/02/03 04:07:50 DEBUG : check sum: Size of src and dst objects identical 2026/02/03 04:07:50 DEBUG : check sum: Unchanged skipping 2026/02/03 04:07:50 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:07:50 DEBUG : Waiting for deletions to finish 2026/02/03 04:07:50 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:292: Sleeping for 7s just to make sure --- PASS: TestSyncBasedOnCheckSum (23.86s) === RUN TestSyncSizeOnly run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:08:02 DEBUG : sizeonly: Need to transfer - File not found at Destination 2026/02/03 04:08:02 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:08:02 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:08:04 DEBUG : sizeonly: size = 6 OK 2026/02/03 04:08:04 INFO : sizeonly: Copied (new) 2026/02/03 04:08:04 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:292: Sleeping for 6s just to make sure 2026/02/03 04:08:12 DEBUG : sizeonly: size = 6 OK 2026/02/03 04:08:12 DEBUG : sizeonly: Sizes identical 2026/02/03 04:08:12 DEBUG : sizeonly: Unchanged skipping 2026/02/03 04:08:12 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:08:12 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:08:12 DEBUG : Waiting for deletions to finish 2026/02/03 04:08:12 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:292: Sleeping for 7s just to make sure --- PASS: TestSyncSizeOnly (22.41s) === RUN TestSyncIgnoreTimes run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:08:28 DEBUG : existing: size = 6 OK 2026/02/03 04:08:28 DEBUG : existing: Sizes identical 2026/02/03 04:08:28 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:08:28 DEBUG : existing: Unchanged skipping 2026/02/03 04:08:28 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:08:28 DEBUG : Waiting for deletions to finish 2026/02/03 04:08:28 INFO : There was nothing to transfer 2026/02/03 04:08:28 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2026/02/03 04:08:28 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:08:28 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:08:30 DEBUG : existing: Removing old object on successful upload 2026/02/03 04:08:30 DEBUG : existing: size = 6 OK 2026/02/03 04:08:30 INFO : existing: Copied (replaced existing) 2026/02/03 04:08:30 DEBUG : Waiting for deletions to finish 2026/02/03 04:08:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:08:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 04:08:31 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:08:31 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 04:08:31 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:08:31 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 04:08:31 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:08:31 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 04:08:32 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:08:32 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 04:08:32 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:08:32 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 04:08:32 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:08:32 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 04:08:33 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:08:33 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 04:08:34 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:08:34 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 04:08:37 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 04:08:37 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "existing" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 04:08:42 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:08:42 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 04:08:52 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:09:12 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:09:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:09:52 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:10:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:10:33 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:10:52 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:11:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:11:33 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "existing" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 04:11:53 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:12:13 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:12:33 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:12:53 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:13:13 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:13:33 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:13:53 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:14:13 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:14:33 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:14:53 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "existing" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "existing" failed: failed to delete item: Error "Server Error" 2026/02/03 04:15:13 DEBUG : pacer: Reducing sleep to 10s fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 04:15:33 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 04:15:43 DEBUG : pacer: Reducing sleep to 2.5s fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 04:15:48 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 04:15:50 DEBUG : pacer: Reducing sleep to 625ms 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: TestSyncIgnoreTimes Messages: listing wrong, want got existing (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: TestSyncIgnoreTimes Messages: Unexpected file "existing" --- FAIL: TestSyncIgnoreTimes (450.05s) === RUN TestSyncIgnoreExisting run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:15:55 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 04:15:55 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 04:15:55 DEBUG : existing: Destination exists, skipping 2026/02/03 04:15:55 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:15:55 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:15:55 DEBUG : Waiting for deletions to finish 2026/02/03 04:15:55 INFO : There was nothing to transfer 2026/02/03 04:15:56 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 04:15:56 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 04:15:56 DEBUG : existing: Destination exists, skipping 2026/02/03 04:15:56 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:15:56 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:15:56 DEBUG : Waiting for deletions to finish 2026/02/03 04:15:56 INFO : There was nothing to transfer 2026/02/03 04:15:56 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 04:15:56 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 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: TestSyncIgnoreExisting Messages: listing wrong, want got existing (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: TestSyncIgnoreExisting Messages: Unexpected file "existing" --- FAIL: TestSyncIgnoreExisting (10.94s) === RUN TestSyncIgnoreErrors run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:16:13 INFO : d: Making directory 2026/02/03 04:16:15 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/03 04:16:16 DEBUG : c/non empty space: size = 5 OK 2026/02/03 04:16:16 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:16:16 DEBUG : c/non empty space: Sizes identical 2026/02/03 04:16:16 DEBUG : c/non empty space: Unchanged skipping 2026/02/03 04:16:16 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:16:18 DEBUG : a/potato2: size = 60 OK 2026/02/03 04:16:18 INFO : a/potato2: Copied (new) 2026/02/03 04:16:18 DEBUG : Waiting for deletions to finish 2026/02/03 04:16:19 INFO : b/potato: Deleted 2026/02/03 04:16:19 INFO : d: Removing directory 2026/02/03 04:16:19 INFO : b: Removing directory 2026/02/03 04:16:20 DEBUG : b: Failed to Rmdir: directory not empty 2026/02/03 04:16:20 DEBUG : drime root 'rclone-test-tavenol5qebu': failed to delete 1 directories 2026/02/03 04:16:20 DEBUG : drime root 'rclone-test-tavenol5qebu': 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/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:994 Error: Should be true Test: TestSyncIgnoreErrors Messages: listing wrong, want a/potato2 (60), c/non empty space (5) got b/potato (21), c/non empty space (5) 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:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:994 Error: Should be true Test: TestSyncIgnoreErrors Messages: Unexpected file "b/potato" fstest.go:203: Not found "a/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/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:994 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreErrors 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:994 Error: Not equal: expected: []string{"a", "c"} actual : []string{"b", "c", "d"} Diff: --- Expected +++ Actual @@ -1,4 +1,5 @@ -([]string) (len=2) { - (string) (len=1) "a", - (string) (len=1) "c" +([]string) (len=3) { + (string) (len=1) "b", + (string) (len=1) "c", + (string) (len=1) "d" } 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 run.go:130: removing dir "a" failed - try 1/3: directory not empty run.go:130: removing dir "a" failed - try 2/3: directory not empty run.go:130: removing dir "a" failed - try 3/3: directory not empty run.go:133: removing dir "a" 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{"a", "c"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=1) "a", + (string) (len=1) "c" } Test: TestSyncIgnoreErrors Messages: directories --- FAIL: TestSyncIgnoreErrors (43.47s) === RUN TestSyncAfterAddingAFile run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", 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 04:17:00 DEBUG : empty space: size = 1 OK 2026/02/03 04:17:00 DEBUG : empty space: Sizes identical 2026/02/03 04:17:00 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/03 04:17:00 DEBUG : empty space: Unchanged skipping 2026/02/03 04:17:00 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:17:00 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:17:04 DEBUG : potato: size = 60 OK 2026/02/03 04:17:04 INFO : potato: Copied (new) 2026/02/03 04:17:04 DEBUG : Waiting for deletions to finish 2026/02/03 04:17:04 INFO : c: Removing directory 2026/02/03 04:17:04 INFO : a: Removing directory 2026/02/03 04:17:05 DEBUG : drime root 'rclone-test-tavenol5qebu': 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: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 (25.91s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:17:22 DEBUG : empty space: Need to transfer - File not found at Destination 2026/02/03 04:17:22 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 04:17:22 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2026/02/03 04:17:22 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2026/02/03 04:17:22 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:17:22 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:17:22 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:292: Sleeping for 7s 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: TestSyncAfterRemovingAFileAndAddingAFileDryRun (28.60s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", 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: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 04:18:01 DEBUG : empty space: size = 1 OK 2026/02/03 04:18:01 DEBUG : empty space: Sizes identical 2026/02/03 04:18:01 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 04:18:01 DEBUG : empty space: Unchanged skipping 2026/02/03 04:18:01 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:18:01 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:18:04 DEBUG : potato2: size = 60 OK 2026/02/03 04:18:04 INFO : potato2: Copied (new) 2026/02/03 04:18:04 DEBUG : Waiting for deletions to finish 2026/02/03 04:18:05 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 (39.97s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:18:34 INFO : d: Making directory 2026/02/03 04:18:35 INFO : d/e: Making directory 2026/02/03 04:18:37 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/03 04:18:37 DEBUG : c/non empty space: size = 5 OK 2026/02/03 04:18:37 DEBUG : c/non empty space: Sizes identical 2026/02/03 04:18:37 DEBUG : c/non empty space: Unchanged skipping 2026/02/03 04:18:38 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:18:38 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:18:42 DEBUG : a/potato2: size = 60 OK 2026/02/03 04:18:42 INFO : a/potato2: Copied (new) 2026/02/03 04:18:42 DEBUG : Waiting for deletions to finish 2026/02/03 04:18:42 INFO : b/potato: Deleted 2026/02/03 04:18:42 INFO : d/e: Removing directory 2026/02/03 04:18:43 INFO : d: Removing directory 2026/02/03 04:18:44 DEBUG : d: Failed to Rmdir: directory not empty 2026/02/03 04:18:44 INFO : b: Removing directory 2026/02/03 04:18:44 DEBUG : b: Failed to Rmdir: directory not empty 2026/02/03 04:18:44 DEBUG : drime root 'rclone-test-tavenol5qebu': failed to delete 2 directories 2026/02/03 04:18:44 DEBUG : drime root 'rclone-test-tavenol5qebu': 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 --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDir (35.65s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:19:06 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:292: Sleeping for 7s just to make sure 2026/02/03 04:19:18 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/03 04:19:18 DEBUG : c/non empty space: size = 5 OK 2026/02/03 04:19:18 DEBUG : c/non empty space: Sizes identical 2026/02/03 04:19:18 DEBUG : c/non empty space: Unchanged skipping 2026/02/03 04:19:18 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:19:18 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:19:23 DEBUG : a/potato2: size = 60 OK 2026/02/03 04:19:23 INFO : a/potato2: Copied (new) 2026/02/03 04:19:23 ERROR : drime root 'rclone-test-tavenol5qebu': not deleting files as there were IO errors 2026/02/03 04:19:23 ERROR : drime root 'rclone-test-tavenol5qebu': not deleting directories as there were IO errors 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 run.go:130: removing dir "b" failed - try 1/3: directory not empty run.go:130: removing dir "b" failed - try 2/3: 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 04:19:41 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 04:19:41 ERROR : error listing: couldn't list files: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-03 04:19:41 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9c7f21a658e63624\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" fstest.go:282: Error listing: couldn't list files: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-03 04:19:41 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9c7f21a658e63624\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (41.97s) === RUN TestSyncDeleteDuring run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:19:41 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 04:19:41 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n

500 Internal Server Error

\r\n
nginx
\r\n\r\n\r\n") 2026/02/03 04:19:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 04:19:41 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 04:19:41 DEBUG : pacer: Reducing sleep to 10ms run.go:290: Failed to put "potato" to "drime root 'rclone-test-tavenol5qebu'": couldn't list files: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-03 04:19:41 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9c7f21a6e9523624\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" 2026/02/03 04:19:41 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 04:19:41 ERROR : error listing: couldn't list files: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-03 04:19:41 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9c7f21a7398b3624\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" run.go:169: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169 /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 /usr/local/go/src/testing/testing.go:1125 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:290 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1179 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1348 Error: Received unexpected error: couldn't list files: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-03 04:19:41 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9c7f21a7398b3624\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" Test: TestSyncDeleteDuring --- FAIL: TestSyncDeleteDuring (0.13s) === RUN TestSyncDeleteBefore run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", 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:1181 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncDeleteBefore 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:1357 Error: Not equal: expected: 0 actual : 1 Test: TestSyncDeleteBefore Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1182 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncDeleteBefore Messages: listing wrong, want empty space (1), potato2 (60) got a/potato2 (60), c/non empty space (5), 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1182 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 Error: Should be true Test: TestSyncDeleteBefore Messages: Unexpected file "c/non 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1182 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 Error: Should be true Test: TestSyncDeleteBefore Messages: Unexpected file "a/potato2" 2026/02/03 04:20:07 DEBUG : Waiting for deletions to finish 2026/02/03 04:20:08 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:20:08 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:20:08 INFO : potato: Deleted 2026/02/03 04:20:08 DEBUG : empty space: size = 1 OK 2026/02/03 04:20:08 DEBUG : empty space: Sizes identical 2026/02/03 04:20:08 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 04:20:08 DEBUG : empty space: Unchanged skipping 2026/02/03 04:20:08 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/02/03 04:20:09 DEBUG : c/non empty space: Need to transfer - File not found at Destination 2026/02/03 04:20:09 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:20:09 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:20:11 DEBUG : a/potato2: size = 60 OK 2026/02/03 04:20:11 INFO : a/potato2: Copied (new) 2026/02/03 04:20:12 DEBUG : pacer: low level retry 1/1 (error Error "Server Error") 2026/02/03 04:20:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 04:20:12 DEBUG : potato2: Received error: failed to upload file: Error "Server Error" - low level retry 0/10 2026/02/03 04:20:13 DEBUG : pacer: Reducing sleep to 10ms 2026/02/03 04:20:13 DEBUG : c/non empty space: size = 5 OK 2026/02/03 04:20:13 INFO : c/non empty space: Copied (new) 2026/02/03 04:20:15 DEBUG : potato2: size = 60 OK 2026/02/03 04:20:15 INFO : potato2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1189 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncDeleteBefore Messages: listing wrong, want empty space (1), potato2 (60) got a/potato2 (60), c/non empty space (5), 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1189 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 Error: Should be true Test: TestSyncDeleteBefore Messages: Unexpected file "c/non 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1189 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1357 Error: Should be true Test: TestSyncDeleteBefore Messages: Unexpected file "a/potato2" 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:1357 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncDeleteBefore Messages: listing wrong, want empty space (1), potato2 (60) got a/potato2 (60), c/non empty space (5), empty space (1), potato2 (60), 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/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:1357 Error: Should be true Test: TestSyncDeleteBefore Messages: Unexpected file "potato2" 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:1357 Error: Should be true Test: TestSyncDeleteBefore Messages: Unexpected file "c/non 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/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:1357 Error: Should be true Test: TestSyncDeleteBefore Messages: Unexpected file "a/potato2" 2026/02/03 04:20:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:20:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 04:20:34 DEBUG : pacer: Reducing sleep to 10ms 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 run.go:130: removing dir "a" failed - try 1/3: directory not empty run.go:130: removing dir "a" failed - try 2/3: directory not empty run.go:130: removing dir "a" failed - try 3/3: directory not empty run.go:133: removing dir "a" 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{"a", "c"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=1) "a", + (string) (len=1) "c" } Test: TestSyncDeleteBefore Messages: directories --- FAIL: TestSyncDeleteBefore (71.36s) === RUN TestCopyDeleteBefore run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:20:57 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 04:20:58 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:20:58 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:21:00 DEBUG : potato2: size = 19 OK 2026/02/03 04:21:00 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" 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{"a", "c"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=1) "a", + (string) (len=1) "c" } Test: TestCopyDeleteBefore Messages: directories --- FAIL: TestCopyDeleteBefore (29.51s) === RUN TestSyncWithExclude run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", 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 04:21:38 DEBUG : enormous: Excluded (Size Filter) 2026/02/03 04:21:38 DEBUG : enormous: Excluded 2026/02/03 04:21:38 DEBUG : potato2: Excluded (Size Filter) 2026/02/03 04:21:38 DEBUG : potato2: Excluded 2026/02/03 04:21:38 DEBUG : potato2: Excluded (Size Filter) 2026/02/03 04:21:38 DEBUG : potato2: Excluded 2026/02/03 04:21:38 DEBUG : empty space: size = 1 OK 2026/02/03 04:21:38 DEBUG : empty space: Sizes identical 2026/02/03 04:21:38 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:21:38 DEBUG : empty space: Unchanged skipping 2026/02/03 04:21:38 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:21:38 DEBUG : Waiting for deletions to finish 2026/02/03 04:21:38 INFO : There was nothing to transfer 2026/02/03 04:21:39 DEBUG : enormous: Excluded (Size Filter) 2026/02/03 04:21:39 DEBUG : enormous: Excluded 2026/02/03 04:21:39 DEBUG : potato2: Excluded (Size Filter) 2026/02/03 04:21:39 DEBUG : potato2: Excluded 2026/02/03 04:21:39 DEBUG : potato2: Excluded (Size Filter) 2026/02/03 04:21:39 DEBUG : potato2: Excluded 2026/02/03 04:21:39 DEBUG : empty space: size = 1 OK 2026/02/03 04:21:39 DEBUG : empty space: Sizes identical 2026/02/03 04:21:39 DEBUG : empty space: Unchanged skipping 2026/02/03 04:21:39 DEBUG : Local file system at /tmp/rclone2346354057: Waiting for checks to finish 2026/02/03 04:21:39 DEBUG : Local file system at /tmp/rclone2346354057: Waiting for transfers to finish 2026/02/03 04:21:39 DEBUG : Waiting for deletions to finish 2026/02/03 04:21:39 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:292: Sleeping for 6s just to make sure --- PASS: TestSyncWithExclude (26.69s) === RUN TestSyncWithTrackRenames run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" sync_test.go:1569: Can track renames: false 2026/02/03 04:21:48 ERROR : drime root 'rclone-test-tavenol5qebu': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/03 04:21:49 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/03 04:21:49 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/03 04:21:49 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:21:49 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:21:51 DEBUG : potato: size = 14 OK 2026/02/03 04:21:51 INFO : potato: Copied (new) 2026/02/03 04:21:51 DEBUG : yam: size = 11 OK 2026/02/03 04:21:51 INFO : yam: Copied (new) 2026/02/03 04:21:51 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:292: Sleeping for 6s just to make sure 2026/02/03 04:22:00 ERROR : drime root 'rclone-test-tavenol5qebu': Ignoring --track-renames as the source and destination do not have a common hash 2026/02/03 04:22:00 DEBUG : potato: size = 14 OK 2026/02/03 04:22:00 DEBUG : potato: Sizes identical 2026/02/03 04:22:00 DEBUG : yaml: Need to transfer - File not found at Destination 2026/02/03 04:22:00 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:22:00 DEBUG : potato: Unchanged skipping 2026/02/03 04:22:00 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:22:02 DEBUG : yaml: size = 11 OK 2026/02/03 04:22:02 INFO : yaml: Copied (new) 2026/02/03 04:22:02 DEBUG : Waiting for deletions to finish 2026/02/03 04:22:03 INFO : yam: Deleted 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: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 --- PASS: TestSyncWithTrackRenames (34.72s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" sync_test.go:1641: Can track renames: false 2026/02/03 04:22:23 ERROR : drime root 'rclone-test-tavenol5qebu': Ignoring --track-renames as either the source or destination do not support modtime 2026/02/03 04:22:24 DEBUG : potato: Need to transfer - File not found at Destination 2026/02/03 04:22:24 DEBUG : yam: Need to transfer - File not found at Destination 2026/02/03 04:22:24 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:22:24 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:22:26 DEBUG : yam: size = 11 OK 2026/02/03 04:22:26 INFO : yam: Copied (new) 2026/02/03 04:22:26 DEBUG : potato: size = 14 OK 2026/02/03 04:22:26 INFO : potato: Copied (new) 2026/02/03 04:22:26 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:292: Sleeping for 6s just to make sure 2026/02/03 04:22:34 ERROR : drime root 'rclone-test-tavenol5qebu': Ignoring --track-renames as either the source or destination do not support modtime 2026/02/03 04:22:34 DEBUG : potato: size = 14 OK 2026/02/03 04:22:34 DEBUG : potato: Sizes identical 2026/02/03 04:22:34 DEBUG : yaml: Need to transfer - File not found at Destination 2026/02/03 04:22:34 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:22:34 DEBUG : potato: Unchanged skipping 2026/02/03 04:22:34 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:22:37 DEBUG : yaml: size = 11 OK 2026/02/03 04:22:37 INFO : yaml: Copied (new) 2026/02/03 04:22:37 DEBUG : Waiting for deletions to finish 2026/02/03 04:22:37 INFO : yam: Deleted 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: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 --- PASS: TestSyncWithTrackRenamesStrategyModtime (34.43s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" sync_test.go:1680: Can track renames: false 2026/02/03 04:22:58 INFO : drime root 'rclone-test-tavenol5qebu': Making map for --track-renames 2026/02/03 04:22:58 INFO : drime root 'rclone-test-tavenol5qebu': Finished making map for --track-renames 2026/02/03 04:22:58 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:22:58 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for renames to finish 2026/02/03 04:22:58 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2026/02/03 04:22:58 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/02/03 04:22:58 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:23:00 DEBUG : potato: size = 14 OK 2026/02/03 04:23:00 INFO : potato: Copied (new) 2026/02/03 04:23:02 DEBUG : sub/yam: size = 11 OK 2026/02/03 04:23:02 INFO : sub/yam: Copied (new) 2026/02/03 04:23:02 DEBUG : Waiting for deletions to finish 2026/02/03 04:23:02 DEBUG : potato: size = 14 OK 2026/02/03 04:23:02 DEBUG : potato: Sizes identical 2026/02/03 04:23:02 DEBUG : potato: Unchanged skipping 2026/02/03 04:23:03 INFO : drime root 'rclone-test-tavenol5qebu': Making map for --track-renames 2026/02/03 04:23:03 INFO : drime root 'rclone-test-tavenol5qebu': Finished making map for --track-renames 2026/02/03 04:23:03 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:23:03 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for renames to finish 2026/02/03 04:23:04 ERROR : sub/yam: Couldn't move: object not found 2026/02/03 04:23:04 DEBUG : yam: Failed to rename to "sub/yam": object not found 2026/02/03 04:23:04 DEBUG : yam: Need to transfer - No matching file found at Destination 2026/02/03 04:23:04 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:23:07 DEBUG : yam: size = 11 OK 2026/02/03 04:23:07 INFO : yam: Copied (new) 2026/02/03 04:23:07 ERROR : drime root 'rclone-test-tavenol5qebu': not deleting files as there were IO errors 2026/02/03 04:23:07 ERROR : drime root 'rclone-test-tavenol5qebu': not deleting directories as there were IO errors sync_test.go:1698: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1698 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf run.go:130: removing dir "sub" failed - try 1/3: directory not empty run.go:130: removing dir "sub" failed - try 2/3: directory not empty run.go:130: removing dir "sub" failed - try 3/3: directory not empty run.go:133: removing dir "sub" 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: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 /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:1698 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: listing wrong, want got yam (11) 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 /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:1698 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: Unexpected file "yam" 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:1698 Error: Not equal: expected: []string{} actual : []string{"sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "sub" } Test: TestSyncWithTrackRenamesStrategyLeaf Messages: directories --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (23.43s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:23:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 04:23:22 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/03 04:23:22 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:23:22 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:23:27 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 04:23:27 INFO : sub dir/hello world: Copied (new) 2026/02/03 04:23:27 INFO : sub dir/hello world: Deleted 2026/02/03 04:23:27 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/03 04:23:27 INFO : nested/sub dir/file: Copied (new) 2026/02/03 04:23:27 INFO : nested/sub dir/file: Deleted 2026/02/03 04:23:27 ERROR : Local file system at /tmp/rclone2346354057: 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 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 run.go:130: removing dir "nested/sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "nested/sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "nested/sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "nested/sub dir" failed: directory not empty run.go:130: removing dir "nested" failed - try 1/3: directory not empty run.go:130: removing dir "nested" failed - try 2/3: directory not empty run.go:130: removing dir "nested" failed - try 3/3: directory not empty run.go:133: removing dir "nested" 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 /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:1819 Error: Not equal: expected: []string{} actual : []string{"nested", "nested/sub dir", "sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,5 @@ -([]string) { +([]string) (len=3) { + (string) (len=6) "nested", + (string) (len=14) "nested/sub dir", + (string) (len=7) "sub dir" } Test: TestMoveWithDeleteEmptySrcDirs Messages: directories --- FAIL: TestMoveWithDeleteEmptySrcDirs (30.54s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:23:52 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/03 04:23:53 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/02/03 04:23:53 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:23:53 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:23:56 DEBUG : sub dir/hello world: size = 11 OK 2026/02/03 04:23:56 INFO : sub dir/hello world: Copied (new) 2026/02/03 04:23:56 INFO : sub dir/hello world: Deleted 2026/02/03 04:23:56 DEBUG : nested/sub dir/file: size = 6 OK 2026/02/03 04:23:56 INFO : nested/sub dir/file: Copied (new) 2026/02/03 04:23:56 INFO : nested/sub dir/file: 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:1851 Error: Should be true Test: TestMoveWithoutDeleteEmptySrcDirs Messages: listing wrong, want nested/sub dir/file (6), sub dir/hello world (11) got nested/sub dir/file (6) 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:1851 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMoveWithoutDeleteEmptySrcDirs Messages: 1 objects not found 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 run.go:130: removing dir "nested/sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "nested" failed - try 1/3: directory not empty run.go:130: removing dir "nested" failed - try 2/3: directory not empty run.go:130: removing dir "nested" failed - try 3/3: directory not empty run.go:133: removing dir "nested" 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{"nested", "sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=6) "nested", + (string) (len=7) "sub dir" } Test: TestMoveWithoutDeleteEmptySrcDirs Messages: directories --- FAIL: TestMoveWithoutDeleteEmptySrcDirs (34.91s) === RUN TestMoveWithIgnoreExisting run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:24:27 DEBUG : existing-b: Need to transfer - File not found at Destination 2026/02/03 04:24:27 DEBUG : existing: Need to transfer - File not found at Destination 2026/02/03 04:24:27 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:24:27 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:24:30 DEBUG : existing-b: size = 6 OK 2026/02/03 04:24:30 INFO : existing-b: Copied (new) 2026/02/03 04:24:30 INFO : existing-b: Deleted 2026/02/03 04:24:31 DEBUG : existing: size = 6 OK 2026/02/03 04:24:31 INFO : existing: Copied (new) 2026/02/03 04:24:31 INFO : existing: 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/run.go:365 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1873 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (6), existing-b (6) got fstest.go:203: Not found "existing" fstest.go:203: Not found "existing-b" 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:1873 Error: Not equal: expected: 0 actual : 2 Test: TestMoveWithIgnoreExisting Messages: 2 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:1873 Error: Not equal: expected: []string{} actual : []string{"nested", "sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=6) "nested", + (string) (len=7) "sub dir" } Test: TestMoveWithIgnoreExisting Messages: directories 2026/02/03 04:24:41 DEBUG : existing: Destination exists, skipping 2026/02/03 04:24:41 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2026/02/03 04:24:41 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for checks to finish 2026/02/03 04:24:41 DEBUG : drime root 'rclone-test-tavenol5qebu': Waiting for transfers to finish 2026/02/03 04:24:41 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{"nested", "sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=6) "nested", + (string) (len=7) "sub dir" } Test: TestMoveWithIgnoreExisting Messages: 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/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{"nested", "sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=6) "nested", + (string) (len=7) "sub dir" } Test: TestMoveWithIgnoreExisting Messages: directories --- FAIL: TestMoveWithIgnoreExisting (36.81s) === RUN TestServerSideMove run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:25:03 DEBUG : Creating backend with remote "TestDrime:rclone-test-poceqag6qapo" 2026/02/03 04:25:03 DEBUG : Config file has changed externally - reloading sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-tavenol5qebu' -> drime root 'rclone-test-poceqag6qapo' 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:1741 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1912 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato3 (60) got fstest.go:203: Not found "empty space" fstest.go:203: Not found "potato3" 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:1912 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMove Messages: 2 objects not found 2026/02/03 04:25:30 DEBUG : drime root 'rclone-test-poceqag6qapo': Using server-side directory move 2026/02/03 04:25:30 INFO : drime root 'rclone-test-poceqag6qapo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/03 04:25:30 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 04:25:30 DEBUG : empty space: size = 1 OK 2026/02/03 04:25:30 DEBUG : empty space: Sizes identical 2026/02/03 04:25:30 DEBUG : empty space: Unchanged skipping 2026/02/03 04:25:30 DEBUG : potato3: size = 68 (drime root 'rclone-test-tavenol5qebu') 2026/02/03 04:25:30 DEBUG : potato3: size = 60 (drime root 'rclone-test-poceqag6qapo') 2026/02/03 04:25:30 DEBUG : potato3: Sizes differ 2026/02/03 04:25:30 DEBUG : drime root 'rclone-test-poceqag6qapo': Waiting for checks to finish 2026/02/03 04:25:31 INFO : potato3: Deleted 2026/02/03 04:25:31 INFO : empty space: Deleted 2026/02/03 04:25:31 DEBUG : drime root 'rclone-test-poceqag6qapo': Waiting for transfers to finish 2026/02/03 04:25:31 ERROR : potato2: Couldn't move: object not found 2026/02/03 04:25:31 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 04:25:31 DEBUG : drime root 'rclone-test-poceqag6qapo': Purge remote 2026/02/03 04:25:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:25:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 04:25:32 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:25:32 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 04:25:33 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:25:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 04:25:33 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:25:33 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 04:25:33 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:25:33 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 04:25:34 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:25:34 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 04:25:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:25:34 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 04:25:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:25:35 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 04:25:36 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:25:36 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 04:25:39 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 04:25:39 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "empty space" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 04:25:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:25:44 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 04:25:54 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:26:14 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:26:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:26:54 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:27:14 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:27:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:27:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:28:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:28:34 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 04:28:54 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:29:14 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:29:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:29:54 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:30:14 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:30:34 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:30:54 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:31:14 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:31:34 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:31:54 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 04:32:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:32:34 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:32:54 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:33:14 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:33:34 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:33:54 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:34:14 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:34:34 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:34:54 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:35:14 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 04:35:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:35:54 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:36:14 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:36:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:36:55 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:37:14 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:37:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:37:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:38:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:38:34 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 04:38:54 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:39:14 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:39:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:39:55 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:40:14 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:40:34 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:40:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:41:15 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:41:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:41:55 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 04:42:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:42:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:42:55 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:43:15 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:43:35 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:43:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:44:15 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:44:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:44:55 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:45:15 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 04:45:35 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:45:55 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:46:15 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:46:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:46:55 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:47:15 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:47:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:47:55 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:48:15 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:48:35 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 04:48:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:49:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:49:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:49:55 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:50:15 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:50:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:50:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:51:15 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:51:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:51:55 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 04:52:15 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestServerSideMove (1631.77s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "drime root 'rclone-test-tavenol5qebu'", Local "Local file system at /tmp/rclone2346354057", Modify Window "876000h0m0s" 2026/02/03 04:52:15 DEBUG : Creating backend with remote "TestDrime:rclone-test-pokovol1diwa" 2026/02/03 04:52:15 DEBUG : Config file has changed externally - reloading 2026/02/03 04:52:35 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 04:52:50 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 04:52:50 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 04:52:54 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 04:52:54 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 04:52:57 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 04:52:57 DEBUG : pacer: Reducing sleep to 78.125ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 04:52:58 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 04:52:59 DEBUG : pacer: Reducing sleep to 19.53125ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 04:53:01 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 7s just to make sure sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-tavenol5qebu' -> drime root 'rclone-test-pokovol1diwa' 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: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 "empty space" fstest.go:203: Not found "potato3" 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 04:53:23 DEBUG : empty space: Excluded (Size Filter) 2026/02/03 04:53:23 DEBUG : empty space: Excluded 2026/02/03 04:53:23 DEBUG : empty space: Excluded (Size Filter) 2026/02/03 04:53:23 DEBUG : empty space: Excluded 2026/02/03 04:53:23 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/03 04:53:23 DEBUG : potato3: size = 68 (drime root 'rclone-test-tavenol5qebu') 2026/02/03 04:53:23 DEBUG : potato3: size = 60 (drime root 'rclone-test-pokovol1diwa') 2026/02/03 04:53:23 DEBUG : potato3: Sizes differ 2026/02/03 04:53:23 DEBUG : drime root 'rclone-test-pokovol1diwa': Waiting for checks to finish 2026/02/03 04:53:23 DEBUG : drime root 'rclone-test-pokovol1diwa': Waiting for transfers to finish 2026/02/03 04:53:23 INFO : potato3: Deleted 2026/02/03 04:53:24 ERROR : potato2: Couldn't move: object not found 2026/02/03 04:53:24 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 04:53:24 DEBUG : drime root 'rclone-test-pokovol1diwa': Purge remote 2026/02/03 04:53:25 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:53:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 04:53:25 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:53:25 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 04:53:26 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:53:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 04:53:26 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:53:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 04:53:26 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:53:26 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 04:53:26 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:53:26 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 04:53:27 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:53:27 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 04:53:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:53:28 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 04:53:29 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:53:29 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 04:53:32 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 04:53:32 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 04:53:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:53:37 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 04:53:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:54:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:54:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:54:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:55:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:55:27 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:55:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:56:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:56:27 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 04:56:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 04:57:07 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 04:57:27 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 04:57:47 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 04:58:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 04:58:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 04:58:47 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 04:59:07 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 04:59:27 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 04:59:47 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 05:00:07 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 05:00:27 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 05:00:47 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 05:01:07 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 05:01:27 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 05:01:47 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 05:02:07 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 05:02:27 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 05:02:47 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 05:03:07 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 05:03:28 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 05:03:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 05:04:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 05:04:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 05:04:48 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 05:05:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 05:05:28 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 05:05:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 05:06:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 05:06:28 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 05:06:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 05:07:08 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 05:07:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 05:07:27 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n

500 Internal Server Error

\r\n
nginx
\r\n\r\n\r\n") 2026/02/03 05:07:48 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 05:08:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 05:08:28 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 05:08:48 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 05:09:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 05:09:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 05:09: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 05:10:08 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestServerSideMoveWithFilter (1072.58s) FAIL 2026/02/03 05:10:08 DEBUG : drime root 'rclone-test-tavenol5qebu': Purge remote 2026/02/03 05:10:28 DEBUG : pacer: Reducing sleep to 5s "./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestCopy|TestCopyAfterDelete|TestCopyCheckFirst|TestCopyDeleteBefore|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyWithFilesFromAndNoTraverse|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterAddingAFile|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncBasedOnCheckSum|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncEmptyDirectories|TestSyncIgnoreErrors|TestSyncIgnoreExisting|TestSyncIgnoreTimes|TestSyncNoEmptyDirectories|TestSyncSetDelayedModTimes|TestSyncSizeOnly|TestSyncWithExclude|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 1h17m19.053564166s (try 2/5): exit status 1: Failed [TestCopy TestCopyNoTraverse TestCopyCheckFirst TestCopyWithFilesFromAndNoTraverse TestCopyEmptyDirectories TestCopyNoEmptyDirectories TestSyncEmptyDirectories TestSyncNoEmptyDirectories TestServerSideCopy TestCopyOverSelf TestServerSideCopyOverSelf TestMoveOverSelf TestServerSideMoveOverSelf TestCopyAfterDelete TestSyncIgnoreTimes TestSyncIgnoreExisting TestSyncIgnoreErrors TestSyncAfterAddingAFile TestSyncAfterRemovingAFileAndAddingAFile TestSyncAfterRemovingAFileAndAddingAFileSubDir TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors TestSyncDeleteDuring TestSyncDeleteBefore TestCopyDeleteBefore TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestMoveWithoutDeleteEmptySrcDirs TestMoveWithIgnoreExisting TestServerSideMove TestServerSideMoveWithFilter]